{"level":"warn","ts":"2026-02-20T18:22:00.984851Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-20T18:22:00.984936Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-02-20T18:22:00.985Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-02-20T18:22:00.985011Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-20T18:22:00.985025Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-20T18:22:00.985619Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-02-20T18:22:00.985711Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-02-20T18:22:01.363299Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"377.387894ms"} {"level":"info","ts":"2026-02-20T18:22:01.376114Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-02-20T18:22:01.376208Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-02-20T18:22:01.376233Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-02-20T18:22:01.376255Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-02-20T18:22:01.376268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-02-20T18:22:01.376307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-02-20T18:22:01.388244Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-02-20T18:22:01.389764Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-02-20T18:22:01.391023Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-02-20T18:22:01.39305Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-02-20T18:22:01.393246Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-02-20T18:22:01.393366Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-20T18:22:01.393987Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-20T18:22:01.394011Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-02-20T18:22:01.394931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-02-20T18:22:01.395482Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-02-20T18:22:01.39908Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-02-20T18:22:01.399291Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-02-20T18:22:01.39932Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-02-20T18:22:01.399375Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-20T18:22:01.399387Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-02-20T18:22:01.977337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-02-20T18:22:01.977417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-02-20T18:22:01.97747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-02-20T18:22:01.977491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-02-20T18:22:01.977509Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-20T18:22:01.977526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-02-20T18:22:01.97754Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-02-20T18:22:01.978692Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-02-20T18:22:01.978878Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T18:22:01.978949Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-02-20T18:22:01.979072Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:22:01.979032Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-02-20T18:22:01.979134Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-02-20T18:22:01.980613Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:22:01.980701Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:22:01.980733Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-02-20T18:22:01.982141Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-02-20T18:22:01.982385Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-02-20T18:22:03.415897Z","caller":"traceutil/trace.go:171","msg":"trace[1023577195] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"138.240201ms","start":"2026-02-20T18:22:03.277631Z","end":"2026-02-20T18:22:03.415871Z","steps":["trace[1023577195] 'process raft request' (duration: 137.363293ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:03.415919Z","caller":"traceutil/trace.go:171","msg":"trace[100860459] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"138.17904ms","start":"2026-02-20T18:22:03.277715Z","end":"2026-02-20T18:22:03.415894Z","steps":["trace[100860459] 'process raft request' (duration: 138.030159ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:03.416053Z","caller":"traceutil/trace.go:171","msg":"trace[574725413] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"137.944338ms","start":"2026-02-20T18:22:03.278071Z","end":"2026-02-20T18:22:03.416015Z","steps":["trace[574725413] 'process raft request' (duration: 137.851797ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:03.416131Z","caller":"traceutil/trace.go:171","msg":"trace[528364705] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"129.73913ms","start":"2026-02-20T18:22:03.28638Z","end":"2026-02-20T18:22:03.41612Z","steps":["trace[528364705] 'process raft request' (duration: 129.667109ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:03.416155Z","caller":"traceutil/trace.go:171","msg":"trace[2003774952] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"138.334232ms","start":"2026-02-20T18:22:03.277791Z","end":"2026-02-20T18:22:03.416125Z","steps":["trace[2003774952] 'process raft request' (duration: 138.0958ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:03.415955Z","caller":"traceutil/trace.go:171","msg":"trace[105749539] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"138.15266ms","start":"2026-02-20T18:22:03.27777Z","end":"2026-02-20T18:22:03.415922Z","steps":["trace[105749539] 'process raft request' (duration: 138.061929ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:03.416263Z","caller":"traceutil/trace.go:171","msg":"trace[1507940922] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"129.872011ms","start":"2026-02-20T18:22:03.286331Z","end":"2026-02-20T18:22:03.416203Z","steps":["trace[1507940922] 'process raft request' (duration: 129.642179ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:03.416143Z","caller":"traceutil/trace.go:171","msg":"trace[584515504] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:7; }","duration":"123.296046ms","start":"2026-02-20T18:22:03.292796Z","end":"2026-02-20T18:22:03.416092Z","steps":["trace[584515504] 'read index received' (duration: 122.209306ms)","trace[584515504] 'applied index is now lower than readState.Index' (duration: 1.08556ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:22:03.416499Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.072733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-20T18:22:03.416609Z","caller":"traceutil/trace.go:171","msg":"trace[2136160694] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"142.215584ms","start":"2026-02-20T18:22:03.274379Z","end":"2026-02-20T18:22:03.416594Z","steps":["trace[2136160694] 'agreement among raft nodes before linearized reading' (duration: 141.884381ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:03.420606Z","caller":"traceutil/trace.go:171","msg":"trace[974202912] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"124.562876ms","start":"2026-02-20T18:22:03.295995Z","end":"2026-02-20T18:22:03.420558Z","steps":["trace[974202912] 'process raft request' (duration: 124.151753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:03.420793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.274831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-02-20T18:22:03.420837Z","caller":"traceutil/trace.go:171","msg":"trace[1099086502] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:12; }","duration":"113.347271ms","start":"2026-02-20T18:22:03.307476Z","end":"2026-02-20T18:22:03.420823Z","steps":["trace[1099086502] 'agreement among raft nodes before linearized reading' (duration: 113.18343ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:05.980457Z","caller":"traceutil/trace.go:171","msg":"trace[1771161553] transaction","detail":"{read_only:false; response_revision:208; number_of_response:1; }","duration":"248.292094ms","start":"2026-02-20T18:22:05.732136Z","end":"2026-02-20T18:22:05.980429Z","steps":["trace[1771161553] 'process raft request' (duration: 168.800003ms)","trace[1771161553] 'compare' (duration: 79.294199ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:22:06.434346Z","caller":"traceutil/trace.go:171","msg":"trace[588388078] transaction","detail":"{read_only:false; response_revision:209; number_of_response:1; }","duration":"106.805112ms","start":"2026-02-20T18:22:06.327514Z","end":"2026-02-20T18:22:06.434319Z","steps":["trace[588388078] 'process raft request' (duration: 106.654371ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:11.42242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"679.987214ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:22:11.422556Z","caller":"traceutil/trace.go:171","msg":"trace[645339627] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:299; }","duration":"632.961923ms","start":"2026-02-20T18:22:10.789577Z","end":"2026-02-20T18:22:11.422539Z","steps":["trace[645339627] 'read index received' (duration: 38.291µs)","trace[645339627] 'applied index is now lower than readState.Index' (duration: 632.922342ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:22:11.422589Z","caller":"traceutil/trace.go:171","msg":"trace[1061442252] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"707.672599ms","start":"2026-02-20T18:22:10.714877Z","end":"2026-02-20T18:22:11.42255Z","steps":["trace[1061442252] 'process raft request' (duration: 27.06137ms)","trace[1061442252] 'compare' (duration: 679.851143ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:22:11.42268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:10.71485Z","time spent":"707.78746ms","remote":"127.0.0.1:43270","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T18:22:11.422751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.175114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-20T18:22:11.42284Z","caller":"traceutil/trace.go:171","msg":"trace[1677767787] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:289; }","duration":"633.326695ms","start":"2026-02-20T18:22:10.789502Z","end":"2026-02-20T18:22:11.422828Z","steps":["trace[1677767787] 'agreement among raft nodes before linearized reading' (duration: 633.154634ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:11.422914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:10.78948Z","time spent":"633.390467ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:22:11.42296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.427811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:22:11.423039Z","caller":"traceutil/trace.go:171","msg":"trace[1327456669] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:289; }","duration":"482.581612ms","start":"2026-02-20T18:22:10.940438Z","end":"2026-02-20T18:22:11.423019Z","steps":["trace[1327456669] 'agreement among raft nodes before linearized reading' (duration: 482.39763ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:11.423085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:10.940411Z","time spent":"482.664343ms","remote":"127.0.0.1:43336","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-02-20T18:22:11.991945Z","caller":"traceutil/trace.go:171","msg":"trace[102716264] transaction","detail":"{read_only:false; response_revision:291; number_of_response:1; }","duration":"564.340301ms","start":"2026-02-20T18:22:11.427582Z","end":"2026-02-20T18:22:11.991922Z","steps":["trace[102716264] 'process raft request' (duration: 564.27586ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:11.992256Z","caller":"traceutil/trace.go:171","msg":"trace[1043594401] linearizableReadLoop","detail":"{readStateIndex:301; appliedIndex:300; }","duration":"564.885815ms","start":"2026-02-20T18:22:11.427326Z","end":"2026-02-20T18:22:11.992212Z","steps":["trace[1043594401] 'read index received' (duration: 71.62µs)","trace[1043594401] 'applied index is now lower than readState.Index' (duration: 564.812285ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:22:11.992246Z","caller":"traceutil/trace.go:171","msg":"trace[1838927375] transaction","detail":"{read_only:false; response_revision:290; number_of_response:1; }","duration":"564.906235ms","start":"2026-02-20T18:22:11.427309Z","end":"2026-02-20T18:22:11.992215Z","steps":["trace[1838927375] 'process raft request' (duration: 487.696971ms)","trace[1838927375] 'compare' (duration: 76.7055ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:22:11.992324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:11.427534Z","time spent":"564.484372ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:22:11.992437Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:11.427287Z","time spent":"565.083147ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:22:11.992471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.989398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:22:11.992477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.50554ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-20T18:22:11.992485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.148867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-02-20T18:22:11.992509Z","caller":"traceutil/trace.go:171","msg":"trace[97726896] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:291; }","duration":"392.068599ms","start":"2026-02-20T18:22:11.600428Z","end":"2026-02-20T18:22:11.992496Z","steps":["trace[97726896] 'agreement among raft nodes before linearized reading' (duration: 392.015989ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:11.992541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:11.600404Z","time spent":"392.13087ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:22:11.992545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"449.210421ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-20T18:22:11.992541Z","caller":"traceutil/trace.go:171","msg":"trace[1247598560] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:291; }","duration":"565.247029ms","start":"2026-02-20T18:22:11.427276Z","end":"2026-02-20T18:22:11.992523Z","steps":["trace[1247598560] 'agreement among raft nodes before linearized reading' (duration: 565.084387ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:11.992569Z","caller":"traceutil/trace.go:171","msg":"trace[1036736589] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:291; }","duration":"449.285851ms","start":"2026-02-20T18:22:11.543278Z","end":"2026-02-20T18:22:11.992564Z","steps":["trace[1036736589] 'agreement among raft nodes before linearized reading' (duration: 449.248861ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:11.992581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:11.427256Z","time spent":"565.317709ms","remote":"127.0.0.1:43270","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-02-20T18:22:11.99259Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:11.543247Z","time spent":"449.338692ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-02-20T18:22:11.992512Z","caller":"traceutil/trace.go:171","msg":"trace[457497497] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:291; }","duration":"456.57265ms","start":"2026-02-20T18:22:11.535931Z","end":"2026-02-20T18:22:11.992503Z","steps":["trace[457497497] 'agreement among raft nodes before linearized reading' (duration: 456.49768ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:11.992629Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:11.535913Z","time spent":"456.707491ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T18:22:17.909707Z","caller":"traceutil/trace.go:171","msg":"trace[654984749] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"822.383421ms","start":"2026-02-20T18:22:17.087297Z","end":"2026-02-20T18:22:17.90968Z","steps":["trace[654984749] 'process raft request' (duration: 822.231494ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:17.909862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:17.087282Z","time spent":"822.501607ms","remote":"127.0.0.1:43246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4241,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:22:18.088384Z","caller":"traceutil/trace.go:171","msg":"trace[2100563450] linearizableReadLoop","detail":"{readStateIndex:379; appliedIndex:377; }","duration":"645.732948ms","start":"2026-02-20T18:22:17.442615Z","end":"2026-02-20T18:22:18.088348Z","steps":["trace[2100563450] 'read index received' (duration: 466.915256ms)","trace[2100563450] 'applied index is now lower than readState.Index' (duration: 178.816572ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:22:18.088596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.975588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:22:18.088643Z","caller":"traceutil/trace.go:171","msg":"trace[1831435539] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:366; }","duration":"646.096784ms","start":"2026-02-20T18:22:17.442538Z","end":"2026-02-20T18:22:18.088635Z","steps":["trace[1831435539] 'agreement among raft nodes before linearized reading' (duration: 646.00519ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:18.088662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:17.442517Z","time spent":"646.140676ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:22:18.088587Z","caller":"traceutil/trace.go:171","msg":"trace[354213346] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"745.650246ms","start":"2026-02-20T18:22:17.342914Z","end":"2026-02-20T18:22:18.088564Z","steps":["trace[354213346] 'process raft request' (duration: 737.96324ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:18.088811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:17.342889Z","time spent":"745.804232ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:22:18.088804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.862558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-scheduler-instance\" ","response":"range_response_count:1 size:4256"} {"level":"info","ts":"2026-02-20T18:22:18.088987Z","caller":"traceutil/trace.go:171","msg":"trace[1344831679] range","detail":"{range_begin:/registry/pods/kube-system/kube-scheduler-instance; range_end:; response_count:1; response_revision:366; }","duration":"175.099308ms","start":"2026-02-20T18:22:17.913834Z","end":"2026-02-20T18:22:18.088933Z","steps":["trace[1344831679] 'agreement among raft nodes before linearized reading' (duration: 174.771984ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:20.995781Z","caller":"traceutil/trace.go:171","msg":"trace[774766213] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"893.395041ms","start":"2026-02-20T18:22:20.102363Z","end":"2026-02-20T18:22:20.995758Z","steps":["trace[774766213] 'process raft request' (duration: 893.360049ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:20.995922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:20.102353Z","time spent":"893.504115ms","remote":"127.0.0.1:43336","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-02-20T18:22:20.996226Z","caller":"traceutil/trace.go:171","msg":"trace[236356454] transaction","detail":"{read_only:false; response_revision:374; number_of_response:1; }","duration":"894.198294ms","start":"2026-02-20T18:22:20.101991Z","end":"2026-02-20T18:22:20.996189Z","steps":["trace[236356454] 'process raft request' (duration: 893.624099ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:20.996427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:20.101972Z","time spent":"894.348751ms","remote":"127.0.0.1:43336","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-02-20T18:22:20.99696Z","caller":"traceutil/trace.go:171","msg":"trace[2122496630] linearizableReadLoop","detail":"{readStateIndex:390; appliedIndex:389; }","duration":"488.220542ms","start":"2026-02-20T18:22:20.508714Z","end":"2026-02-20T18:22:20.996934Z","steps":["trace[2122496630] 'read index received' (duration: 487.432658ms)","trace[2122496630] 'applied index is now lower than readState.Index' (duration: 787.284µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:22:20.997068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.329377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:22:20.997097Z","caller":"traceutil/trace.go:171","msg":"trace[1180173087] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:376; }","duration":"488.430591ms","start":"2026-02-20T18:22:20.508658Z","end":"2026-02-20T18:22:20.997089Z","steps":["trace[1180173087] 'agreement among raft nodes before linearized reading' (duration: 488.346318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:20.99713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:20.50863Z","time spent":"488.490124ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:22:20.997304Z","caller":"traceutil/trace.go:171","msg":"trace[294210394] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"893.3897ms","start":"2026-02-20T18:22:20.103764Z","end":"2026-02-20T18:22:20.997154Z","steps":["trace[294210394] 'process raft request' (duration: 893.066156ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:20.997833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:20.103748Z","time spent":"893.977335ms","remote":"127.0.0.1:43336","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-02-20T18:22:23.337225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.65248ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:22:23.337351Z","caller":"traceutil/trace.go:171","msg":"trace[1545109243] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"331.97421ms","start":"2026-02-20T18:22:23.005351Z","end":"2026-02-20T18:22:23.337325Z","steps":["trace[1545109243] 'process raft request' (duration: 159.104751ms)","trace[1545109243] 'compare' (duration: 172.40333ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:22:23.337446Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:23.00534Z","time spent":"332.063384ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:22:23.592589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.637942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:22:23.592683Z","caller":"traceutil/trace.go:171","msg":"trace[1254125685] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:381; }","duration":"150.784578ms","start":"2026-02-20T18:22:23.441877Z","end":"2026-02-20T18:22:23.592661Z","steps":["trace[1254125685] 'range keys from in-memory index tree' (duration: 150.556408ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:24.023999Z","caller":"traceutil/trace.go:171","msg":"trace[765495682] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"419.22245ms","start":"2026-02-20T18:22:23.60475Z","end":"2026-02-20T18:22:24.023973Z","steps":["trace[765495682] 'process raft request' (duration: 419.076974ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:24.024155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:23.604723Z","time spent":"419.349925ms","remote":"127.0.0.1:43494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4158,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:22:24.024606Z","caller":"traceutil/trace.go:171","msg":"trace[762646078] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"234.246242ms","start":"2026-02-20T18:22:23.790339Z","end":"2026-02-20T18:22:24.024585Z","steps":["trace[762646078] 'process raft request' (duration: 234.168539ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:27.404241Z","caller":"traceutil/trace.go:171","msg":"trace[1007416974] linearizableReadLoop","detail":"{readStateIndex:435; appliedIndex:434; }","duration":"334.889426ms","start":"2026-02-20T18:22:27.069321Z","end":"2026-02-20T18:22:27.404211Z","steps":["trace[1007416974] 'read index received' (duration: 334.675518ms)","trace[1007416974] 'applied index is now lower than readState.Index' (duration: 212.578µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:22:27.404318Z","caller":"traceutil/trace.go:171","msg":"trace[645520335] transaction","detail":"{read_only:false; response_revision:419; number_of_response:1; }","duration":"335.015072ms","start":"2026-02-20T18:22:27.069279Z","end":"2026-02-20T18:22:27.404294Z","steps":["trace[645520335] 'process raft request' (duration: 334.792913ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:27.404428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.097934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-02-20T18:22:27.404462Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:27.069238Z","time spent":"335.129656ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:22:27.404489Z","caller":"traceutil/trace.go:171","msg":"trace[27845108] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:419; }","duration":"335.205469ms","start":"2026-02-20T18:22:27.069268Z","end":"2026-02-20T18:22:27.404473Z","steps":["trace[27845108] 'agreement among raft nodes before linearized reading' (duration: 335.099375ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:27.404601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:27.069239Z","time spent":"335.349104ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:22:30.126508Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:30.626741Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:31.127102Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:31.626983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000673644s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-20T18:22:31.627054Z","caller":"traceutil/trace.go:171","msg":"trace[705962914] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000790168s","start":"2026-02-20T18:22:29.626248Z","end":"2026-02-20T18:22:31.627038Z","steps":["trace[705962914] 'agreement among raft nodes before linearized reading' (duration: 2.000698215s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:31.627095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:29.626236Z","time spent":"2.000847021s","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:22:31.627237Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:32.127563Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:32.627994Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:33.128994Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:33.629017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000230364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-20T18:22:33.629076Z","caller":"traceutil/trace.go:171","msg":"trace[834973610] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000328198s","start":"2026-02-20T18:22:31.628735Z","end":"2026-02-20T18:22:33.629063Z","steps":["trace[834973610] 'agreement among raft nodes before linearized reading' (duration: 2.000247555s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:33.629107Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:31.628722Z","time spent":"2.00037847s","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:22:33.629202Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194366,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:33.734146Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.108882232s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:22:33.74519Z","caller":"traceutil/trace.go:171","msg":"trace[80589017] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"4.11996474s","start":"2026-02-20T18:22:29.625208Z","end":"2026-02-20T18:22:33.745173Z","steps":["trace[80589017] 'process raft request' (duration: 4.119864616s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:33.745328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:29.6252Z","time spent":"4.120047513s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:22:35.175123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999403486s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-20T18:22:35.175225Z","caller":"traceutil/trace.go:171","msg":"trace[1177926148] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; }","duration":"4.999563441s","start":"2026-02-20T18:22:30.175642Z","end":"2026-02-20T18:22:35.175206Z","steps":["trace[1177926148] 'agreement among raft nodes before linearized reading' (duration: 4.999433166s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:35.175276Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:30.175622Z","time spent":"4.999643594s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} 2026/02/20 18:22:35 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:22:35.182925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999947285s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-20T18:22:35.182993Z","caller":"traceutil/trace.go:171","msg":"trace[1930176646] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000054969s","start":"2026-02-20T18:22:33.182922Z","end":"2026-02-20T18:22:35.182977Z","steps":["trace[1930176646] 'agreement among raft nodes before linearized reading' (duration: 1.999980787s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:35.183072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:33.182903Z","time spent":"2.000157732s","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 18:22:35 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:22:35.381383Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.646731496s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-20T18:22:35.384018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.29299ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:22:35.384126Z","caller":"traceutil/trace.go:171","msg":"trace[1355184463] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:424; }","duration":"376.426925ms","start":"2026-02-20T18:22:35.007685Z","end":"2026-02-20T18:22:35.384112Z","steps":["trace[1355184463] 'range keys from in-memory index tree' (duration: 376.243958ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:35.384646Z","caller":"traceutil/trace.go:171","msg":"trace[1959521374] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"5.758040383s","start":"2026-02-20T18:22:29.626595Z","end":"2026-02-20T18:22:35.384635Z","steps":["trace[1959521374] 'process raft request' (duration: 5.755069246s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:35.384726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:29.626587Z","time spent":"5.758089734s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:22:35.384816Z","caller":"traceutil/trace.go:171","msg":"trace[599613737] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"5.758087474s","start":"2026-02-20T18:22:29.626668Z","end":"2026-02-20T18:22:35.384755Z","steps":["trace[599613737] 'process raft request' (duration: 5.757903577s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:35.384997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:29.62665Z","time spent":"5.75824848s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:22:35.388464Z","caller":"traceutil/trace.go:171","msg":"trace[551567252] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"1.088909328s","start":"2026-02-20T18:22:34.299538Z","end":"2026-02-20T18:22:35.388448Z","steps":["trace[551567252] 'process raft request' (duration: 1.088880657s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:35.388845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:34.299507Z","time spent":"1.08924169s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:22:35.388479Z","caller":"traceutil/trace.go:171","msg":"trace[1548879259] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:439; }","duration":"5.762175492s","start":"2026-02-20T18:22:29.626278Z","end":"2026-02-20T18:22:35.388453Z","steps":["trace[1548879259] 'read index received' (duration: 4.118747823s)","trace[1548879259] 'applied index is now lower than readState.Index' (duration: 1.643422849s)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:22:35.388541Z","caller":"traceutil/trace.go:171","msg":"trace[1399974813] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"1.750501905s","start":"2026-02-20T18:22:33.638029Z","end":"2026-02-20T18:22:35.38853Z","steps":["trace[1399974813] 'process raft request' (duration: 1.750261016s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:35.38983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.752898043s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:22:35.389886Z","caller":"traceutil/trace.go:171","msg":"trace[313818805] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"1.752979166s","start":"2026-02-20T18:22:33.636889Z","end":"2026-02-20T18:22:35.389868Z","steps":["trace[313818805] 'agreement among raft nodes before linearized reading' (duration: 1.752863601s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:35.389927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:33.636856Z","time spent":"1.753065058s","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:22:35.389916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:33.63801Z","time spent":"1.75173143s","remote":"127.0.0.1:43154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T18:22:35.390447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.507197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T18:22:35.390508Z","caller":"traceutil/trace.go:171","msg":"trace[1894042729] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:428; }","duration":"491.60481ms","start":"2026-02-20T18:22:34.898895Z","end":"2026-02-20T18:22:35.3905Z","steps":["trace[1894042729] 'agreement among raft nodes before linearized reading' (duration: 491.115413ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:35.390546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:34.898876Z","time spent":"491.664573ms","remote":"127.0.0.1:43096","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.62\" "} {"level":"info","ts":"2026-02-20T18:22:37.570343Z","caller":"traceutil/trace.go:171","msg":"trace[1536957817] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"171.754781ms","start":"2026-02-20T18:22:37.398561Z","end":"2026-02-20T18:22:37.570316Z","steps":["trace[1536957817] 'process raft request' (duration: 171.369327ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:38.121503Z","caller":"traceutil/trace.go:171","msg":"trace[1441306066] linearizableReadLoop","detail":"{readStateIndex:453; appliedIndex:451; }","duration":"678.44652ms","start":"2026-02-20T18:22:37.443029Z","end":"2026-02-20T18:22:38.121476Z","steps":["trace[1441306066] 'read index received' (duration: 126.814665ms)","trace[1441306066] 'applied index is now lower than readState.Index' (duration: 551.630065ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:22:38.121872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.491979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-20T18:22:38.121886Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"678.831313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:22:38.121794Z","caller":"traceutil/trace.go:171","msg":"trace[874251195] transaction","detail":"{read_only:false; response_revision:435; number_of_response:1; }","duration":"719.630713ms","start":"2026-02-20T18:22:37.401985Z","end":"2026-02-20T18:22:38.121616Z","steps":["trace[874251195] 'process raft request' (duration: 718.481763ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:38.121958Z","caller":"traceutil/trace.go:171","msg":"trace[963757700] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:435; }","duration":"364.594933ms","start":"2026-02-20T18:22:37.75734Z","end":"2026-02-20T18:22:38.121935Z","steps":["trace[963757700] 'agreement among raft nodes before linearized reading' (duration: 364.430737ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:38.122003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:37.757287Z","time spent":"364.707147ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T18:22:38.121995Z","caller":"traceutil/trace.go:171","msg":"trace[308555912] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:435; }","duration":"678.987179ms","start":"2026-02-20T18:22:37.442981Z","end":"2026-02-20T18:22:38.121968Z","steps":["trace[308555912] 'agreement among raft nodes before linearized reading' (duration: 678.835044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:38.12206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:37.442963Z","time spent":"679.083403ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:22:38.122199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:37.401962Z","time spent":"720.022107ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:22:43.865458Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194484,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:44.365971Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194484,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:44.638131Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.419142032s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:22:44.638674Z","caller":"traceutil/trace.go:171","msg":"trace[682939188] linearizableReadLoop","detail":"{readStateIndex:500; appliedIndex:499; }","duration":"1.273704216s","start":"2026-02-20T18:22:43.364936Z","end":"2026-02-20T18:22:44.63864Z","steps":["trace[682939188] 'read index received' (duration: 1.273429367s)","trace[682939188] 'applied index is now lower than readState.Index' (duration: 272.479µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:22:44.638798Z","caller":"traceutil/trace.go:171","msg":"trace[364722985] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"1.419845115s","start":"2026-02-20T18:22:43.218879Z","end":"2026-02-20T18:22:44.638724Z","steps":["trace[364722985] 'process raft request' (duration: 1.419504613s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:44.638946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.19592876s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:22:44.639004Z","caller":"traceutil/trace.go:171","msg":"trace[1022846179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:481; }","duration":"1.196058894s","start":"2026-02-20T18:22:43.442924Z","end":"2026-02-20T18:22:44.638983Z","steps":["trace[1022846179] 'agreement among raft nodes before linearized reading' (duration: 1.19594746s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:44.639043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:43.21886Z","time spent":"1.420024831s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:22:44.639056Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:43.442889Z","time spent":"1.196155877s","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:22:44.639017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.274031236s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4020"} {"level":"info","ts":"2026-02-20T18:22:44.639141Z","caller":"traceutil/trace.go:171","msg":"trace[1875575687] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:481; }","duration":"1.274202432s","start":"2026-02-20T18:22:43.364909Z","end":"2026-02-20T18:22:44.639111Z","steps":["trace[1875575687] 'agreement among raft nodes before linearized reading' (duration: 1.273866181s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:44.639198Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:43.364889Z","time spent":"1.274299965s","remote":"127.0.0.1:43244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4043,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-20T18:22:44.639154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.138303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-20T18:22:44.639283Z","caller":"traceutil/trace.go:171","msg":"trace[1806705837] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:481; }","duration":"410.35119ms","start":"2026-02-20T18:22:44.2289Z","end":"2026-02-20T18:22:44.639251Z","steps":["trace[1806705837] 'agreement among raft nodes before linearized reading' (duration: 410.064711ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:44.639353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:44.228866Z","time spent":"410.477024ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:22:44.639361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.047093741s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-20T18:22:44.639408Z","caller":"traceutil/trace.go:171","msg":"trace[2565407] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:481; }","duration":"1.047203025s","start":"2026-02-20T18:22:43.592191Z","end":"2026-02-20T18:22:44.639394Z","steps":["trace[2565407] 'agreement among raft nodes before linearized reading' (duration: 1.04707911s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:44.639444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:43.592161Z","time spent":"1.047274587s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:22:44.639526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.934831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:22:44.639577Z","caller":"traceutil/trace.go:171","msg":"trace[1353489481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:481; }","duration":"412.009804ms","start":"2026-02-20T18:22:44.227552Z","end":"2026-02-20T18:22:44.639562Z","steps":["trace[1353489481] 'agreement among raft nodes before linearized reading' (duration: 411.907911ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:44.639614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:44.227537Z","time spent":"412.068666ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-20T18:22:52.784086Z","caller":"traceutil/trace.go:171","msg":"trace[2104322711] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"486.392729ms","start":"2026-02-20T18:22:52.297673Z","end":"2026-02-20T18:22:52.784066Z","steps":["trace[2104322711] 'process raft request' (duration: 486.179602ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:52.784197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:52.297661Z","time spent":"486.479761ms","remote":"127.0.0.1:43246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16799,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:22:53.060125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.424082ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:22:53.060413Z","caller":"traceutil/trace.go:171","msg":"trace[1020418364] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"230.483609ms","start":"2026-02-20T18:22:52.829901Z","end":"2026-02-20T18:22:53.060385Z","steps":["trace[1020418364] 'read index received' (duration: 63.233421ms)","trace[1020418364] 'applied index is now lower than readState.Index' (duration: 167.248588ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:22:53.060447Z","caller":"traceutil/trace.go:171","msg":"trace[2069725621] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"270.870129ms","start":"2026-02-20T18:22:52.78954Z","end":"2026-02-20T18:22:53.06041Z","steps":["trace[2069725621] 'process raft request' (duration: 103.652203ms)","trace[2069725621] 'compare' (duration: 166.231167ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:22:53.060571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.670884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:22:53.060619Z","caller":"traceutil/trace.go:171","msg":"trace[1715866509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:527; }","duration":"230.744776ms","start":"2026-02-20T18:22:52.829861Z","end":"2026-02-20T18:22:53.060606Z","steps":["trace[1715866509] 'agreement among raft nodes before linearized reading' (duration: 230.651063ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:53.060781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.932122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-20T18:22:53.060864Z","caller":"traceutil/trace.go:171","msg":"trace[1475538143] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:527; }","duration":"230.040815ms","start":"2026-02-20T18:22:52.830801Z","end":"2026-02-20T18:22:53.060842Z","steps":["trace[1475538143] 'agreement among raft nodes before linearized reading' (duration: 229.87027ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:53.060789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.20662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-20T18:22:53.060994Z","caller":"traceutil/trace.go:171","msg":"trace[641104232] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:527; }","duration":"230.506749ms","start":"2026-02-20T18:22:52.830456Z","end":"2026-02-20T18:22:53.060963Z","steps":["trace[641104232] 'agreement among raft nodes before linearized reading' (duration: 230.138468ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:53.688249Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194582,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:22:54.050331Z","caller":"traceutil/trace.go:171","msg":"trace[1035418202] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"984.741333ms","start":"2026-02-20T18:22:53.065561Z","end":"2026-02-20T18:22:54.050302Z","steps":["trace[1035418202] 'process raft request' (duration: 984.704682ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:22:54.050399Z","caller":"traceutil/trace.go:171","msg":"trace[974702213] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"984.798004ms","start":"2026-02-20T18:22:53.065562Z","end":"2026-02-20T18:22:54.05036Z","steps":["trace[974702213] 'process raft request' (duration: 984.569397ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:54.050478Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:53.065541Z","time spent":"984.861656ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:22:54.050677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:53.065543Z","time spent":"984.918788ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:22:54.952477Z","caller":"traceutil/trace.go:171","msg":"trace[1570946690] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:548; }","duration":"1.764251433s","start":"2026-02-20T18:22:53.188187Z","end":"2026-02-20T18:22:54.952438Z","steps":["trace[1570946690] 'read index received' (duration: 862.00021ms)","trace[1570946690] 'applied index is now lower than readState.Index' (duration: 902.249783ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:22:54.95273Z","caller":"traceutil/trace.go:171","msg":"trace[1047712820] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.88711198s","start":"2026-02-20T18:22:53.065587Z","end":"2026-02-20T18:22:54.952699Z","steps":["trace[1047712820] 'process raft request' (duration: 1.838007695s)","trace[1047712820] 'compare' (duration: 48.630691ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:22:54.952765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.512766408s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:22:54.953057Z","caller":"traceutil/trace.go:171","msg":"trace[166909499] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"1.513097428s","start":"2026-02-20T18:22:53.439937Z","end":"2026-02-20T18:22:54.953035Z","steps":["trace[166909499] 'agreement among raft nodes before linearized reading' (duration: 1.512769038s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:54.953103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:53.439916Z","time spent":"1.513175941s","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:22:54.952867Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.764661926s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:22:54.953286Z","caller":"traceutil/trace.go:171","msg":"trace[1829749233] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"1.76511917s","start":"2026-02-20T18:22:53.188136Z","end":"2026-02-20T18:22:54.953255Z","steps":["trace[1829749233] 'agreement among raft nodes before linearized reading' (duration: 1.764658646s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:54.95339Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:53.188114Z","time spent":"1.765239164s","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:22:54.952925Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:53.065557Z","time spent":"1.887241484s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:22:55.717211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"661.050677ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"info","ts":"2026-02-20T18:22:55.717305Z","caller":"traceutil/trace.go:171","msg":"trace[1264113139] linearizableReadLoop","detail":"{readStateIndex:552; appliedIndex:551; }","duration":"756.067177ms","start":"2026-02-20T18:22:54.961221Z","end":"2026-02-20T18:22:55.717288Z","steps":["trace[1264113139] 'read index received' (duration: 94.909138ms)","trace[1264113139] 'applied index is now lower than readState.Index' (duration: 661.156339ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:22:55.717349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:54.95441Z","time spent":"762.933171ms","remote":"127.0.0.1:43096","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-02-20T18:22:55.717405Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"756.184271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:22:55.717434Z","caller":"traceutil/trace.go:171","msg":"trace[1965593213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"756.246193ms","start":"2026-02-20T18:22:54.961177Z","end":"2026-02-20T18:22:55.717423Z","steps":["trace[1965593213] 'agreement among raft nodes before linearized reading' (duration: 756.189212ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:55.717463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:54.961162Z","time spent":"756.294754ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:22:55.717724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"653.081912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:22:55.717781Z","caller":"traceutil/trace.go:171","msg":"trace[932128850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"653.239417ms","start":"2026-02-20T18:22:55.064526Z","end":"2026-02-20T18:22:55.717765Z","steps":["trace[932128850] 'agreement among raft nodes before linearized reading' (duration: 653.057372ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:55.717819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:55.064497Z","time spent":"653.31386ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:22:56.55619Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:57.05628Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:57.557044Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:58.058012Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:58.440606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000480309s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-20T18:22:58.44066Z","caller":"traceutil/trace.go:171","msg":"trace[196048389] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000581262s","start":"2026-02-20T18:22:56.440067Z","end":"2026-02-20T18:22:58.440649Z","steps":["trace[196048389] 'agreement among raft nodes before linearized reading' (duration: 2.00051071s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:22:58.440688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:56.440048Z","time spent":"2.000635144s","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:22:58.559023Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:59.05956Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:59.560289Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194592,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:22:59.878824Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.159818023s","expected-duration":"1s"} {"level":"warn","ts":"2026-02-20T18:23:00.441281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000129206s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-20T18:23:00.441373Z","caller":"traceutil/trace.go:171","msg":"trace[2065993158] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.00025779s","start":"2026-02-20T18:22:58.441079Z","end":"2026-02-20T18:23:00.441337Z","steps":["trace[2065993158] 'agreement among raft nodes before linearized reading' (duration: 2.000167827s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:00.441423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:58.441053Z","time spent":"2.000361903s","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 18:23:00 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:23:00.720159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:55.72143Z","time spent":"4.998723654s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} 2026/02/20 18:23:00 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:23:00.961458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.082394654s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:23:00.961573Z","caller":"traceutil/trace.go:171","msg":"trace[275604839] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"5.24266165s","start":"2026-02-20T18:22:55.718893Z","end":"2026-02-20T18:23:00.961554Z","steps":["trace[275604839] 'process raft request' (duration: 4.160096491s)","trace[275604839] 'compare' (duration: 1.081991073s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:00.961635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:55.718846Z","time spent":"5.242757643s","remote":"127.0.0.1:43096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:23:01.139162Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.260167821s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:23:01.139658Z","caller":"traceutil/trace.go:171","msg":"trace[510455854] linearizableReadLoop","detail":"{readStateIndex:556; appliedIndex:552; }","duration":"5.084104542s","start":"2026-02-20T18:22:56.05553Z","end":"2026-02-20T18:23:01.139635Z","steps":["trace[510455854] 'read index received' (duration: 3.823438217s)","trace[510455854] 'applied index is now lower than readState.Index' (duration: 1.260665265s)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:01.139964Z","caller":"traceutil/trace.go:171","msg":"trace[1660246043] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"5.306646518s","start":"2026-02-20T18:22:55.833306Z","end":"2026-02-20T18:23:01.139953Z","steps":["trace[1660246043] 'process raft request' (duration: 5.306255957s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:01.140062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:55.83329Z","time spent":"5.306703219s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:23:01.140319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.084773441s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-20T18:23:01.140302Z","caller":"traceutil/trace.go:171","msg":"trace[251973712] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"2.138581212s","start":"2026-02-20T18:22:59.001685Z","end":"2026-02-20T18:23:01.140266Z","steps":["trace[251973712] 'process raft request' (duration: 2.137914044s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:01.140389Z","caller":"traceutil/trace.go:171","msg":"trace[22523290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:534; }","duration":"5.084856403s","start":"2026-02-20T18:22:56.055512Z","end":"2026-02-20T18:23:01.140368Z","steps":["trace[22523290] 'agreement among raft nodes before linearized reading' (duration: 5.084681998s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:01.140452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:56.055444Z","time spent":"5.084995847s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:23:01.140509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:59.00166Z","time spent":"2.138737716s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:23:01.45727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.291695ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-20T18:23:01.457647Z","caller":"traceutil/trace.go:171","msg":"trace[896321576] linearizableReadLoop","detail":"{readStateIndex:557; appliedIndex:556; }","duration":"317.905637ms","start":"2026-02-20T18:23:01.13972Z","end":"2026-02-20T18:23:01.457625Z","steps":["trace[896321576] 'read index received' (duration: 111.212521ms)","trace[896321576] 'applied index is now lower than readState.Index' (duration: 206.690646ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:01.457687Z","caller":"traceutil/trace.go:171","msg":"trace[1806148957] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"313.020232ms","start":"2026-02-20T18:23:01.14465Z","end":"2026-02-20T18:23:01.457671Z","steps":["trace[1806148957] 'process raft request' (duration: 312.899008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:01.457783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:01.144631Z","time spent":"313.093264ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:23:01.457902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.500556786s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-20T18:23:01.457946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.402086321s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:23:01.45795Z","caller":"traceutil/trace.go:171","msg":"trace[198413751] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:535; }","duration":"4.500639268s","start":"2026-02-20T18:22:56.957299Z","end":"2026-02-20T18:23:01.457938Z","steps":["trace[198413751] 'agreement among raft nodes before linearized reading' (duration: 4.500536925s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:01.457969Z","caller":"traceutil/trace.go:171","msg":"trace[619326914] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:535; }","duration":"5.402149453s","start":"2026-02-20T18:22:56.055814Z","end":"2026-02-20T18:23:01.457963Z","steps":["trace[619326914] 'agreement among raft nodes before linearized reading' (duration: 5.402087831s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:01.457974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:56.957284Z","time spent":"4.500685429s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:23:01.458449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.013073224s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:01.458804Z","caller":"traceutil/trace.go:171","msg":"trace[1530757347] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:535; }","duration":"1.013441814s","start":"2026-02-20T18:23:00.44532Z","end":"2026-02-20T18:23:01.458762Z","steps":["trace[1530757347] 'agreement among raft nodes before linearized reading' (duration: 1.012619171s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:01.459024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:00.445302Z","time spent":"1.013528987s","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:23:01.457991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:22:56.055804Z","time spent":"5.402182593s","remote":"127.0.0.1:43336","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-02-20T18:23:01.45938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.331324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-02-20T18:23:01.459444Z","caller":"traceutil/trace.go:171","msg":"trace[1185385520] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:535; }","duration":"495.422446ms","start":"2026-02-20T18:23:00.964002Z","end":"2026-02-20T18:23:01.459424Z","steps":["trace[1185385520] 'agreement among raft nodes before linearized reading' (duration: 493.715179ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:01.459493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:00.963981Z","time spent":"495.489319ms","remote":"127.0.0.1:43236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-02-20T18:23:01.457974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.01218116s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/kube-apiserver-instance.18960780e70de1e5\" ","response":"range_response_count:1 size:774"} {"level":"info","ts":"2026-02-20T18:23:01.459552Z","caller":"traceutil/trace.go:171","msg":"trace[1986340044] range","detail":"{range_begin:/registry/events/kube-system/kube-apiserver-instance.18960780e70de1e5; range_end:; response_count:1; response_revision:535; }","duration":"1.013795094s","start":"2026-02-20T18:23:00.445746Z","end":"2026-02-20T18:23:01.459541Z","steps":["trace[1986340044] 'agreement among raft nodes before linearized reading' (duration: 1.012170419s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:01.459578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:00.445733Z","time spent":"1.013836585s","remote":"127.0.0.1:43154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":797,"request content":"key:\"/registry/events/kube-system/kube-apiserver-instance.18960780e70de1e5\" "} {"level":"warn","ts":"2026-02-20T18:23:01.755369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.59186ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:23:01.755437Z","caller":"traceutil/trace.go:171","msg":"trace[972875903] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:558; }","duration":"293.775929ms","start":"2026-02-20T18:23:01.46165Z","end":"2026-02-20T18:23:01.755426Z","steps":["trace[972875903] 'read index received' (duration: 79.372µs)","trace[972875903] 'applied index is now lower than readState.Index' (duration: 293.695667ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:01.755483Z","caller":"traceutil/trace.go:171","msg":"trace[1995063737] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"293.852641ms","start":"2026-02-20T18:23:01.461624Z","end":"2026-02-20T18:23:01.755477Z","steps":["trace[1995063737] 'process raft request' (duration: 103.083836ms)","trace[1995063737] 'compare' (duration: 190.503708ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:01.755699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.039986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/\" range_end:\"/registry/masterleases0\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-02-20T18:23:01.755766Z","caller":"traceutil/trace.go:171","msg":"trace[1874046132] range","detail":"{range_begin:/registry/masterleases/; range_end:/registry/masterleases0; response_count:1; response_revision:536; }","duration":"294.137469ms","start":"2026-02-20T18:23:01.46161Z","end":"2026-02-20T18:23:01.755748Z","steps":["trace[1874046132] 'agreement among raft nodes before linearized reading' (duration: 293.906452ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:01.808455Z","caller":"traceutil/trace.go:171","msg":"trace[455600874] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"339.194897ms","start":"2026-02-20T18:23:01.469234Z","end":"2026-02-20T18:23:01.808429Z","steps":["trace[455600874] 'process raft request' (duration: 339.138806ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:01.808575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"346.831849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:23:01.808604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:01.469221Z","time spent":"339.30693ms","remote":"127.0.0.1:43246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16897,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:23:01.808634Z","caller":"traceutil/trace.go:171","msg":"trace[1737363741] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:539; }","duration":"346.931392ms","start":"2026-02-20T18:23:01.461689Z","end":"2026-02-20T18:23:01.80862Z","steps":["trace[1737363741] 'agreement among raft nodes before linearized reading' (duration: 346.798558ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:01.808666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:01.461655Z","time spent":"347.005684ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:23:01.808925Z","caller":"traceutil/trace.go:171","msg":"trace[438976468] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"340.322879ms","start":"2026-02-20T18:23:01.468594Z","end":"2026-02-20T18:23:01.808917Z","steps":["trace[438976468] 'process raft request' (duration: 339.734193ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:01.808989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:01.468585Z","time spent":"340.35821ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:23:01.809108Z","caller":"traceutil/trace.go:171","msg":"trace[1206814998] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"342.978412ms","start":"2026-02-20T18:23:01.466123Z","end":"2026-02-20T18:23:01.809101Z","steps":["trace[1206814998] 'process raft request' (duration: 342.073767ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:01.809141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:01.46611Z","time spent":"343.012243ms","remote":"127.0.0.1:43336","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-02-20T18:23:01.872736Z","caller":"traceutil/trace.go:171","msg":"trace[281724243] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"114.611905ms","start":"2026-02-20T18:23:01.758086Z","end":"2026-02-20T18:23:01.872697Z","steps":["trace[281724243] 'process raft request' (duration: 103.211199ms)","trace[281724243] 'compare' (duration: 11.271372ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:01.87285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.699505ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-02-20T18:23:01.872917Z","caller":"traceutil/trace.go:171","msg":"trace[757446040] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:540; }","duration":"115.775097ms","start":"2026-02-20T18:23:01.757124Z","end":"2026-02-20T18:23:01.872899Z","steps":["trace[757446040] 'agreement among raft nodes before linearized reading' (duration: 115.597673ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:03.941565Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514194617,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:23:04.023035Z","caller":"traceutil/trace.go:171","msg":"trace[989325807] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"598.805778ms","start":"2026-02-20T18:23:03.424211Z","end":"2026-02-20T18:23:04.023017Z","steps":["trace[989325807] 'process raft request' (duration: 598.714276ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:04.023148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:03.424199Z","time spent":"598.896211ms","remote":"127.0.0.1:43154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":754,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:23:04.217457Z","caller":"traceutil/trace.go:171","msg":"trace[1070585415] linearizableReadLoop","detail":"{readStateIndex:568; appliedIndex:567; }","duration":"777.094177ms","start":"2026-02-20T18:23:03.440335Z","end":"2026-02-20T18:23:04.217429Z","steps":["trace[1070585415] 'read index received' (duration: 582.748921ms)","trace[1070585415] 'applied index is now lower than readState.Index' (duration: 194.344216ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:04.217626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"777.267392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:04.217664Z","caller":"traceutil/trace.go:171","msg":"trace[1134020199] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"777.363675ms","start":"2026-02-20T18:23:03.440289Z","end":"2026-02-20T18:23:04.217653Z","steps":["trace[1134020199] 'agreement among raft nodes before linearized reading' (duration: 777.281272ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:04.21761Z","caller":"traceutil/trace.go:171","msg":"trace[1984230364] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"784.17018ms","start":"2026-02-20T18:23:03.433382Z","end":"2026-02-20T18:23:04.217552Z","steps":["trace[1984230364] 'process raft request' (duration: 782.069973ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:04.217704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:03.440268Z","time spent":"777.427506ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:23:04.21787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:03.433361Z","time spent":"784.368445ms","remote":"127.0.0.1:43246","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17152,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:23:04.218106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"751.367508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:23:04.218146Z","caller":"traceutil/trace.go:171","msg":"trace[499574519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"751.408629ms","start":"2026-02-20T18:23:03.466728Z","end":"2026-02-20T18:23:04.218137Z","steps":["trace[499574519] 'agreement among raft nodes before linearized reading' (duration: 751.268395ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:04.218175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:03.466695Z","time spent":"751.47424ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:23:04.218237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.298888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-20T18:23:04.218323Z","caller":"traceutil/trace.go:171","msg":"trace[1719373849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:545; }","duration":"405.455262ms","start":"2026-02-20T18:23:03.812841Z","end":"2026-02-20T18:23:04.218296Z","steps":["trace[1719373849] 'agreement among raft nodes before linearized reading' (duration: 405.260257ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:04.21861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:03.812808Z","time spent":"405.552885ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:23:04.218356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.598516ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-02-20T18:23:04.218742Z","caller":"traceutil/trace.go:171","msg":"trace[1092232299] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:545; }","duration":"406.018077ms","start":"2026-02-20T18:23:03.812706Z","end":"2026-02-20T18:23:04.218724Z","steps":["trace[1092232299] 'agreement among raft nodes before linearized reading' (duration: 405.581396ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:04.218788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:03.812687Z","time spent":"406.08757ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-02-20T18:23:06.563237Z","caller":"traceutil/trace.go:171","msg":"trace[249344525] linearizableReadLoop","detail":"{readStateIndex:579; appliedIndex:576; }","duration":"235.095649ms","start":"2026-02-20T18:23:06.328125Z","end":"2026-02-20T18:23:06.563221Z","steps":["trace[249344525] 'read index received' (duration: 58.985811ms)","trace[249344525] 'applied index is now lower than readState.Index' (duration: 176.108408ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:06.563297Z","caller":"traceutil/trace.go:171","msg":"trace[1339612920] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"245.963586ms","start":"2026-02-20T18:23:06.317289Z","end":"2026-02-20T18:23:06.563253Z","steps":["trace[1339612920] 'process raft request' (duration: 245.706319ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:06.563489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.313065ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:23:06.563585Z","caller":"traceutil/trace.go:171","msg":"trace[2005523760] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:555; }","duration":"235.454958ms","start":"2026-02-20T18:23:06.328108Z","end":"2026-02-20T18:23:06.563563Z","steps":["trace[2005523760] 'agreement among raft nodes before linearized reading' (duration: 235.227832ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:06.563143Z","caller":"traceutil/trace.go:171","msg":"trace[65967440] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"245.905205ms","start":"2026-02-20T18:23:06.317182Z","end":"2026-02-20T18:23:06.563087Z","steps":["trace[65967440] 'process raft request' (duration: 221.514139ms)","trace[65967440] 'compare' (duration: 24.110618ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:06.798508Z","caller":"traceutil/trace.go:171","msg":"trace[1867443471] transaction","detail":"{read_only:false; response_revision:556; number_of_response:1; }","duration":"116.41112ms","start":"2026-02-20T18:23:06.682066Z","end":"2026-02-20T18:23:06.798477Z","steps":["trace[1867443471] 'process raft request' (duration: 116.221905ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:07.045669Z","caller":"traceutil/trace.go:171","msg":"trace[1633176442] linearizableReadLoop","detail":"{readStateIndex:581; appliedIndex:580; }","duration":"242.108385ms","start":"2026-02-20T18:23:06.803529Z","end":"2026-02-20T18:23:07.045638Z","steps":["trace[1633176442] 'read index received' (duration: 238.922531ms)","trace[1633176442] 'applied index is now lower than readState.Index' (duration: 3.184304ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:07.045975Z","caller":"traceutil/trace.go:171","msg":"trace[163735774] transaction","detail":"{read_only:false; response_revision:557; number_of_response:1; }","duration":"270.305401ms","start":"2026-02-20T18:23:06.775658Z","end":"2026-02-20T18:23:07.045963Z","steps":["trace[163735774] 'process raft request' (duration: 266.884642ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:07.046283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.729912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:23:07.04632Z","caller":"traceutil/trace.go:171","msg":"trace[1907107886] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:557; }","duration":"242.837765ms","start":"2026-02-20T18:23:06.803472Z","end":"2026-02-20T18:23:07.04631Z","steps":["trace[1907107886] 'agreement among raft nodes before linearized reading' (duration: 242.67709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:07.046444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.376319ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:23:07.046541Z","caller":"traceutil/trace.go:171","msg":"trace[2056589551] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:557; }","duration":"167.585894ms","start":"2026-02-20T18:23:06.878934Z","end":"2026-02-20T18:23:07.046519Z","steps":["trace[2056589551] 'agreement among raft nodes before linearized reading' (duration: 167.349888ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:09.423815Z","caller":"traceutil/trace.go:171","msg":"trace[597302352] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"110.68482ms","start":"2026-02-20T18:23:09.31285Z","end":"2026-02-20T18:23:09.423534Z","steps":["trace[597302352] 'process raft request' (duration: 110.477635ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:10.145025Z","caller":"traceutil/trace.go:171","msg":"trace[679492323] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"349.289212ms","start":"2026-02-20T18:23:09.795709Z","end":"2026-02-20T18:23:10.144999Z","steps":["trace[679492323] 'process raft request' (duration: 329.071186ms)","trace[679492323] 'compare' (duration: 19.829376ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:10.14517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:09.795692Z","time spent":"349.404484ms","remote":"127.0.0.1:43114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:23:10.339251Z","caller":"traceutil/trace.go:171","msg":"trace[160551005] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"223.069664ms","start":"2026-02-20T18:23:10.11615Z","end":"2026-02-20T18:23:10.33922Z","steps":["trace[160551005] 'process raft request' (duration: 223.014172ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:10.339275Z","caller":"traceutil/trace.go:171","msg":"trace[569519934] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"422.335988ms","start":"2026-02-20T18:23:09.916908Z","end":"2026-02-20T18:23:10.339244Z","steps":["trace[569519934] 'process raft request' (duration: 418.399598ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:10.339483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:09.91686Z","time spent":"422.523283ms","remote":"127.0.0.1:43114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:23:10.359645Z","caller":"traceutil/trace.go:171","msg":"trace[2122632924] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"205.519705ms","start":"2026-02-20T18:23:10.15409Z","end":"2026-02-20T18:23:10.35961Z","steps":["trace[2122632924] 'process raft request' (duration: 205.256609ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:10.465653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.094347ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-02-20T18:23:10.46599Z","caller":"traceutil/trace.go:171","msg":"trace[68773044] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:626; }","duration":"134.622986ms","start":"2026-02-20T18:23:10.33132Z","end":"2026-02-20T18:23:10.465943Z","steps":["trace[68773044] 'read index received' (duration: 3.977192ms)","trace[68773044] 'applied index is now lower than readState.Index' (duration: 130.621573ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:10.466128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.77847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:10.466229Z","caller":"traceutil/trace.go:171","msg":"trace[1485789298] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:604; }","duration":"134.945503ms","start":"2026-02-20T18:23:10.331261Z","end":"2026-02-20T18:23:10.466206Z","steps":["trace[1485789298] 'agreement among raft nodes before linearized reading' (duration: 134.79301ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:10.466273Z","caller":"traceutil/trace.go:171","msg":"trace[1728099002] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"112.000598ms","start":"2026-02-20T18:23:10.354233Z","end":"2026-02-20T18:23:10.466234Z","steps":["trace[1728099002] 'process raft request' (duration: 111.622489ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:10.466402Z","caller":"traceutil/trace.go:171","msg":"trace[1413208331] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"109.165786ms","start":"2026-02-20T18:23:10.357208Z","end":"2026-02-20T18:23:10.466373Z","steps":["trace[1413208331] 'process raft request' (duration: 108.94464ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:10.914709Z","caller":"traceutil/trace.go:171","msg":"trace[171485699] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"441.915228ms","start":"2026-02-20T18:23:10.472773Z","end":"2026-02-20T18:23:10.914689Z","steps":["trace[171485699] 'process raft request' (duration: 441.744653ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:10.914952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:10.472745Z","time spent":"442.120273ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:23:10.956996Z","caller":"traceutil/trace.go:171","msg":"trace[268931841] transaction","detail":"{read_only:false; response_revision:608; number_of_response:1; }","duration":"483.604721ms","start":"2026-02-20T18:23:10.473355Z","end":"2026-02-20T18:23:10.956959Z","steps":["trace[268931841] 'process raft request' (duration: 483.346055ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:10.95702Z","caller":"traceutil/trace.go:171","msg":"trace[1171882779] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:632; }","duration":"444.023601ms","start":"2026-02-20T18:23:10.512967Z","end":"2026-02-20T18:23:10.956991Z","steps":["trace[1171882779] 'read index received' (duration: 401.532097ms)","trace[1171882779] 'applied index is now lower than readState.Index' (duration: 42.490374ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:10.957174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:10.473341Z","time spent":"483.752905ms","remote":"127.0.0.1:43154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:23:10.957185Z","caller":"traceutil/trace.go:171","msg":"trace[862739343] transaction","detail":"{read_only:false; response_revision:610; number_of_response:1; }","duration":"476.601593ms","start":"2026-02-20T18:23:10.480574Z","end":"2026-02-20T18:23:10.957175Z","steps":["trace[862739343] 'process raft request' (duration: 476.363357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:10.957327Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:10.480565Z","time spent":"476.655914ms","remote":"127.0.0.1:43114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":24229,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:23:10.9575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"444.530924ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:10.957551Z","caller":"traceutil/trace.go:171","msg":"trace[1282520290] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:610; }","duration":"444.606746ms","start":"2026-02-20T18:23:10.512928Z","end":"2026-02-20T18:23:10.957535Z","steps":["trace[1282520290] 'agreement among raft nodes before linearized reading' (duration: 444.541574ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:10.957582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:10.512911Z","time spent":"444.664877ms","remote":"127.0.0.1:43114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" "} {"level":"warn","ts":"2026-02-20T18:23:10.957632Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"427.350825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-20T18:23:10.957681Z","caller":"traceutil/trace.go:171","msg":"trace[701555788] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:610; }","duration":"427.405997ms","start":"2026-02-20T18:23:10.530264Z","end":"2026-02-20T18:23:10.95767Z","steps":["trace[701555788] 'agreement among raft nodes before linearized reading' (duration: 427.243232ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:10.957713Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:10.530226Z","time spent":"427.480539ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:23:10.957801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.630663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io\" ","response":"range_response_count:1 size:5586"} {"level":"info","ts":"2026-02-20T18:23:10.957875Z","caller":"traceutil/trace.go:171","msg":"trace[1352856100] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumexternalworkloads.cilium.io; range_end:; response_count:1; response_revision:610; }","duration":"246.88941ms","start":"2026-02-20T18:23:10.710967Z","end":"2026-02-20T18:23:10.957856Z","steps":["trace[1352856100] 'agreement among raft nodes before linearized reading' (duration: 246.720436ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:10.95789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.007897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:23:10.95792Z","caller":"traceutil/trace.go:171","msg":"trace[454286311] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:610; }","duration":"331.042428ms","start":"2026-02-20T18:23:10.626869Z","end":"2026-02-20T18:23:10.957911Z","steps":["trace[454286311] 'agreement among raft nodes before linearized reading' (duration: 330.916605ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:10.957946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:10.626792Z","time spent":"331.14874ms","remote":"127.0.0.1:43336","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-02-20T18:23:10.958126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.240276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-jzp5w\" ","response":"range_response_count:1 size:17914"} {"level":"info","ts":"2026-02-20T18:23:10.958148Z","caller":"traceutil/trace.go:171","msg":"trace[869193678] range","detail":"{range_begin:/registry/pods/kube-system/cilium-jzp5w; range_end:; response_count:1; response_revision:610; }","duration":"241.316048ms","start":"2026-02-20T18:23:10.716827Z","end":"2026-02-20T18:23:10.958143Z","steps":["trace[869193678] 'agreement among raft nodes before linearized reading' (duration: 241.259526ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:10.958228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.104949ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-20T18:23:10.958306Z","caller":"traceutil/trace.go:171","msg":"trace[122984192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:610; }","duration":"349.507519ms","start":"2026-02-20T18:23:10.608758Z","end":"2026-02-20T18:23:10.958266Z","steps":["trace[122984192] 'agreement among raft nodes before linearized reading' (duration: 349.090318ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:10.958351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:10.608735Z","time spent":"349.603672ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-02-20T18:23:10.957444Z","caller":"traceutil/trace.go:171","msg":"trace[2060276638] transaction","detail":"{read_only:false; response_revision:609; number_of_response:1; }","duration":"480.224355ms","start":"2026-02-20T18:23:10.477063Z","end":"2026-02-20T18:23:10.957288Z","steps":["trace[2060276638] 'process raft request' (duration: 479.795594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:10.959678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:10.477055Z","time spent":"482.489612ms","remote":"127.0.0.1:43114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13205,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:23:11.880401Z","caller":"traceutil/trace.go:171","msg":"trace[943174479] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"143.703166ms","start":"2026-02-20T18:23:11.73666Z","end":"2026-02-20T18:23:11.880363Z","steps":["trace[943174479] 'process raft request' (duration: 105.509659ms)","trace[943174479] 'compare' (duration: 38.011882ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:12.977246Z","caller":"traceutil/trace.go:171","msg":"trace[734407734] linearizableReadLoop","detail":"{readStateIndex:652; appliedIndex:651; }","duration":"226.403437ms","start":"2026-02-20T18:23:12.750725Z","end":"2026-02-20T18:23:12.977129Z","steps":["trace[734407734] 'read index received' (duration: 220.853408ms)","trace[734407734] 'applied index is now lower than readState.Index' (duration: 5.546489ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:12.977223Z","caller":"traceutil/trace.go:171","msg":"trace[96896739] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"231.155647ms","start":"2026-02-20T18:23:12.74602Z","end":"2026-02-20T18:23:12.977175Z","steps":["trace[96896739] 'process raft request' (duration: 225.553457ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:12.977425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.652564ms","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-02-20T18:23:12.97751Z","caller":"traceutil/trace.go:171","msg":"trace[64488472] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:626; }","duration":"226.801267ms","start":"2026-02-20T18:23:12.750694Z","end":"2026-02-20T18:23:12.977495Z","steps":["trace[64488472] 'agreement among raft nodes before linearized reading' (duration: 226.635303ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.025483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.645482ms","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-02-20T18:23:13.025592Z","caller":"traceutil/trace.go:171","msg":"trace[396842724] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:628; }","duration":"274.784426ms","start":"2026-02-20T18:23:12.750786Z","end":"2026-02-20T18:23:13.02557Z","steps":["trace[396842724] 'agreement among raft nodes before linearized reading' (duration: 274.605251ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.026004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.5846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:13.026036Z","caller":"traceutil/trace.go:171","msg":"trace[472674367] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:628; }","duration":"207.658512ms","start":"2026-02-20T18:23:12.81837Z","end":"2026-02-20T18:23:13.026029Z","steps":["trace[472674367] 'agreement among raft nodes before linearized reading' (duration: 207.615531ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.026146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.817126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:13.026174Z","caller":"traceutil/trace.go:171","msg":"trace[1155690211] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:628; }","duration":"207.860698ms","start":"2026-02-20T18:23:12.818308Z","end":"2026-02-20T18:23:13.026169Z","steps":["trace[1155690211] 'agreement among raft nodes before linearized reading' (duration: 207.820357ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.026157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.671703ms","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-02-20T18:23:13.026274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.775721ms","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-02-20T18:23:13.026279Z","caller":"traceutil/trace.go:171","msg":"trace[1344526087] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:628; }","duration":"207.852498ms","start":"2026-02-20T18:23:12.818397Z","end":"2026-02-20T18:23:13.026249Z","steps":["trace[1344526087] 'agreement among raft nodes before linearized reading' (duration: 207.589921ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.026345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.880564ms","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":"info","ts":"2026-02-20T18:23:13.026373Z","caller":"traceutil/trace.go:171","msg":"trace[817127966] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:628; }","duration":"220.911435ms","start":"2026-02-20T18:23:12.805456Z","end":"2026-02-20T18:23:13.026368Z","steps":["trace[817127966] 'agreement among raft nodes before linearized reading' (duration: 220.873404ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.026474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.052678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:13.026503Z","caller":"traceutil/trace.go:171","msg":"trace[2122284775] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:628; }","duration":"221.100239ms","start":"2026-02-20T18:23:12.805397Z","end":"2026-02-20T18:23:13.026497Z","steps":["trace[2122284775] 'agreement among raft nodes before linearized reading' (duration: 221.061128ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.026634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.188038ms","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-02-20T18:23:13.026673Z","caller":"traceutil/trace.go:171","msg":"trace[1412028582] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:628; }","duration":"236.2767ms","start":"2026-02-20T18:23:12.79039Z","end":"2026-02-20T18:23:13.026667Z","steps":["trace[1412028582] 'agreement among raft nodes before linearized reading' (duration: 236.222779ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.026791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.175725ms","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":"info","ts":"2026-02-20T18:23:13.026965Z","caller":"traceutil/trace.go:171","msg":"trace[569211283] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:628; }","duration":"275.37774ms","start":"2026-02-20T18:23:12.751567Z","end":"2026-02-20T18:23:13.026945Z","steps":["trace[569211283] 'agreement among raft nodes before linearized reading' (duration: 275.117383ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.027225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.127233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:13.028159Z","caller":"traceutil/trace.go:171","msg":"trace[1098523616] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:628; }","duration":"260.093947ms","start":"2026-02-20T18:23:12.768051Z","end":"2026-02-20T18:23:13.028145Z","steps":["trace[1098523616] 'agreement among raft nodes before linearized reading' (duration: 259.151803ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.027288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.79882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:13.028394Z","caller":"traceutil/trace.go:171","msg":"trace[1122080021] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:628; }","duration":"260.935328ms","start":"2026-02-20T18:23:12.767436Z","end":"2026-02-20T18:23:13.028371Z","steps":["trace[1122080021] 'agreement among raft nodes before linearized reading' (duration: 259.78267ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.027402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.058736ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliuml2announcementpolicies/\" range_end:\"/registry/cilium.io/ciliuml2announcementpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:13.028519Z","caller":"traceutil/trace.go:171","msg":"trace[2140672072] range","detail":"{range_begin:/registry/cilium.io/ciliuml2announcementpolicies/; range_end:/registry/cilium.io/ciliuml2announcementpolicies0; response_count:0; response_revision:628; }","duration":"261.203335ms","start":"2026-02-20T18:23:12.767306Z","end":"2026-02-20T18:23:13.028509Z","steps":["trace[2140672072] 'agreement among raft nodes before linearized reading' (duration: 260.068867ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.027544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.106391ms","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-02-20T18:23:13.028654Z","caller":"traceutil/trace.go:171","msg":"trace[1168586465] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:628; }","duration":"238.28802ms","start":"2026-02-20T18:23:12.790356Z","end":"2026-02-20T18:23:13.028644Z","steps":["trace[1168586465] 'agreement among raft nodes before linearized reading' (duration: 237.049479ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:13.027585Z","caller":"traceutil/trace.go:171","msg":"trace[525661570] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"271.717278ms","start":"2026-02-20T18:23:12.755837Z","end":"2026-02-20T18:23:13.027554Z","steps":["trace[525661570] 'process raft request' (duration: 269.334709ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.027673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.299506ms","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-02-20T18:23:13.028931Z","caller":"traceutil/trace.go:171","msg":"trace[572770442] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:628; }","duration":"238.586467ms","start":"2026-02-20T18:23:12.790334Z","end":"2026-02-20T18:23:13.02892Z","steps":["trace[572770442] 'agreement among raft nodes before linearized reading' (duration: 237.295155ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:13.026303Z","caller":"traceutil/trace.go:171","msg":"trace[1480920845] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:628; }","duration":"220.805642ms","start":"2026-02-20T18:23:12.805492Z","end":"2026-02-20T18:23:13.026297Z","steps":["trace[1480920845] 'agreement among raft nodes before linearized reading' (duration: 220.765161ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.027725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.513943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:13.029306Z","caller":"traceutil/trace.go:171","msg":"trace[629636211] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:628; }","duration":"194.123622ms","start":"2026-02-20T18:23:12.83517Z","end":"2026-02-20T18:23:13.029293Z","steps":["trace[629636211] 'agreement among raft nodes before linearized reading' (duration: 192.474291ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.027798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.621815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:13.029482Z","caller":"traceutil/trace.go:171","msg":"trace[1881777266] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:628; }","duration":"194.320778ms","start":"2026-02-20T18:23:12.835151Z","end":"2026-02-20T18:23:13.029471Z","steps":["trace[1881777266] 'agreement among raft nodes before linearized reading' (duration: 192.592515ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.027845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.714567ms","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-02-20T18:23:13.029742Z","caller":"traceutil/trace.go:171","msg":"trace[661324051] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:628; }","duration":"194.617255ms","start":"2026-02-20T18:23:12.835108Z","end":"2026-02-20T18:23:13.029725Z","steps":["trace[661324051] 'agreement among raft nodes before linearized reading' (duration: 192.716818ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.027893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.127051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:13.030047Z","caller":"traceutil/trace.go:171","msg":"trace[1397043959] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:628; }","duration":"184.288756ms","start":"2026-02-20T18:23:12.845746Z","end":"2026-02-20T18:23:13.030035Z","steps":["trace[1397043959] 'agreement among raft nodes before linearized reading' (duration: 182.126802ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.027955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.02698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-02-20T18:23:13.030591Z","caller":"traceutil/trace.go:171","msg":"trace[833527323] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:628; }","duration":"135.570643ms","start":"2026-02-20T18:23:12.894847Z","end":"2026-02-20T18:23:13.030418Z","steps":["trace[833527323] 'agreement among raft nodes before linearized reading' (duration: 133.062051ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.028003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.949568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188495"} {"level":"warn","ts":"2026-02-20T18:23:13.030902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.819138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-02-20T18:23:13.031011Z","caller":"traceutil/trace.go:171","msg":"trace[618310359] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:628; }","duration":"111.968092ms","start":"2026-02-20T18:23:12.919018Z","end":"2026-02-20T18:23:13.030986Z","steps":["trace[618310359] 'agreement among raft nodes before linearized reading' (duration: 109.182622ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:13.030942Z","caller":"traceutil/trace.go:171","msg":"trace[1699147030] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:628; }","duration":"119.913621ms","start":"2026-02-20T18:23:12.910991Z","end":"2026-02-20T18:23:13.030904Z","steps":["trace[1699147030] 'agreement among raft nodes before linearized reading' (duration: 116.768143ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.028107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.482831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:13.031471Z","caller":"traceutil/trace.go:171","msg":"trace[338592523] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:628; }","duration":"185.851934ms","start":"2026-02-20T18:23:12.845604Z","end":"2026-02-20T18:23:13.031456Z","steps":["trace[338592523] 'agreement among raft nodes before linearized reading' (duration: 182.47873ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.027666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.141972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:13.031616Z","caller":"traceutil/trace.go:171","msg":"trace[1717014217] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:628; }","duration":"186.08109ms","start":"2026-02-20T18:23:12.845474Z","end":"2026-02-20T18:23:13.031555Z","steps":["trace[1717014217] 'agreement among raft nodes before linearized reading' (duration: 182.150332ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.421674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.184079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:23:13.421827Z","caller":"traceutil/trace.go:171","msg":"trace[1314481820] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"386.619861ms","start":"2026-02-20T18:23:13.03518Z","end":"2026-02-20T18:23:13.4218Z","steps":["trace[1314481820] 'process raft request' (duration: 93.198737ms)","trace[1314481820] 'compare' (duration: 293.039574ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:13.421968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:13.035164Z","time spent":"386.730304ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:23:13.421914Z","caller":"traceutil/trace.go:171","msg":"trace[1279008021] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"369.034064ms","start":"2026-02-20T18:23:13.052849Z","end":"2026-02-20T18:23:13.421883Z","steps":["trace[1279008021] 'read index received' (duration: 75.546028ms)","trace[1279008021] 'applied index is now lower than readState.Index' (duration: 293.485766ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:13.422107Z","caller":"traceutil/trace.go:171","msg":"trace[1033786120] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"319.3552ms","start":"2026-02-20T18:23:13.102716Z","end":"2026-02-20T18:23:13.422071Z","steps":["trace[1033786120] 'process raft request' (duration: 319.143044ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.422203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:13.102684Z","time spent":"319.489372ms","remote":"127.0.0.1:43114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188228,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:23:13.42322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.315226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-20T18:23:13.423371Z","caller":"traceutil/trace.go:171","msg":"trace[960697588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:629; }","duration":"370.517081ms","start":"2026-02-20T18:23:13.052802Z","end":"2026-02-20T18:23:13.423319Z","steps":["trace[960697588] 'agreement among raft nodes before linearized reading' (duration: 369.218008ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.423461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:13.052779Z","time spent":"370.638184ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:23:13.510305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.466021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:13.510417Z","caller":"traceutil/trace.go:171","msg":"trace[128644856] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"327.615884ms","start":"2026-02-20T18:23:13.182782Z","end":"2026-02-20T18:23:13.510398Z","steps":["trace[128644856] 'agreement among raft nodes before linearized reading' (duration: 327.350518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.510436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.510798ms","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-02-20T18:23:13.510505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.09668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:23:13.510464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:13.182769Z","time spent":"327.684296ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:23:13.51053Z","caller":"traceutil/trace.go:171","msg":"trace[268616601] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:632; }","duration":"456.680473ms","start":"2026-02-20T18:23:13.053833Z","end":"2026-02-20T18:23:13.510513Z","steps":["trace[268616601] 'agreement among raft nodes before linearized reading' (duration: 456.436477ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:13.510552Z","caller":"traceutil/trace.go:171","msg":"trace[1449458291] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:632; }","duration":"343.181611ms","start":"2026-02-20T18:23:13.167359Z","end":"2026-02-20T18:23:13.510541Z","steps":["trace[1449458291] 'agreement among raft nodes before linearized reading' (duration: 343.110369ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.510573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:13.053814Z","time spent":"456.740404ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:23:13.51045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.158186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-20T18:23:13.510705Z","caller":"traceutil/trace.go:171","msg":"trace[177301838] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"274.505814ms","start":"2026-02-20T18:23:13.236172Z","end":"2026-02-20T18:23:13.510678Z","steps":["trace[177301838] 'agreement among raft nodes before linearized reading' (duration: 273.954851ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.510608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:13.167339Z","time spent":"343.259103ms","remote":"127.0.0.1:43212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:23:13.511039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.639773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:23:13.511134Z","caller":"traceutil/trace.go:171","msg":"trace[1257406484] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"454.762975ms","start":"2026-02-20T18:23:13.056353Z","end":"2026-02-20T18:23:13.511116Z","steps":["trace[1257406484] 'agreement among raft nodes before linearized reading' (duration: 453.900164ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.51117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:13.056345Z","time spent":"454.814686ms","remote":"127.0.0.1:43336","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-02-20T18:23:13.585302Z","caller":"traceutil/trace.go:171","msg":"trace[1594364902] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"157.626658ms","start":"2026-02-20T18:23:13.427632Z","end":"2026-02-20T18:23:13.585259Z","steps":["trace[1594364902] 'process raft request' (duration: 141.827246ms)","trace[1594364902] 'compare' (duration: 15.491705ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:13.585629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.889677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:13.585681Z","caller":"traceutil/trace.go:171","msg":"trace[1865240058] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"141.958919ms","start":"2026-02-20T18:23:13.44371Z","end":"2026-02-20T18:23:13.585669Z","steps":["trace[1865240058] 'agreement among raft nodes before linearized reading' (duration: 141.657171ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:13.739566Z","caller":"traceutil/trace.go:171","msg":"trace[91989434] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"223.541107ms","start":"2026-02-20T18:23:13.516001Z","end":"2026-02-20T18:23:13.739542Z","steps":["trace[91989434] 'process raft request' (duration: 223.448865ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:13.739639Z","caller":"traceutil/trace.go:171","msg":"trace[1333834889] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"220.473671ms","start":"2026-02-20T18:23:13.519161Z","end":"2026-02-20T18:23:13.739634Z","steps":["trace[1333834889] 'process raft request' (duration: 220.315767ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:13.739574Z","caller":"traceutil/trace.go:171","msg":"trace[530132092] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"225.425954ms","start":"2026-02-20T18:23:13.514113Z","end":"2026-02-20T18:23:13.739539Z","steps":["trace[530132092] 'process raft request' (duration: 210.619076ms)","trace[530132092] 'compare' (duration: 14.590333ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:13.739823Z","caller":"traceutil/trace.go:171","msg":"trace[717108677] linearizableReadLoop","detail":"{readStateIndex:664; appliedIndex:660; }","duration":"154.475621ms","start":"2026-02-20T18:23:13.58534Z","end":"2026-02-20T18:23:13.739816Z","steps":["trace[717108677] 'read index received' (duration: 139.440487ms)","trace[717108677] 'applied index is now lower than readState.Index' (duration: 15.034034ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:13.740042Z","caller":"traceutil/trace.go:171","msg":"trace[780484840] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"215.472866ms","start":"2026-02-20T18:23:13.524562Z","end":"2026-02-20T18:23:13.740034Z","steps":["trace[780484840] 'process raft request' (duration: 214.938823ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:13.740059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.304451ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-02-20T18:23:13.740081Z","caller":"traceutil/trace.go:171","msg":"trace[683040464] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:638; }","duration":"188.387903ms","start":"2026-02-20T18:23:13.551685Z","end":"2026-02-20T18:23:13.740073Z","steps":["trace[683040464] 'agreement among raft nodes before linearized reading' (duration: 188.156187ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:13.97665Z","caller":"traceutil/trace.go:171","msg":"trace[660328025] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"231.525956ms","start":"2026-02-20T18:23:13.7451Z","end":"2026-02-20T18:23:13.976626Z","steps":["trace[660328025] 'process raft request' (duration: 231.420833ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:13.976648Z","caller":"traceutil/trace.go:171","msg":"trace[1407976727] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"231.573747ms","start":"2026-02-20T18:23:13.745038Z","end":"2026-02-20T18:23:13.976612Z","steps":["trace[1407976727] 'process raft request' (duration: 230.718045ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:13.976657Z","caller":"traceutil/trace.go:171","msg":"trace[1874284878] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"231.521355ms","start":"2026-02-20T18:23:13.745086Z","end":"2026-02-20T18:23:13.976608Z","steps":["trace[1874284878] 'process raft request' (duration: 231.353721ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:13.976622Z","caller":"traceutil/trace.go:171","msg":"trace[1544191926] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:665; }","duration":"173.438612ms","start":"2026-02-20T18:23:13.803153Z","end":"2026-02-20T18:23:13.976592Z","steps":["trace[1544191926] 'read index received' (duration: 172.526139ms)","trace[1544191926] 'applied index is now lower than readState.Index' (duration: 910.803µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:13.97712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.936464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-6f56467975-dhdnn\" ","response":"range_response_count:1 size:2858"} {"level":"info","ts":"2026-02-20T18:23:13.977261Z","caller":"traceutil/trace.go:171","msg":"trace[277239577] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-6f56467975-dhdnn; range_end:; response_count:1; response_revision:642; }","duration":"174.116618ms","start":"2026-02-20T18:23:13.80312Z","end":"2026-02-20T18:23:13.977237Z","steps":["trace[277239577] 'agreement among raft nodes before linearized reading' (duration: 173.637836ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:14.254974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.398262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:23:14.255066Z","caller":"traceutil/trace.go:171","msg":"trace[413070716] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"451.747686ms","start":"2026-02-20T18:23:13.803306Z","end":"2026-02-20T18:23:14.255053Z","steps":["trace[413070716] 'process raft request' (duration: 302.208721ms)","trace[413070716] 'compare' (duration: 149.173606ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:14.2551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:13.803288Z","time spent":"451.797977ms","remote":"127.0.0.1:43154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":807,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-02-20T18:23:14.25808Z","caller":"traceutil/trace.go:171","msg":"trace[1563043260] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:668; }","duration":"207.769471ms","start":"2026-02-20T18:23:14.050256Z","end":"2026-02-20T18:23:14.258025Z","steps":["trace[1563043260] 'read index received' (duration: 55.338814ms)","trace[1563043260] 'applied index is now lower than readState.Index' (duration: 152.428927ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:14.258617Z","caller":"traceutil/trace.go:171","msg":"trace[1921070441] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"275.215027ms","start":"2026-02-20T18:23:13.983381Z","end":"2026-02-20T18:23:14.258596Z","steps":["trace[1921070441] 'process raft request' (duration: 274.379896ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:14.258677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.426837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-02-20T18:23:14.258734Z","caller":"traceutil/trace.go:171","msg":"trace[1875186218] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:644; }","duration":"208.494168ms","start":"2026-02-20T18:23:14.0502Z","end":"2026-02-20T18:23:14.258694Z","steps":["trace[1875186218] 'agreement among raft nodes before linearized reading' (duration: 208.429217ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:14.353146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.402349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-20T18:23:14.353211Z","caller":"traceutil/trace.go:171","msg":"trace[1708557951] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:645; }","duration":"101.467781ms","start":"2026-02-20T18:23:14.251719Z","end":"2026-02-20T18:23:14.353187Z","steps":["trace[1708557951] 'agreement among raft nodes before linearized reading' (duration: 101.00006ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:14.35349Z","caller":"traceutil/trace.go:171","msg":"trace[1352348714] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"187.760268ms","start":"2026-02-20T18:23:14.165697Z","end":"2026-02-20T18:23:14.353457Z","steps":["trace[1352348714] 'process raft request' (duration: 186.800804ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:14.353891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.965331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:14.353936Z","caller":"traceutil/trace.go:171","msg":"trace[1424607391] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:645; }","duration":"176.998162ms","start":"2026-02-20T18:23:14.176916Z","end":"2026-02-20T18:23:14.353914Z","steps":["trace[1424607391] 'agreement among raft nodes before linearized reading' (duration: 175.838594ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:14.353871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.367578ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"warn","ts":"2026-02-20T18:23:14.354345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.853573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:14.354405Z","caller":"traceutil/trace.go:171","msg":"trace[65915314] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:645; }","duration":"177.939966ms","start":"2026-02-20T18:23:14.176449Z","end":"2026-02-20T18:23:14.354389Z","steps":["trace[65915314] 'agreement among raft nodes before linearized reading' (duration: 177.783742ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:14.354671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.078366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/coredns\" ","response":"range_response_count:1 size:179"} {"level":"info","ts":"2026-02-20T18:23:14.354701Z","caller":"traceutil/trace.go:171","msg":"trace[1226384928] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:645; }","duration":"102.114566ms","start":"2026-02-20T18:23:14.25258Z","end":"2026-02-20T18:23:14.354694Z","steps":["trace[1226384928] 'agreement among raft nodes before linearized reading' (duration: 100.206319ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:14.354904Z","caller":"traceutil/trace.go:171","msg":"trace[535393141] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/coredns; range_end:; response_count:1; response_revision:645; }","duration":"102.072215ms","start":"2026-02-20T18:23:14.252265Z","end":"2026-02-20T18:23:14.354337Z","steps":["trace[535393141] 'agreement among raft nodes before linearized reading' (duration: 100.772264ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:14.354189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.73035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:23:14.355497Z","caller":"traceutil/trace.go:171","msg":"trace[997563105] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:645; }","duration":"179.124025ms","start":"2026-02-20T18:23:14.176341Z","end":"2026-02-20T18:23:14.355465Z","steps":["trace[997563105] 'agreement among raft nodes before linearized reading' (duration: 177.632839ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:14.355116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.420657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:23:14.355723Z","caller":"traceutil/trace.go:171","msg":"trace[1419991820] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:645; }","duration":"293.053873ms","start":"2026-02-20T18:23:14.062649Z","end":"2026-02-20T18:23:14.355703Z","steps":["trace[1419991820] 'agreement among raft nodes before linearized reading' (duration: 292.436248ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:14.355064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.839795ms","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":"info","ts":"2026-02-20T18:23:14.358029Z","caller":"traceutil/trace.go:171","msg":"trace[1978201855] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:645; }","duration":"202.807068ms","start":"2026-02-20T18:23:14.155203Z","end":"2026-02-20T18:23:14.35801Z","steps":["trace[1978201855] 'agreement among raft nodes before linearized reading' (duration: 199.415974ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:14.363127Z","caller":"traceutil/trace.go:171","msg":"trace[612622004] transaction","detail":"{read_only:false; response_revision:646; number_of_response:1; }","duration":"104.436034ms","start":"2026-02-20T18:23:14.258671Z","end":"2026-02-20T18:23:14.363107Z","steps":["trace[612622004] 'process raft request' (duration: 99.82614ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:14.363276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.255785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/coredns-5dd5756b68-q4fv2\" ","response":"range_response_count:1 size:3813"} {"level":"info","ts":"2026-02-20T18:23:14.363352Z","caller":"traceutil/trace.go:171","msg":"trace[1085975939] range","detail":"{range_begin:/registry/pods/kube-system/coredns-5dd5756b68-q4fv2; range_end:; response_count:1; response_revision:646; }","duration":"101.369758ms","start":"2026-02-20T18:23:14.261967Z","end":"2026-02-20T18:23:14.363336Z","steps":["trace[1085975939] 'agreement among raft nodes before linearized reading' (duration: 101.185354ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:14.363709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.663245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/local-path-provisioner-6f56467975-dhdnn\" ","response":"range_response_count:1 size:3795"} {"level":"info","ts":"2026-02-20T18:23:14.363756Z","caller":"traceutil/trace.go:171","msg":"trace[2063306211] range","detail":"{range_begin:/registry/pods/local-path-storage/local-path-provisioner-6f56467975-dhdnn; range_end:; response_count:1; response_revision:646; }","duration":"103.718606ms","start":"2026-02-20T18:23:14.260023Z","end":"2026-02-20T18:23:14.363742Z","steps":["trace[2063306211] 'agreement among raft nodes before linearized reading' (duration: 103.631704ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:18.170021Z","caller":"traceutil/trace.go:171","msg":"trace[265975562] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"121.231549ms","start":"2026-02-20T18:23:18.048758Z","end":"2026-02-20T18:23:18.16999Z","steps":["trace[265975562] 'process raft request' (duration: 121.068385ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:19.754438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.75549ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-20T18:23:19.754515Z","caller":"traceutil/trace.go:171","msg":"trace[604090766] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:714; }","duration":"144.896744ms","start":"2026-02-20T18:23:19.609603Z","end":"2026-02-20T18:23:19.754499Z","steps":["trace[604090766] 'range keys from in-memory index tree' (duration: 144.558046ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:19.864968Z","caller":"traceutil/trace.go:171","msg":"trace[677275705] linearizableReadLoop","detail":"{readStateIndex:744; appliedIndex:743; }","duration":"102.985013ms","start":"2026-02-20T18:23:19.761957Z","end":"2026-02-20T18:23:19.864942Z","steps":["trace[677275705] 'read index received' (duration: 102.86104ms)","trace[677275705] 'applied index is now lower than readState.Index' (duration: 122.253µs)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:19.865064Z","caller":"traceutil/trace.go:171","msg":"trace[1225200462] transaction","detail":"{read_only:false; response_revision:715; number_of_response:1; }","duration":"106.27364ms","start":"2026-02-20T18:23:19.758767Z","end":"2026-02-20T18:23:19.86504Z","steps":["trace[1225200462] 'process raft request' (duration: 106.007474ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:19.865212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.100692ms","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-02-20T18:23:19.865214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.837946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-20T18:23:19.865242Z","caller":"traceutil/trace.go:171","msg":"trace[1081945061] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:715; }","duration":"102.191014ms","start":"2026-02-20T18:23:19.763044Z","end":"2026-02-20T18:23:19.865235Z","steps":["trace[1081945061] 'agreement among raft nodes before linearized reading' (duration: 102.057761ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:19.865249Z","caller":"traceutil/trace.go:171","msg":"trace[811982858] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:715; }","duration":"101.907298ms","start":"2026-02-20T18:23:19.763331Z","end":"2026-02-20T18:23:19.865239Z","steps":["trace[811982858] 'agreement among raft nodes before linearized reading' (duration: 101.770785ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:19.865365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.338391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:23:19.865466Z","caller":"traceutil/trace.go:171","msg":"trace[996190192] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:715; }","duration":"103.506666ms","start":"2026-02-20T18:23:19.761933Z","end":"2026-02-20T18:23:19.86544Z","steps":["trace[996190192] 'agreement among raft nodes before linearized reading' (duration: 103.140557ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:20.025332Z","caller":"traceutil/trace.go:171","msg":"trace[2089291081] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"156.538093ms","start":"2026-02-20T18:23:19.868764Z","end":"2026-02-20T18:23:20.025302Z","steps":["trace[2089291081] 'process raft request' (duration: 155.821807ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:20.027444Z","caller":"traceutil/trace.go:171","msg":"trace[1226377382] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"156.81748ms","start":"2026-02-20T18:23:19.868874Z","end":"2026-02-20T18:23:20.025692Z","steps":["trace[1226377382] 'process raft request' (duration: 156.37913ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:22.228922Z","caller":"traceutil/trace.go:171","msg":"trace[725782934] linearizableReadLoop","detail":"{readStateIndex:762; appliedIndex:761; }","duration":"134.793527ms","start":"2026-02-20T18:23:22.094099Z","end":"2026-02-20T18:23:22.228893Z","steps":["trace[725782934] 'read index received' (duration: 100.817154ms)","trace[725782934] 'applied index is now lower than readState.Index' (duration: 33.974883ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:23:22.229055Z","caller":"traceutil/trace.go:171","msg":"trace[1781730065] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"189.276564ms","start":"2026-02-20T18:23:22.039724Z","end":"2026-02-20T18:23:22.229001Z","steps":["trace[1781730065] 'process raft request' (duration: 155.269359ms)","trace[1781730065] 'compare' (duration: 33.751739ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:22.229192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.039493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:23:22.22938Z","caller":"traceutil/trace.go:171","msg":"trace[196686273] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:732; }","duration":"135.312879ms","start":"2026-02-20T18:23:22.094016Z","end":"2026-02-20T18:23:22.229329Z","steps":["trace[196686273] 'agreement among raft nodes before linearized reading' (duration: 134.978241ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:26.239346Z","caller":"traceutil/trace.go:171","msg":"trace[1094889661] linearizableReadLoop","detail":"{readStateIndex:773; appliedIndex:772; }","duration":"107.762827ms","start":"2026-02-20T18:23:26.131559Z","end":"2026-02-20T18:23:26.239322Z","steps":["trace[1094889661] 'read index received' (duration: 105.030766ms)","trace[1094889661] 'applied index is now lower than readState.Index' (duration: 2.731311ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:26.23956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.976801ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-20T18:23:26.23959Z","caller":"traceutil/trace.go:171","msg":"trace[1184724564] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:742; }","duration":"108.069554ms","start":"2026-02-20T18:23:26.131512Z","end":"2026-02-20T18:23:26.239581Z","steps":["trace[1184724564] 'agreement among raft nodes before linearized reading' (duration: 107.90039ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:26.239993Z","caller":"traceutil/trace.go:171","msg":"trace[1303670064] transaction","detail":"{read_only:false; response_revision:742; number_of_response:1; }","duration":"252.001019ms","start":"2026-02-20T18:23:25.98798Z","end":"2026-02-20T18:23:26.239981Z","steps":["trace[1303670064] 'process raft request' (duration: 248.736337ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:52.271955Z","caller":"traceutil/trace.go:171","msg":"trace[538950555] transaction","detail":"{read_only:false; response_revision:818; number_of_response:1; }","duration":"376.759175ms","start":"2026-02-20T18:23:51.895172Z","end":"2026-02-20T18:23:52.271931Z","steps":["trace[538950555] 'process raft request' (duration: 376.600222ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:23:52.272187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:51.895155Z","time spent":"376.883067ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:23:52.27353Z","caller":"traceutil/trace.go:171","msg":"trace[733106335] linearizableReadLoop","detail":"{readStateIndex:856; appliedIndex:854; }","duration":"139.536173ms","start":"2026-02-20T18:23:52.133971Z","end":"2026-02-20T18:23:52.273507Z","steps":["trace[733106335] 'read index received' (duration: 137.878712ms)","trace[733106335] 'applied index is now lower than readState.Index' (duration: 1.656501ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:52.273699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.714376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:23:52.273736Z","caller":"traceutil/trace.go:171","msg":"trace[1908777107] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:819; }","duration":"139.796207ms","start":"2026-02-20T18:23:52.13393Z","end":"2026-02-20T18:23:52.273726Z","steps":["trace[1908777107] 'agreement among raft nodes before linearized reading' (duration: 139.666645ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:52.273799Z","caller":"traceutil/trace.go:171","msg":"trace[543049095] transaction","detail":"{read_only:false; response_revision:819; number_of_response:1; }","duration":"147.600973ms","start":"2026-02-20T18:23:52.126097Z","end":"2026-02-20T18:23:52.273698Z","steps":["trace[543049095] 'process raft request' (duration: 147.298267ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:23:52.378469Z","caller":"traceutil/trace.go:171","msg":"trace[1002279168] transaction","detail":"{read_only:false; response_revision:820; number_of_response:1; }","duration":"101.303473ms","start":"2026-02-20T18:23:52.27713Z","end":"2026-02-20T18:23:52.378434Z","steps":["trace[1002279168] 'process raft request' (duration: 51.209001ms)","trace[1002279168] 'compare' (duration: 49.921028ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:23:58.95401Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514195254,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:23:59.454814Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514195254,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:23:59.954974Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514195254,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:00.455651Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514195254,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:00.956954Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514195254,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:01.442282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000389686s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-02-20T18:24:01.442374Z","caller":"traceutil/trace.go:171","msg":"trace[278311089] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000536319s","start":"2026-02-20T18:23:59.441813Z","end":"2026-02-20T18:24:01.442349Z","steps":["trace[278311089] 'agreement among raft nodes before linearized reading' (duration: 2.000426617s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:01.442445Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:59.441792Z","time spent":"2.00063618s","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/02/20 18:24:01 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-02-20T18:24:01.457343Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514195254,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:01.957891Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514195254,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:02.458089Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514195254,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-02-20T18:24:02.524167Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"4.116642429s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:24:02.524585Z","caller":"traceutil/trace.go:171","msg":"trace[1947516926] transaction","detail":"{read_only:false; response_revision:836; number_of_response:1; }","duration":"4.117111787s","start":"2026-02-20T18:23:58.407457Z","end":"2026-02-20T18:24:02.524569Z","steps":["trace[1947516926] 'process raft request' (duration: 4.117006225s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:02.524703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:58.407439Z","time spent":"4.117207599s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:24:02.796253Z","caller":"traceutil/trace.go:171","msg":"trace[1163467403] transaction","detail":"{read_only:false; response_revision:837; number_of_response:1; }","duration":"4.387743384s","start":"2026-02-20T18:23:58.408475Z","end":"2026-02-20T18:24:02.796219Z","steps":["trace[1163467403] 'process raft request' (duration: 4.360749962s)","trace[1163467403] 'compare' (duration: 26.787538ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:02.796435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:58.408464Z","time spent":"4.387889556s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:24:02.800251Z","caller":"traceutil/trace.go:171","msg":"trace[94895793] transaction","detail":"{read_only:false; response_revision:838; number_of_response:1; }","duration":"463.325961ms","start":"2026-02-20T18:24:02.336903Z","end":"2026-02-20T18:24:02.800229Z","steps":["trace[94895793] 'process raft request' (duration: 463.173869ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:02.800282Z","caller":"traceutil/trace.go:171","msg":"trace[2127227091] linearizableReadLoop","detail":"{readStateIndex:877; appliedIndex:873; }","duration":"4.34684054s","start":"2026-02-20T18:23:58.453422Z","end":"2026-02-20T18:24:02.800262Z","steps":["trace[2127227091] 'read index received' (duration: 4.071034262s)","trace[2127227091] 'applied index is now lower than readState.Index' (duration: 275.805418ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:02.800329Z","caller":"traceutil/trace.go:171","msg":"trace[1509074467] transaction","detail":"{read_only:false; response_revision:839; number_of_response:1; }","duration":"280.146335ms","start":"2026-02-20T18:24:02.520166Z","end":"2026-02-20T18:24:02.800312Z","steps":["trace[1509074467] 'process raft request' (duration: 280.026753ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:02.800394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:02.336888Z","time spent":"463.430453ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:02.800473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.347048353s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:24:02.800532Z","caller":"traceutil/trace.go:171","msg":"trace[179558881] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:839; }","duration":"4.347147825s","start":"2026-02-20T18:23:58.453368Z","end":"2026-02-20T18:24:02.800516Z","steps":["trace[179558881] 'agreement among raft nodes before linearized reading' (duration: 4.346988863s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:02.800563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.147338796s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-20T18:24:02.800588Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:58.453348Z","time spent":"4.347227656s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-02-20T18:24:02.800614Z","caller":"traceutil/trace.go:171","msg":"trace[1939053909] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:839; }","duration":"3.147405107s","start":"2026-02-20T18:23:59.653196Z","end":"2026-02-20T18:24:02.800602Z","steps":["trace[1939053909] 'agreement among raft nodes before linearized reading' (duration: 3.147274425s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:02.800665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:59.653189Z","time spent":"3.147467919s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-02-20T18:24:02.80058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.597897011s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:24:02.800674Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.156269585s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:24:02.800727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.384323828s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:02.800746Z","caller":"traceutil/trace.go:171","msg":"trace[1309252159] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:839; }","duration":"3.598142716s","start":"2026-02-20T18:23:59.20256Z","end":"2026-02-20T18:24:02.800703Z","steps":["trace[1309252159] 'agreement among raft nodes before linearized reading' (duration: 3.597941382s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:02.800743Z","caller":"traceutil/trace.go:171","msg":"trace[1884648746] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:839; }","duration":"4.156374737s","start":"2026-02-20T18:23:58.644359Z","end":"2026-02-20T18:24:02.800733Z","steps":["trace[1884648746] 'agreement among raft nodes before linearized reading' (duration: 4.156265455s)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:02.800768Z","caller":"traceutil/trace.go:171","msg":"trace[163223725] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:839; }","duration":"3.384415819s","start":"2026-02-20T18:23:59.416342Z","end":"2026-02-20T18:24:02.800757Z","steps":["trace[163223725] 'agreement among raft nodes before linearized reading' (duration: 3.384354098s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:02.800789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358281255s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:24:02.800811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:59.416318Z","time spent":"3.38448573s","remote":"127.0.0.1:43378","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:24:02.800817Z","caller":"traceutil/trace.go:171","msg":"trace[780172605] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:839; }","duration":"1.358336425s","start":"2026-02-20T18:24:01.442472Z","end":"2026-02-20T18:24:02.800808Z","steps":["trace[780172605] 'agreement among raft nodes before linearized reading' (duration: 1.358295685s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:02.800843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:01.442456Z","time spent":"1.358382626s","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:24:02.80086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:58.644338Z","time spent":"4.156511359s","remote":"127.0.0.1:43158","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:24:02.800549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.316545517s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-02-20T18:24:02.80105Z","caller":"traceutil/trace.go:171","msg":"trace[1177089061] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:839; }","duration":"4.317070306s","start":"2026-02-20T18:23:58.483972Z","end":"2026-02-20T18:24:02.801042Z","steps":["trace[1177089061] 'agreement among raft nodes before linearized reading' (duration: 4.316427094s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:02.800883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:59.20254Z","time spent":"3.598293759s","remote":"127.0.0.1:43204","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:24:02.801132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:23:58.483961Z","time spent":"4.317107337s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-02-20T18:24:02.801282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.683143024s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:24:02.801457Z","caller":"traceutil/trace.go:171","msg":"trace[1688044527] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:839; }","duration":"1.683370839s","start":"2026-02-20T18:24:01.11807Z","end":"2026-02-20T18:24:02.801441Z","steps":["trace[1688044527] 'agreement among raft nodes before linearized reading' (duration: 1.682939161s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:02.801509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:01.118051Z","time spent":"1.683441699s","remote":"127.0.0.1:43476","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:24:03.210801Z","caller":"traceutil/trace.go:171","msg":"trace[1839067679] transaction","detail":"{read_only:false; response_revision:841; number_of_response:1; }","duration":"404.850837ms","start":"2026-02-20T18:24:02.805924Z","end":"2026-02-20T18:24:03.210775Z","steps":["trace[1839067679] 'process raft request' (duration: 380.226519ms)","trace[1839067679] 'compare' (duration: 24.279032ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:03.210934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:02.805901Z","time spent":"404.956029ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:24:03.210928Z","caller":"traceutil/trace.go:171","msg":"trace[1504060413] linearizableReadLoop","detail":"{readStateIndex:881; appliedIndex:879; }","duration":"372.681054ms","start":"2026-02-20T18:24:02.838212Z","end":"2026-02-20T18:24:03.210893Z","steps":["trace[1504060413] 'read index received' (duration: 347.952084ms)","trace[1504060413] 'applied index is now lower than readState.Index' (duration: 24.72774ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:03.211092Z","caller":"traceutil/trace.go:171","msg":"trace[2044713407] transaction","detail":"{read_only:false; response_revision:842; number_of_response:1; }","duration":"403.443962ms","start":"2026-02-20T18:24:02.807623Z","end":"2026-02-20T18:24:03.211067Z","steps":["trace[2044713407] 'process raft request' (duration: 403.096966ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:03.211162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:02.807611Z","time spent":"403.521104ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:03.211167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"403.243609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:03.211239Z","caller":"traceutil/trace.go:171","msg":"trace[538616232] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:842; }","duration":"403.369301ms","start":"2026-02-20T18:24:02.807849Z","end":"2026-02-20T18:24:03.211218Z","steps":["trace[538616232] 'agreement among raft nodes before linearized reading' (duration: 403.218518ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:03.211304Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:02.80784Z","time spent":"403.446932ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:24:03.241631Z","caller":"traceutil/trace.go:171","msg":"trace[576403941] transaction","detail":"{read_only:false; response_revision:843; number_of_response:1; }","duration":"400.669611ms","start":"2026-02-20T18:24:02.840365Z","end":"2026-02-20T18:24:03.241035Z","steps":["trace[576403941] 'process raft request' (duration: 400.536859ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:03.241823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:02.840341Z","time spent":"401.386423ms","remote":"127.0.0.1:43154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:03.550293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.209584ms","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:329940"} {"level":"info","ts":"2026-02-20T18:24:03.550368Z","caller":"traceutil/trace.go:171","msg":"trace[644691852] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:844; }","duration":"216.326795ms","start":"2026-02-20T18:24:03.334024Z","end":"2026-02-20T18:24:03.550351Z","steps":["trace[644691852] 'range keys from in-memory index tree' (duration: 215.544882ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:05.942261Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514195292,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:24:06.196015Z","caller":"traceutil/trace.go:171","msg":"trace[226119197] transaction","detail":"{read_only:false; response_revision:849; number_of_response:1; }","duration":"976.174835ms","start":"2026-02-20T18:24:05.219814Z","end":"2026-02-20T18:24:06.195989Z","steps":["trace[226119197] 'process raft request' (duration: 976.027713ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:06.196159Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:05.219794Z","time spent":"976.290368ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:07.462337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.019903462s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-02-20T18:24:07.462598Z","caller":"traceutil/trace.go:171","msg":"trace[1334038067] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.020181626s","start":"2026-02-20T18:24:05.442369Z","end":"2026-02-20T18:24:07.46255Z","steps":["trace[1334038067] 'agreement among raft nodes before linearized reading' (duration: 2.019926282s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:07.462659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:05.442354Z","time spent":"2.020292468s","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:24:08.129348Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.933515615s","expected-duration":"1s"} {"level":"info","ts":"2026-02-20T18:24:08.202496Z","caller":"traceutil/trace.go:171","msg":"trace[39522915] transaction","detail":"{read_only:false; response_revision:850; number_of_response:1; }","duration":"2.981876204s","start":"2026-02-20T18:24:05.2206Z","end":"2026-02-20T18:24:08.202476Z","steps":["trace[39522915] 'process raft request' (duration: 2.909080771s)","trace[39522915] 'compare' (duration: 72.664761ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:08.202487Z","caller":"traceutil/trace.go:171","msg":"trace[1913649767] linearizableReadLoop","detail":"{readStateIndex:890; appliedIndex:888; }","duration":"2.760279249s","start":"2026-02-20T18:24:05.442186Z","end":"2026-02-20T18:24:08.202465Z","steps":["trace[1913649767] 'read index received' (duration: 753.690495ms)","trace[1913649767] 'applied index is now lower than readState.Index' (duration: 2.006587514s)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:08.202619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.760418912s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-02-20T18:24:08.202641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:05.220569Z","time spent":"2.981982296s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:24:08.202657Z","caller":"traceutil/trace.go:171","msg":"trace[2018528066] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:850; }","duration":"2.760512913s","start":"2026-02-20T18:24:05.442133Z","end":"2026-02-20T18:24:08.202646Z","steps":["trace[2018528066] 'agreement among raft nodes before linearized reading' (duration: 2.760404491s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.202687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:05.442101Z","time spent":"2.760577384s","remote":"127.0.0.1:43546","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":23,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:24:08.202707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.701134654s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4913"} {"level":"info","ts":"2026-02-20T18:24:08.202747Z","caller":"traceutil/trace.go:171","msg":"trace[2142321806] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:850; }","duration":"1.701207425s","start":"2026-02-20T18:24:06.501529Z","end":"2026-02-20T18:24:08.202737Z","steps":["trace[2142321806] 'agreement among raft nodes before linearized reading' (duration: 1.701091563s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.202767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:06.501508Z","time spent":"1.701255316s","remote":"127.0.0.1:43244","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4936,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-02-20T18:24:08.202919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.277499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:08.202954Z","caller":"traceutil/trace.go:171","msg":"trace[1629664147] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:850; }","duration":"739.33963ms","start":"2026-02-20T18:24:07.463606Z","end":"2026-02-20T18:24:08.202945Z","steps":["trace[1629664147] 'agreement among raft nodes before linearized reading' (duration: 739.287379ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.202968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.665446102s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-02-20T18:24:08.202974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:07.463591Z","time spent":"739.3783ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:24:08.202983Z","caller":"traceutil/trace.go:171","msg":"trace[593486098] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:850; }","duration":"1.665500133s","start":"2026-02-20T18:24:06.537479Z","end":"2026-02-20T18:24:08.202979Z","steps":["trace[593486098] 'agreement among raft nodes before linearized reading' (duration: 1.665461673s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.202986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.378960558s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-02-20T18:24:08.20302Z","caller":"traceutil/trace.go:171","msg":"trace[137238144] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:850; }","duration":"1.379018159s","start":"2026-02-20T18:24:06.823991Z","end":"2026-02-20T18:24:08.203009Z","steps":["trace[137238144] 'agreement among raft nodes before linearized reading' (duration: 1.378904127s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.203044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:06.823976Z","time spent":"1.379062659s","remote":"127.0.0.1:43336","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-02-20T18:24:08.203093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.75409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:08.203119Z","caller":"traceutil/trace.go:171","msg":"trace[2087844967] range","detail":"{range_begin:/registry/ingress/; range_end:/registry/ingress0; response_count:0; response_revision:850; }","duration":"637.797671ms","start":"2026-02-20T18:24:07.565314Z","end":"2026-02-20T18:24:08.203111Z","steps":["trace[2087844967] 'agreement among raft nodes before linearized reading' (duration: 637.75665ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.203139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:07.565306Z","time spent":"637.827762ms","remote":"127.0.0.1:43364","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":0,"response size":28,"request content":"key:\"/registry/ingress/\" range_end:\"/registry/ingress0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:24:08.203151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.336649403s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-02-20T18:24:08.203174Z","caller":"traceutil/trace.go:171","msg":"trace[1882182777] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:850; }","duration":"1.336714434s","start":"2026-02-20T18:24:06.866453Z","end":"2026-02-20T18:24:08.203167Z","steps":["trace[1882182777] 'agreement among raft nodes before linearized reading' (duration: 1.336647153s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.203194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:06.866431Z","time spent":"1.336758235s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-02-20T18:24:08.203206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.111766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:08.203224Z","caller":"traceutil/trace.go:171","msg":"trace[722932463] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:850; }","duration":"638.148508ms","start":"2026-02-20T18:24:07.565071Z","end":"2026-02-20T18:24:08.20322Z","steps":["trace[722932463] 'agreement among raft nodes before linearized reading' (duration: 638.120567ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.203237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:07.565061Z","time spent":"638.172418ms","remote":"127.0.0.1:43214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:24:08.202996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:06.537458Z","time spent":"1.665534924s","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:24:08.203322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.208308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-02-20T18:24:08.203342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"638.379541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:08.203355Z","caller":"traceutil/trace.go:171","msg":"trace[2012823838] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:850; }","duration":"638.27953ms","start":"2026-02-20T18:24:07.565065Z","end":"2026-02-20T18:24:08.203344Z","steps":["trace[2012823838] 'agreement among raft nodes before linearized reading' (duration: 638.232149ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:08.203374Z","caller":"traceutil/trace.go:171","msg":"trace[35419306] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:850; }","duration":"638.450642ms","start":"2026-02-20T18:24:07.564915Z","end":"2026-02-20T18:24:08.203366Z","steps":["trace[35419306] 'agreement among raft nodes before linearized reading' (duration: 638.396341ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:08.203394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:07.565038Z","time spent":"638.34562ms","remote":"127.0.0.1:43224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:24:08.2034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:07.564896Z","time spent":"638.497602ms","remote":"127.0.0.1:43496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"info","ts":"2026-02-20T18:24:10.113417Z","caller":"traceutil/trace.go:171","msg":"trace[1835588716] linearizableReadLoop","detail":"{readStateIndex:897; appliedIndex:896; }","duration":"126.844067ms","start":"2026-02-20T18:24:09.986507Z","end":"2026-02-20T18:24:10.113352Z","steps":["trace[1835588716] 'read index received' (duration: 39.089806ms)","trace[1835588716] 'applied index is now lower than readState.Index' (duration: 87.753371ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:10.113442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.908788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-02-20T18:24:10.113516Z","caller":"traceutil/trace.go:171","msg":"trace[2111757428] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:855; }","duration":"127.047591ms","start":"2026-02-20T18:24:09.986458Z","end":"2026-02-20T18:24:10.113505Z","steps":["trace[2111757428] 'agreement among raft nodes before linearized reading' (duration: 126.901008ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:10.375461Z","caller":"traceutil/trace.go:171","msg":"trace[1760184966] transaction","detail":"{read_only:false; response_revision:859; number_of_response:1; }","duration":"125.452522ms","start":"2026-02-20T18:24:10.249988Z","end":"2026-02-20T18:24:10.37544Z","steps":["trace[1760184966] 'process raft request' (duration: 122.609314ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:10.375682Z","caller":"traceutil/trace.go:171","msg":"trace[1360052061] transaction","detail":"{read_only:false; response_revision:860; number_of_response:1; }","duration":"125.622476ms","start":"2026-02-20T18:24:10.250025Z","end":"2026-02-20T18:24:10.375648Z","steps":["trace[1360052061] 'process raft request' (duration: 125.369811ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:12.451551Z","caller":"traceutil/trace.go:171","msg":"trace[1910351440] linearizableReadLoop","detail":"{readStateIndex:903; appliedIndex:902; }","duration":"201.892448ms","start":"2026-02-20T18:24:12.249636Z","end":"2026-02-20T18:24:12.451529Z","steps":["trace[1910351440] 'read index received' (duration: 201.690635ms)","trace[1910351440] 'applied index is now lower than readState.Index' (duration: 200.843µs)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:12.451772Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.117522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-02-20T18:24:12.451819Z","caller":"traceutil/trace.go:171","msg":"trace[2090188924] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:861; }","duration":"202.206304ms","start":"2026-02-20T18:24:12.249602Z","end":"2026-02-20T18:24:12.451808Z","steps":["trace[2090188924] 'agreement among raft nodes before linearized reading' (duration: 202.043861ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:12.451578Z","caller":"traceutil/trace.go:171","msg":"trace[2061540543] transaction","detail":"{read_only:false; response_revision:861; number_of_response:1; }","duration":"228.374518ms","start":"2026-02-20T18:24:12.223178Z","end":"2026-02-20T18:24:12.451553Z","steps":["trace[2061540543] 'process raft request' (duration: 228.199405ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:12.452434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.892358ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-02-20T18:24:12.452479Z","caller":"traceutil/trace.go:171","msg":"trace[1676685202] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:861; }","duration":"122.965319ms","start":"2026-02-20T18:24:12.329501Z","end":"2026-02-20T18:24:12.452466Z","steps":["trace[1676685202] 'agreement among raft nodes before linearized reading' (duration: 122.157986ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:12.634689Z","caller":"traceutil/trace.go:171","msg":"trace[1053645567] transaction","detail":"{read_only:false; response_revision:865; number_of_response:1; }","duration":"178.326178ms","start":"2026-02-20T18:24:12.456336Z","end":"2026-02-20T18:24:12.634662Z","steps":["trace[1053645567] 'process raft request' (duration: 178.219176ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:12.634839Z","caller":"traceutil/trace.go:171","msg":"trace[76467121] transaction","detail":"{read_only:false; response_revision:863; number_of_response:1; }","duration":"179.137161ms","start":"2026-02-20T18:24:12.455645Z","end":"2026-02-20T18:24:12.634782Z","steps":["trace[76467121] 'process raft request' (duration: 178.762844ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:12.634977Z","caller":"traceutil/trace.go:171","msg":"trace[254788870] transaction","detail":"{read_only:false; response_revision:864; number_of_response:1; }","duration":"178.954718ms","start":"2026-02-20T18:24:12.456009Z","end":"2026-02-20T18:24:12.634964Z","steps":["trace[254788870] 'process raft request' (duration: 178.50819ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:21.686693Z","caller":"traceutil/trace.go:171","msg":"trace[607855671] transaction","detail":"{read_only:false; response_revision:915; number_of_response:1; }","duration":"349.291334ms","start":"2026-02-20T18:24:21.337357Z","end":"2026-02-20T18:24:21.686648Z","steps":["trace[607855671] 'process raft request' (duration: 257.453016ms)","trace[607855671] 'compare' (duration: 91.531113ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:21.686677Z","caller":"traceutil/trace.go:171","msg":"trace[1485751659] linearizableReadLoop","detail":"{readStateIndex:959; appliedIndex:958; }","duration":"348.549323ms","start":"2026-02-20T18:24:21.338087Z","end":"2026-02-20T18:24:21.686636Z","steps":["trace[1485751659] 'read index received' (duration: 256.797996ms)","trace[1485751659] 'applied index is now lower than readState.Index' (duration: 91.749707ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:21.686864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:21.337335Z","time spent":"349.458937ms","remote":"127.0.0.1:43154","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":655,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-02-20T18:24:21.68692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.835877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:1536"} {"level":"info","ts":"2026-02-20T18:24:21.686972Z","caller":"traceutil/trace.go:171","msg":"trace[848068705] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:915; }","duration":"348.99246ms","start":"2026-02-20T18:24:21.337965Z","end":"2026-02-20T18:24:21.686958Z","steps":["trace[848068705] 'agreement among raft nodes before linearized reading' (duration: 348.762316ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:21.687008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:21.337892Z","time spent":"349.109071ms","remote":"127.0.0.1:43246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":1559,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"warn","ts":"2026-02-20T18:24:21.687506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.531225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" ","response":"range_response_count:1 size:1465"} {"level":"info","ts":"2026-02-20T18:24:21.687582Z","caller":"traceutil/trace.go:171","msg":"trace[328638511] range","detail":"{range_begin:/registry/persistentvolumeclaims/default/test-pvc; range_end:; response_count:1; response_revision:915; }","duration":"316.673626ms","start":"2026-02-20T18:24:21.370892Z","end":"2026-02-20T18:24:21.687565Z","steps":["trace[328638511] 'agreement among raft nodes before linearized reading' (duration: 316.128288ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:21.687631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:21.370829Z","time spent":"316.790748ms","remote":"127.0.0.1:43224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":1488,"request content":"key:\"/registry/persistentvolumeclaims/default/test-pvc\" "} {"level":"info","ts":"2026-02-20T18:24:23.608942Z","caller":"traceutil/trace.go:171","msg":"trace[1778363258] transaction","detail":"{read_only:false; response_revision:926; number_of_response:1; }","duration":"136.661599ms","start":"2026-02-20T18:24:23.472254Z","end":"2026-02-20T18:24:23.608916Z","steps":["trace[1778363258] 'process raft request' (duration: 136.472356ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:25.573244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.32238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:25.573329Z","caller":"traceutil/trace.go:171","msg":"trace[1722019319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:932; }","duration":"119.442762ms","start":"2026-02-20T18:24:25.453864Z","end":"2026-02-20T18:24:25.573307Z","steps":["trace[1722019319] 'range keys from in-memory index tree' (duration: 119.245168ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:32.957267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.915424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-02-20T18:24:32.957519Z","caller":"traceutil/trace.go:171","msg":"trace[435029300] linearizableReadLoop","detail":"{readStateIndex:1001; appliedIndex:999; }","duration":"223.952528ms","start":"2026-02-20T18:24:32.733542Z","end":"2026-02-20T18:24:32.957495Z","steps":["trace[435029300] 'read index received' (duration: 691.271µs)","trace[435029300] 'applied index is now lower than readState.Index' (duration: 223.259107ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:32.957567Z","caller":"traceutil/trace.go:171","msg":"trace[98223980] transaction","detail":"{read_only:false; response_revision:955; number_of_response:1; }","duration":"296.558236ms","start":"2026-02-20T18:24:32.66098Z","end":"2026-02-20T18:24:32.957539Z","steps":["trace[98223980] 'process raft request' (duration: 180.273006ms)","trace[98223980] 'compare' (duration: 115.767232ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:32.95777Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.758086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/test-pv\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:32.957882Z","caller":"traceutil/trace.go:171","msg":"trace[816158164] range","detail":"{range_begin:/registry/persistentvolumes/test-pv; range_end:; response_count:0; response_revision:955; }","duration":"194.899818ms","start":"2026-02-20T18:24:32.762964Z","end":"2026-02-20T18:24:32.957863Z","steps":["trace[816158164] 'agreement among raft nodes before linearized reading' (duration: 194.761936ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:32.958049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.148997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-02-20T18:24:32.958056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.670635ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-20T18:24:32.957773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.195671ms","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":"info","ts":"2026-02-20T18:24:32.958125Z","caller":"traceutil/trace.go:171","msg":"trace[1659954463] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:955; }","duration":"157.296769ms","start":"2026-02-20T18:24:32.800811Z","end":"2026-02-20T18:24:32.958108Z","steps":["trace[1659954463] 'agreement among raft nodes before linearized reading' (duration: 157.084086ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:32.958183Z","caller":"traceutil/trace.go:171","msg":"trace[2118517287] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:955; }","duration":"155.779936ms","start":"2026-02-20T18:24:32.802346Z","end":"2026-02-20T18:24:32.958126Z","steps":["trace[2118517287] 'agreement among raft nodes before linearized reading' (duration: 155.475492ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:32.958512Z","caller":"traceutil/trace.go:171","msg":"trace[1020818284] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:955; }","duration":"224.604918ms","start":"2026-02-20T18:24:32.733532Z","end":"2026-02-20T18:24:32.958137Z","steps":["trace[1020818284] 'agreement among raft nodes before linearized reading' (duration: 224.156451ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:32.978599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.479542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-20T18:24:32.978665Z","caller":"traceutil/trace.go:171","msg":"trace[117148248] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:955; }","duration":"202.598525ms","start":"2026-02-20T18:24:32.776034Z","end":"2026-02-20T18:24:32.978633Z","steps":["trace[117148248] 'agreement among raft nodes before linearized reading' (duration: 181.891821ms)","trace[117148248] 'range keys from bolt db' (duration: 20.436199ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:34.211453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.028861836s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:34.211558Z","caller":"traceutil/trace.go:171","msg":"trace[1567345130] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"1.029026998s","start":"2026-02-20T18:24:33.182513Z","end":"2026-02-20T18:24:34.21154Z","steps":["trace[1567345130] 'range keys from in-memory index tree' (duration: 1.028711944s)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:34.21162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:33.182477Z","time spent":"1.0291142s","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:24:34.211718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"849.645486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:34.211795Z","caller":"traceutil/trace.go:171","msg":"trace[168010677] range","detail":"{range_begin:/registry/csistoragecapacities/; range_end:/registry/csistoragecapacities0; response_count:0; response_revision:958; }","duration":"849.768919ms","start":"2026-02-20T18:24:33.362008Z","end":"2026-02-20T18:24:34.211777Z","steps":["trace[168010677] 'count revisions from in-memory index tree' (duration: 849.501575ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:34.211847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:33.361989Z","time spent":"849.841569ms","remote":"127.0.0.1:43452","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/csistoragecapacities/\" range_end:\"/registry/csistoragecapacities0\" count_only:true "} {"level":"warn","ts":"2026-02-20T18:24:34.212028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"770.940521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:34.212103Z","caller":"traceutil/trace.go:171","msg":"trace[1717280325] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:958; }","duration":"771.060593ms","start":"2026-02-20T18:24:33.441022Z","end":"2026-02-20T18:24:34.212083Z","steps":["trace[1717280325] 'range keys from in-memory index tree' (duration: 770.772079ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:34.212149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:33.441003Z","time spent":"771.137274ms","remote":"127.0.0.1:43048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-02-20T18:24:34.212382Z","caller":"traceutil/trace.go:171","msg":"trace[485427282] transaction","detail":"{read_only:false; response_revision:960; number_of_response:1; }","duration":"503.012214ms","start":"2026-02-20T18:24:33.709355Z","end":"2026-02-20T18:24:34.212367Z","steps":["trace[485427282] 'process raft request' (duration: 502.909333ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:34.212501Z","caller":"traceutil/trace.go:171","msg":"trace[1373672103] transaction","detail":"{read_only:false; response_revision:959; number_of_response:1; }","duration":"608.719697ms","start":"2026-02-20T18:24:33.603761Z","end":"2026-02-20T18:24:34.212481Z","steps":["trace[1373672103] 'process raft request' (duration: 536.26819ms)","trace[1373672103] 'compare' (duration: 71.830998ms)"],"step_count":2} {"level":"warn","ts":"2026-02-20T18:24:34.212551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:33.709328Z","time spent":"503.126246ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:34.212584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:33.603706Z","time spent":"608.837459ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:35.53664Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514195592,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:24:35.727402Z","caller":"traceutil/trace.go:171","msg":"trace[502146066] linearizableReadLoop","detail":"{readStateIndex:1015; appliedIndex:1013; }","duration":"691.817369ms","start":"2026-02-20T18:24:35.035561Z","end":"2026-02-20T18:24:35.727378Z","steps":["trace[502146066] 'read index received' (duration: 688.728303ms)","trace[502146066] 'applied index is now lower than readState.Index' (duration: 3.087906ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:35.72753Z","caller":"traceutil/trace.go:171","msg":"trace[31516955] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"704.399167ms","start":"2026-02-20T18:24:35.02312Z","end":"2026-02-20T18:24:35.72752Z","steps":["trace[31516955] 'process raft request' (duration: 701.151959ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:35.727639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:35.023099Z","time spent":"704.464148ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:35.727778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"284.934958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:35.727813Z","caller":"traceutil/trace.go:171","msg":"trace[792724397] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:968; }","duration":"285.012599ms","start":"2026-02-20T18:24:35.442791Z","end":"2026-02-20T18:24:35.727803Z","steps":["trace[792724397] 'agreement among raft nodes before linearized reading' (duration: 284.959918ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:35.727798Z","caller":"traceutil/trace.go:171","msg":"trace[179374813] transaction","detail":"{read_only:false; response_revision:968; number_of_response:1; }","duration":"704.276175ms","start":"2026-02-20T18:24:35.023491Z","end":"2026-02-20T18:24:35.727767Z","steps":["trace[179374813] 'process raft request' (duration: 703.798378ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:35.727972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:35.023477Z","time spent":"704.400916ms","remote":"127.0.0.1:43336","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-02-20T18:24:35.728258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"692.692052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2771"} {"level":"info","ts":"2026-02-20T18:24:35.728514Z","caller":"traceutil/trace.go:171","msg":"trace[1625258500] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:968; }","duration":"692.795963ms","start":"2026-02-20T18:24:35.035521Z","end":"2026-02-20T18:24:35.728317Z","steps":["trace[1625258500] 'agreement among raft nodes before linearized reading' (duration: 692.182424ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:35.72863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:35.035506Z","time spent":"693.108088ms","remote":"127.0.0.1:43246","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2794,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-02-20T18:24:38.180386Z","caller":"traceutil/trace.go:171","msg":"trace[1610840933] transaction","detail":"{read_only:false; response_revision:974; number_of_response:1; }","duration":"444.316678ms","start":"2026-02-20T18:24:37.736044Z","end":"2026-02-20T18:24:38.180361Z","steps":["trace[1610840933] 'process raft request' (duration: 443.967683ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:38.180581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:37.736013Z","time spent":"444.46858ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:24:38.181283Z","caller":"traceutil/trace.go:171","msg":"trace[1794201871] transaction","detail":"{read_only:false; response_revision:975; number_of_response:1; }","duration":"445.226091ms","start":"2026-02-20T18:24:37.736039Z","end":"2026-02-20T18:24:38.181265Z","steps":["trace[1794201871] 'process raft request' (duration: 445.15247ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:38.181805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:37.736009Z","time spent":"445.718568ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-02-20T18:24:39.088093Z","caller":"traceutil/trace.go:171","msg":"trace[1904833415] transaction","detail":"{read_only:false; response_revision:976; number_of_response:1; }","duration":"328.925024ms","start":"2026-02-20T18:24:38.759136Z","end":"2026-02-20T18:24:39.088061Z","steps":["trace[1904833415] 'process raft request' (duration: 328.606409ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:39.088261Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:38.759114Z","time spent":"329.058347ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-02-20T18:24:40.684676Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782674884514195626,"retry-timeout":"500ms"} {"level":"info","ts":"2026-02-20T18:24:40.814873Z","caller":"traceutil/trace.go:171","msg":"trace[973296388] linearizableReadLoop","detail":"{readStateIndex:1028; appliedIndex:1026; }","duration":"630.666014ms","start":"2026-02-20T18:24:40.184181Z","end":"2026-02-20T18:24:40.814847Z","steps":["trace[973296388] 'read index received' (duration: 625.180333ms)","trace[973296388] 'applied index is now lower than readState.Index' (duration: 5.485071ms)"],"step_count":2} {"level":"info","ts":"2026-02-20T18:24:40.814922Z","caller":"traceutil/trace.go:171","msg":"trace[864669019] transaction","detail":"{read_only:false; response_revision:979; number_of_response:1; }","duration":"184.201841ms","start":"2026-02-20T18:24:40.630691Z","end":"2026-02-20T18:24:40.814892Z","steps":["trace[864669019] 'process raft request' (duration: 184.039188ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:40.815055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"630.853467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-02-20T18:24:40.815089Z","caller":"traceutil/trace.go:171","msg":"trace[131560481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:979; }","duration":"630.944888ms","start":"2026-02-20T18:24:40.184127Z","end":"2026-02-20T18:24:40.815072Z","steps":["trace[131560481] 'agreement among raft nodes before linearized reading' (duration: 630.807956ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:40.815113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:40.184102Z","time spent":"631.005819ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-02-20T18:24:40.815183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"629.178342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-02-20T18:24:40.815205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.968997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-02-20T18:24:40.815239Z","caller":"traceutil/trace.go:171","msg":"trace[1908917061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:979; }","duration":"373.09822ms","start":"2026-02-20T18:24:40.44213Z","end":"2026-02-20T18:24:40.815228Z","steps":["trace[1908917061] 'agreement among raft nodes before linearized reading' (duration: 372.978138ms)"],"step_count":1} {"level":"info","ts":"2026-02-20T18:24:40.815239Z","caller":"traceutil/trace.go:171","msg":"trace[1885262045] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:979; }","duration":"629.320334ms","start":"2026-02-20T18:24:40.185896Z","end":"2026-02-20T18:24:40.815217Z","steps":["trace[1885262045] 'agreement among raft nodes before linearized reading' (duration: 629.116201ms)"],"step_count":1} {"level":"warn","ts":"2026-02-20T18:24:40.815266Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:40.44211Z","time spent":"373.14939ms","remote":"127.0.0.1:43034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-02-20T18:24:40.815288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-02-20T18:24:40.185878Z","time spent":"629.403145ms","remote":"127.0.0.1:43336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "}