{"level":"warn","ts":"2026-04-12T20:40:47.088183Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-12T20:40:47.088261Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.69:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.69:2380","--initial-cluster=instance=https://199.204.45.69:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.69:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.69: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-04-12T20:40:47.088318Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-12T20:40:47.088326Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-12T20:40:47.088342Z","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-04-12T20:40:47.088964Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"]} {"level":"info","ts":"2026-04-12T20:40:47.08904Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.69: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-04-12T20:40:47.095093Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.875746ms"} {"level":"info","ts":"2026-04-12T20:40:47.10083Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fe334179879fd0aa","cluster-id":"d79a8cccc23ced73"} {"level":"info","ts":"2026-04-12T20:40:47.100956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=()"} {"level":"info","ts":"2026-04-12T20:40:47.101068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 0"} {"level":"info","ts":"2026-04-12T20:40:47.101189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fe334179879fd0aa [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-12T20:40:47.101265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became follower at term 1"} {"level":"info","ts":"2026-04-12T20:40:47.10135Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"warn","ts":"2026-04-12T20:40:47.108895Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-12T20:40:47.110232Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-12T20:40:47.111277Z","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-04-12T20:40:47.113748Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fe334179879fd0aa","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-12T20:40:47.11394Z","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-04-12T20:40:47.114377Z","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-04-12T20:40:47.114529Z","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-04-12T20:40:47.114281Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fe334179879fd0aa","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-12T20:40:47.116053Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa switched to configuration voters=(18317056099668185258)"} {"level":"info","ts":"2026-04-12T20:40:47.116362Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","added-peer-id":"fe334179879fd0aa","added-peer-peer-urls":["https://199.204.45.69:2380"]} {"level":"info","ts":"2026-04-12T20:40:47.117992Z","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-04-12T20:40:47.118132Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-12T20:40:47.118155Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.69:2380"} {"level":"info","ts":"2026-04-12T20:40:47.119463Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fe334179879fd0aa","initial-advertise-peer-urls":["https://199.204.45.69:2380"],"listen-peer-urls":["https://199.204.45.69:2380"],"advertise-client-urls":["https://199.204.45.69:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.69:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-12T20:40:47.119495Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-12T20:40:47.302345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa is starting a new election at term 1"} {"level":"info","ts":"2026-04-12T20:40:47.302408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became pre-candidate at term 1"} {"level":"info","ts":"2026-04-12T20:40:47.302437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgPreVoteResp from fe334179879fd0aa at term 1"} {"level":"info","ts":"2026-04-12T20:40:47.302452Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became candidate at term 2"} {"level":"info","ts":"2026-04-12T20:40:47.302462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa received MsgVoteResp from fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-12T20:40:47.302473Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fe334179879fd0aa became leader at term 2"} {"level":"info","ts":"2026-04-12T20:40:47.302485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fe334179879fd0aa elected leader fe334179879fd0aa at term 2"} {"level":"info","ts":"2026-04-12T20:40:47.303204Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fe334179879fd0aa","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.69:2379]}","request-path":"/0/members/fe334179879fd0aa/attributes","cluster-id":"d79a8cccc23ced73","publish-timeout":"7s"} {"level":"info","ts":"2026-04-12T20:40:47.303234Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:40:47.303253Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:40:47.303494Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-12T20:40:47.303623Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-12T20:40:47.303649Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-12T20:40:47.305659Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d79a8cccc23ced73","local-member-id":"fe334179879fd0aa","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:40:47.305737Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:40:47.305767Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-12T20:40:47.306682Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.69:2379"} {"level":"info","ts":"2026-04-12T20:40:47.307276Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-12T20:40:48.977823Z","caller":"traceutil/trace.go:171","msg":"trace[621778240] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"100.757789ms","start":"2026-04-12T20:40:48.877045Z","end":"2026-04-12T20:40:48.977803Z","steps":["trace[621778240] 'process raft request' (duration: 100.489407ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:49.037928Z","caller":"traceutil/trace.go:171","msg":"trace[1782706922] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"147.528693ms","start":"2026-04-12T20:40:48.890385Z","end":"2026-04-12T20:40:49.037914Z","steps":["trace[1782706922] 'read index received' (duration: 87.166802ms)","trace[1782706922] 'applied index is now lower than readState.Index' (duration: 60.361391ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:40:49.038122Z","caller":"traceutil/trace.go:171","msg":"trace[1552106855] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"160.909789ms","start":"2026-04-12T20:40:48.877195Z","end":"2026-04-12T20:40:49.038105Z","steps":["trace[1552106855] 'process raft request' (duration: 159.923711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:49.038539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.226395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-12T20:40:49.03862Z","caller":"traceutil/trace.go:171","msg":"trace[2050015930] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"113.315055ms","start":"2026-04-12T20:40:48.92529Z","end":"2026-04-12T20:40:49.038605Z","steps":["trace[2050015930] 'agreement among raft nodes before linearized reading' (duration: 113.050153ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:49.038606Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.197708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-12T20:40:49.038715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.65046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-12T20:40:49.038746Z","caller":"traceutil/trace.go:171","msg":"trace[1019370634] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"112.70048ms","start":"2026-04-12T20:40:48.926034Z","end":"2026-04-12T20:40:49.038735Z","steps":["trace[1019370634] 'agreement among raft nodes before linearized reading' (duration: 112.66149ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:49.038844Z","caller":"traceutil/trace.go:171","msg":"trace[624487447] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"148.360979ms","start":"2026-04-12T20:40:48.890373Z","end":"2026-04-12T20:40:49.038734Z","steps":["trace[624487447] 'agreement among raft nodes before linearized reading' (duration: 148.177568ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:50.580135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.563723ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-04-12T20:40:50.580252Z","caller":"traceutil/trace.go:171","msg":"trace[1808724760] transaction","detail":"{read_only:false; response_revision:83; number_of_response:1; }","duration":"335.824895ms","start":"2026-04-12T20:40:50.244409Z","end":"2026-04-12T20:40:50.580234Z","steps":["trace[1808724760] 'process raft request' (duration: 87.581677ms)","trace[1808724760] 'compare' (duration: 247.451232ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:40:50.580308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:50.244397Z","time spent":"335.888425ms","remote":"127.0.0.1:50252","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":827,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:40:50.897573Z","caller":"traceutil/trace.go:171","msg":"trace[1087215683] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"109.458546ms","start":"2026-04-12T20:40:50.788075Z","end":"2026-04-12T20:40:50.897533Z","steps":["trace[1087215683] 'process raft request' (duration: 73.933881ms)","trace[1087215683] 'compare' (duration: 35.341594ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:40:51.940594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.703033ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:40:51.940762Z","caller":"traceutil/trace.go:171","msg":"trace[1356031383] linearizableReadLoop","detail":"{readStateIndex:179; appliedIndex:178; }","duration":"125.442614ms","start":"2026-04-12T20:40:51.815296Z","end":"2026-04-12T20:40:51.940739Z","steps":["trace[1356031383] 'read index received' (duration: 15.195617ms)","trace[1356031383] 'applied index is now lower than readState.Index' (duration: 110.245187ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:40:51.94089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.599295ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:40:51.940973Z","caller":"traceutil/trace.go:171","msg":"trace[773653794] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:service-controller; range_end:; response_count:0; response_revision:175; }","duration":"125.700526ms","start":"2026-04-12T20:40:51.815248Z","end":"2026-04-12T20:40:51.940948Z","steps":["trace[773653794] 'agreement among raft nodes before linearized reading' (duration: 125.595105ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:51.940986Z","caller":"traceutil/trace.go:171","msg":"trace[586072771] transaction","detail":"{read_only:false; response_revision:175; number_of_response:1; }","duration":"138.212682ms","start":"2026-04-12T20:40:51.802548Z","end":"2026-04-12T20:40:51.940761Z","steps":["trace[586072771] 'process raft request' (duration: 28.018265ms)","trace[586072771] 'compare' (duration: 109.489042ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:40:52.448912Z","caller":"traceutil/trace.go:171","msg":"trace[6822831] transaction","detail":"{read_only:false; response_revision:185; number_of_response:1; }","duration":"148.06782ms","start":"2026-04-12T20:40:52.300804Z","end":"2026-04-12T20:40:52.448871Z","steps":["trace[6822831] 'process raft request' (duration: 105.942589ms)","trace[6822831] 'compare' (duration: 41.92036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:40:52.842881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.179486ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:40:52.843037Z","caller":"traceutil/trace.go:171","msg":"trace[2031089337] transaction","detail":"{read_only:false; response_revision:199; number_of_response:1; }","duration":"135.889578ms","start":"2026-04-12T20:40:52.707085Z","end":"2026-04-12T20:40:52.842975Z","steps":["trace[2031089337] 'process raft request' (duration: 35.537791ms)","trace[2031089337] 'compare' (duration: 100.051995ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:40:53.002657Z","caller":"traceutil/trace.go:171","msg":"trace[302326784] transaction","detail":"{read_only:false; response_revision:200; number_of_response:1; }","duration":"152.408963ms","start":"2026-04-12T20:40:52.850221Z","end":"2026-04-12T20:40:53.00263Z","steps":["trace[302326784] 'process raft request' (duration: 150.867382ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:54.551961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.396089ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:40:54.552247Z","caller":"traceutil/trace.go:171","msg":"trace[274937143] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"137.741219ms","start":"2026-04-12T20:40:54.414474Z","end":"2026-04-12T20:40:54.552216Z","steps":["trace[274937143] 'process raft request' (duration: 137.619329ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:54.552321Z","caller":"traceutil/trace.go:171","msg":"trace[1950455998] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"161.62021ms","start":"2026-04-12T20:40:54.390658Z","end":"2026-04-12T20:40:54.552278Z","steps":["trace[1950455998] 'process raft request' (duration: 44.814289ms)","trace[1950455998] 'compare' (duration: 116.225457ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:40:54.981165Z","caller":"traceutil/trace.go:171","msg":"trace[127550482] transaction","detail":"{read_only:false; response_revision:259; number_of_response:1; }","duration":"183.238874ms","start":"2026-04-12T20:40:54.797886Z","end":"2026-04-12T20:40:54.981125Z","steps":["trace[127550482] 'process raft request' (duration: 167.733057ms)","trace[127550482] 'compare' (duration: 15.149464ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:40:54.981266Z","caller":"traceutil/trace.go:171","msg":"trace[2048433949] linearizableReadLoop","detail":"{readStateIndex:265; appliedIndex:264; }","duration":"176.292401ms","start":"2026-04-12T20:40:54.80465Z","end":"2026-04-12T20:40:54.980943Z","steps":["trace[2048433949] 'read index received' (duration: 160.895335ms)","trace[2048433949] 'applied index is now lower than readState.Index' (duration: 15.396036ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:40:54.981537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.861626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18a5b6a49f93d8d4\" ","response":"range_response_count:1 size:657"} {"level":"info","ts":"2026-04-12T20:40:54.981617Z","caller":"traceutil/trace.go:171","msg":"trace[1469274456] range","detail":"{range_begin:/registry/events/default/instance.18a5b6a49f93d8d4; range_end:; response_count:1; response_revision:259; }","duration":"176.981347ms","start":"2026-04-12T20:40:54.804616Z","end":"2026-04-12T20:40:54.981597Z","steps":["trace[1469274456] 'agreement among raft nodes before linearized reading' (duration: 176.675344ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:54.98174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.250029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/namespace-controller\" ","response":"range_response_count:1 size:205"} {"level":"info","ts":"2026-04-12T20:40:54.981801Z","caller":"traceutil/trace.go:171","msg":"trace[893931937] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/namespace-controller; range_end:; response_count:1; response_revision:259; }","duration":"169.354839ms","start":"2026-04-12T20:40:54.812433Z","end":"2026-04-12T20:40:54.981788Z","steps":["trace[893931937] 'agreement among raft nodes before linearized reading' (duration: 169.183548ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:57.735527Z","caller":"traceutil/trace.go:171","msg":"trace[583654102] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"113.517645ms","start":"2026-04-12T20:40:57.621982Z","end":"2026-04-12T20:40:57.735499Z","steps":["trace[583654102] 'process raft request' (duration: 26.586539ms)","trace[583654102] 'compare' (duration: 86.734045ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:40:57.926905Z","caller":"traceutil/trace.go:171","msg":"trace[1692512230] linearizableReadLoop","detail":"{readStateIndex:345; appliedIndex:344; }","duration":"163.449205ms","start":"2026-04-12T20:40:57.763431Z","end":"2026-04-12T20:40:57.92688Z","steps":["trace[1692512230] 'read index received' (duration: 163.282724ms)","trace[1692512230] 'applied index is now lower than readState.Index' (duration: 165.461µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:40:57.927136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.686677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-12T20:40:57.927178Z","caller":"traceutil/trace.go:171","msg":"trace[1113088144] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:331; }","duration":"163.809847ms","start":"2026-04-12T20:40:57.763356Z","end":"2026-04-12T20:40:57.927166Z","steps":["trace[1113088144] 'agreement among raft nodes before linearized reading' (duration: 163.679786ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:57.927307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.128846ms","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-04-12T20:40:57.927375Z","caller":"traceutil/trace.go:171","msg":"trace[1039479027] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:331; }","duration":"115.233617ms","start":"2026-04-12T20:40:57.812125Z","end":"2026-04-12T20:40:57.927359Z","steps":["trace[1039479027] 'agreement among raft nodes before linearized reading' (duration: 115.115346ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:57.92703Z","caller":"traceutil/trace.go:171","msg":"trace[1104659344] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"183.731416ms","start":"2026-04-12T20:40:57.743263Z","end":"2026-04-12T20:40:57.926994Z","steps":["trace[1104659344] 'process raft request' (duration: 183.477754ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:58.053211Z","caller":"traceutil/trace.go:171","msg":"trace[132375627] transaction","detail":"{read_only:false; response_revision:333; number_of_response:1; }","duration":"114.875052ms","start":"2026-04-12T20:40:57.938277Z","end":"2026-04-12T20:40:58.053153Z","steps":["trace[132375627] 'process raft request' (duration: 114.146387ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:58.053389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.978083ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:40:58.053414Z","caller":"traceutil/trace.go:171","msg":"trace[178492033] transaction","detail":"{read_only:false; response_revision:334; number_of_response:1; }","duration":"114.892532ms","start":"2026-04-12T20:40:57.938492Z","end":"2026-04-12T20:40:58.053385Z","steps":["trace[178492033] 'process raft request' (duration: 114.6046ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:58.053457Z","caller":"traceutil/trace.go:171","msg":"trace[474207749] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:334; }","duration":"111.057313ms","start":"2026-04-12T20:40:57.942391Z","end":"2026-04-12T20:40:58.053448Z","steps":["trace[474207749] 'agreement among raft nodes before linearized reading' (duration: 110.934572ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:58.053287Z","caller":"traceutil/trace.go:171","msg":"trace[1591928661] linearizableReadLoop","detail":"{readStateIndex:348; appliedIndex:345; }","duration":"110.872442ms","start":"2026-04-12T20:40:57.942398Z","end":"2026-04-12T20:40:58.05327Z","steps":["trace[1591928661] 'read index received' (duration: 29.472089ms)","trace[1591928661] 'applied index is now lower than readState.Index' (duration: 81.399333ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:40:58.2628Z","caller":"traceutil/trace.go:171","msg":"trace[1752344213] transaction","detail":"{read_only:false; response_revision:340; number_of_response:1; }","duration":"102.894601ms","start":"2026-04-12T20:40:58.159874Z","end":"2026-04-12T20:40:58.262769Z","steps":["trace[1752344213] 'process raft request' (duration: 102.628529ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:58.52276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.550148ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:40:58.522921Z","caller":"traceutil/trace.go:171","msg":"trace[814160381] transaction","detail":"{read_only:false; response_revision:343; number_of_response:1; }","duration":"251.588781ms","start":"2026-04-12T20:40:58.271316Z","end":"2026-04-12T20:40:58.522905Z","steps":["trace[814160381] 'process raft request' (duration: 251.536161ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:58.522974Z","caller":"traceutil/trace.go:171","msg":"trace[1724727377] transaction","detail":"{read_only:false; response_revision:342; number_of_response:1; }","duration":"252.482007ms","start":"2026-04-12T20:40:58.270447Z","end":"2026-04-12T20:40:58.522929Z","steps":["trace[1724727377] 'process raft request' (duration: 137.700418ms)","trace[1724727377] 'compare' (duration: 114.307586ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:40:58.556235Z","caller":"traceutil/trace.go:171","msg":"trace[512819496] linearizableReadLoop","detail":"{readStateIndex:359; appliedIndex:355; }","duration":"242.860827ms","start":"2026-04-12T20:40:58.313352Z","end":"2026-04-12T20:40:58.556212Z","steps":["trace[512819496] 'read index received' (duration: 94.802761ms)","trace[512819496] 'applied index is now lower than readState.Index' (duration: 148.057336ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:40:58.556304Z","caller":"traceutil/trace.go:171","msg":"trace[1907792197] transaction","detail":"{read_only:false; response_revision:344; number_of_response:1; }","duration":"281.284731ms","start":"2026-04-12T20:40:58.274997Z","end":"2026-04-12T20:40:58.556281Z","steps":["trace[1907792197] 'process raft request' (duration: 281.042219ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:58.556393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.022458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-04-12T20:40:58.556385Z","caller":"traceutil/trace.go:171","msg":"trace[1617446653] transaction","detail":"{read_only:false; response_revision:345; number_of_response:1; }","duration":"281.242841ms","start":"2026-04-12T20:40:58.275048Z","end":"2026-04-12T20:40:58.556291Z","steps":["trace[1617446653] 'process raft request' (duration: 281.09662ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:58.556461Z","caller":"traceutil/trace.go:171","msg":"trace[2112623634] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:1; response_revision:345; }","duration":"243.116458ms","start":"2026-04-12T20:40:58.313321Z","end":"2026-04-12T20:40:58.556438Z","steps":["trace[2112623634] 'agreement among raft nodes before linearized reading' (duration: 242.978917ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:58.666794Z","caller":"traceutil/trace.go:171","msg":"trace[590273812] transaction","detail":"{read_only:false; response_revision:346; number_of_response:1; }","duration":"140.008976ms","start":"2026-04-12T20:40:58.526753Z","end":"2026-04-12T20:40:58.666762Z","steps":["trace[590273812] 'process raft request' (duration: 114.342657ms)","trace[590273812] 'compare' (duration: 25.441097ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:40:58.666874Z","caller":"traceutil/trace.go:171","msg":"trace[1603617982] transaction","detail":"{read_only:false; response_revision:349; number_of_response:1; }","duration":"102.395237ms","start":"2026-04-12T20:40:58.564456Z","end":"2026-04-12T20:40:58.666851Z","steps":["trace[1603617982] 'process raft request' (duration: 102.334236ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:58.666979Z","caller":"traceutil/trace.go:171","msg":"trace[398527886] linearizableReadLoop","detail":"{readStateIndex:361; appliedIndex:359; }","duration":"110.647609ms","start":"2026-04-12T20:40:58.55632Z","end":"2026-04-12T20:40:58.666968Z","steps":["trace[398527886] 'read index received' (duration: 84.789208ms)","trace[398527886] 'applied index is now lower than readState.Index' (duration: 25.85695ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:40:58.666967Z","caller":"traceutil/trace.go:171","msg":"trace[1145529801] transaction","detail":"{read_only:false; response_revision:348; number_of_response:1; }","duration":"106.966271ms","start":"2026-04-12T20:40:58.559947Z","end":"2026-04-12T20:40:58.666913Z","steps":["trace[1145529801] 'process raft request' (duration: 106.80537ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:58.667081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.06263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/deployment-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-12T20:40:58.667115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.606188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:1 size:203"} {"level":"info","ts":"2026-04-12T20:40:58.667129Z","caller":"traceutil/trace.go:171","msg":"trace[30973137] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/deployment-controller; range_end:; response_count:1; response_revision:349; }","duration":"204.156811ms","start":"2026-04-12T20:40:58.462962Z","end":"2026-04-12T20:40:58.667119Z","steps":["trace[30973137] 'agreement among raft nodes before linearized reading' (duration: 204.06696ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:58.667142Z","caller":"traceutil/trace.go:171","msg":"trace[1413573130] transaction","detail":"{read_only:false; response_revision:347; number_of_response:1; }","duration":"138.692186ms","start":"2026-04-12T20:40:58.528439Z","end":"2026-04-12T20:40:58.667132Z","steps":["trace[1413573130] 'process raft request' (duration: 138.257323ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:58.66715Z","caller":"traceutil/trace.go:171","msg":"trace[1184127154] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:1; response_revision:349; }","duration":"252.686049ms","start":"2026-04-12T20:40:58.414453Z","end":"2026-04-12T20:40:58.667139Z","steps":["trace[1184127154] 'agreement among raft nodes before linearized reading' (duration: 252.597098ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:58.667252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.432784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpointslice-controller\" ","response":"range_response_count:1 size:214"} {"level":"warn","ts":"2026-04-12T20:40:58.667288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.667393ms","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-04-12T20:40:58.667292Z","caller":"traceutil/trace.go:171","msg":"trace[836884454] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpointslice-controller; range_end:; response_count:1; response_revision:349; }","duration":"153.469045ms","start":"2026-04-12T20:40:58.513812Z","end":"2026-04-12T20:40:58.667281Z","steps":["trace[836884454] 'agreement among raft nodes before linearized reading' (duration: 153.410414ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:40:58.667306Z","caller":"traceutil/trace.go:171","msg":"trace[2095700504] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:349; }","duration":"304.723113ms","start":"2026-04-12T20:40:58.362577Z","end":"2026-04-12T20:40:58.6673Z","steps":["trace[2095700504] 'agreement among raft nodes before linearized reading' (duration: 304.689113ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:58.667321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:58.362558Z","time spent":"304.758023ms","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":219,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-04-12T20:40:58.667293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.212733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-apiserver-instance\" ","response":"range_response_count:1 size:7294"} {"level":"info","ts":"2026-04-12T20:40:58.667443Z","caller":"traceutil/trace.go:171","msg":"trace[1502687035] range","detail":"{range_begin:/registry/pods/kube-system/kube-apiserver-instance; range_end:; response_count:1; response_revision:349; }","duration":"107.386265ms","start":"2026-04-12T20:40:58.560017Z","end":"2026-04-12T20:40:58.667403Z","steps":["trace[1502687035] 'agreement among raft nodes before linearized reading' (duration: 107.186533ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:59.267256Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003493723570237,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:40:59.547427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.29686ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:40:59.547617Z","caller":"traceutil/trace.go:171","msg":"trace[739917840] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"781.048898ms","start":"2026-04-12T20:40:58.766551Z","end":"2026-04-12T20:40:59.5476Z","steps":["trace[739917840] 'process raft request' (duration: 780.975168ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:40:59.547669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:58.766544Z","time spent":"781.103458ms","remote":"127.0.0.1:50028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1731,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:40:59.547703Z","caller":"traceutil/trace.go:171","msg":"trace[2077115610] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"781.436411ms","start":"2026-04-12T20:40:58.766232Z","end":"2026-04-12T20:40:59.547668Z","steps":["trace[2077115610] 'process raft request' (duration: 628.833697ms)","trace[2077115610] 'compare' (duration: 151.739927ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:40:59.547849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:58.766221Z","time spent":"781.555221ms","remote":"127.0.0.1:49998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":680,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:41:00.124537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.677772ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:41:00.124819Z","caller":"traceutil/trace.go:171","msg":"trace[1195117614] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"1.3578329s","start":"2026-04-12T20:40:58.766903Z","end":"2026-04-12T20:41:00.124736Z","steps":["trace[1195117614] 'process raft request' (duration: 1.235878925s)","trace[1195117614] 'compare' (duration: 121.535151ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:00.124855Z","caller":"traceutil/trace.go:171","msg":"trace[566228771] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"1.35792922s","start":"2026-04-12T20:40:58.766904Z","end":"2026-04-12T20:41:00.124833Z","steps":["trace[566228771] 'process raft request' (duration: 1.357749809s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:00.124935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:58.76689Z","time spent":"1.358007871s","remote":"127.0.0.1:49998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":654,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:41:00.124914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:58.766877Z","time spent":"1.357998201s","remote":"127.0.0.1:49998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":687,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:41:00.125057Z","caller":"traceutil/trace.go:171","msg":"trace[916827034] linearizableReadLoop","detail":"{readStateIndex:380; appliedIndex:375; }","duration":"1.358041371s","start":"2026-04-12T20:40:58.766997Z","end":"2026-04-12T20:41:00.125039Z","steps":["trace[916827034] 'read index received' (duration: 628.056541ms)","trace[916827034] 'applied index is now lower than readState.Index' (duration: 729.98367ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:00.125177Z","caller":"traceutil/trace.go:171","msg":"trace[1292247656] transaction","detail":"{read_only:false; response_revision:366; number_of_response:1; }","duration":"1.355731834s","start":"2026-04-12T20:40:58.769431Z","end":"2026-04-12T20:41:00.125163Z","steps":["trace[1292247656] 'process raft request' (duration: 1.355435512s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:00.12525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.358222472s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/etcd-instance\" ","response":"range_response_count:1 size:5326"} {"level":"warn","ts":"2026-04-12T20:41:00.12527Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:58.769423Z","time spent":"1.355790564s","remote":"127.0.0.1:50358","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3691,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:00.125297Z","caller":"traceutil/trace.go:171","msg":"trace[1102201775] range","detail":"{range_begin:/registry/pods/kube-system/etcd-instance; range_end:; response_count:1; response_revision:366; }","duration":"1.358288043s","start":"2026-04-12T20:40:58.766994Z","end":"2026-04-12T20:41:00.125283Z","steps":["trace[1102201775] 'agreement among raft nodes before linearized reading' (duration: 1.358180622s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:00.125341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:58.766974Z","time spent":"1.358355254s","remote":"127.0.0.1:50104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":42,"response count":1,"response size":5350,"request content":"key:\"/registry/pods/kube-system/etcd-instance\" "} {"level":"info","ts":"2026-04-12T20:41:00.659469Z","caller":"traceutil/trace.go:171","msg":"trace[247175437] linearizableReadLoop","detail":"{readStateIndex:381; appliedIndex:380; }","duration":"534.303323ms","start":"2026-04-12T20:41:00.125142Z","end":"2026-04-12T20:41:00.659445Z","steps":["trace[247175437] 'read index received' (duration: 470.744898ms)","trace[247175437] 'applied index is now lower than readState.Index' (duration: 63.557355ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:41:00.659592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.495228173s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:41:00.659629Z","caller":"traceutil/trace.go:171","msg":"trace[1052646879] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:367; }","duration":"1.495299483s","start":"2026-04-12T20:40:59.164321Z","end":"2026-04-12T20:41:00.65962Z","steps":["trace[1052646879] 'agreement among raft nodes before linearized reading' (duration: 1.495238863s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:00.659641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"902.118648ms","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-04-12T20:41:00.659673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:59.1643Z","time spent":"1.495364696s","remote":"127.0.0.1:49940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-12T20:41:00.659686Z","caller":"traceutil/trace.go:171","msg":"trace[50252438] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:367; }","duration":"902.18411ms","start":"2026-04-12T20:40:59.75749Z","end":"2026-04-12T20:41:00.659674Z","steps":["trace[50252438] 'agreement among raft nodes before linearized reading' (duration: 902.123848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:00.659739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:59.757475Z","time spent":"902.2532ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-12T20:41:00.659791Z","caller":"traceutil/trace.go:171","msg":"trace[566545540] transaction","detail":"{read_only:false; response_revision:367; number_of_response:1; }","duration":"1.182438792s","start":"2026-04-12T20:40:59.477308Z","end":"2026-04-12T20:41:00.659747Z","steps":["trace[566545540] 'process raft request' (duration: 1.118621984s)","trace[566545540] 'compare' (duration: 63.397314ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:41:00.659836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.73079319s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"warn","ts":"2026-04-12T20:41:00.659925Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.891272728s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"warn","ts":"2026-04-12T20:41:00.659985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:59.477279Z","time spent":"1.182613243s","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:41:00.659801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.890507342s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-04-12T20:41:00.660142Z","caller":"traceutil/trace.go:171","msg":"trace[1984044513] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:367; }","duration":"1.890852855s","start":"2026-04-12T20:40:58.769245Z","end":"2026-04-12T20:41:00.660098Z","steps":["trace[1984044513] 'agreement among raft nodes before linearized reading' (duration: 1.890437992s)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:00.659984Z","caller":"traceutil/trace.go:171","msg":"trace[922364086] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:367; }","duration":"1.891351309s","start":"2026-04-12T20:40:58.768618Z","end":"2026-04-12T20:41:00.65997Z","steps":["trace[922364086] 'agreement among raft nodes before linearized reading' (duration: 1.891262858s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:00.660212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:58.769239Z","time spent":"1.890953865s","remote":"127.0.0.1:50376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2925,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-04-12T20:41:00.66026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:58.768608Z","time spent":"1.891630161s","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":231,"request content":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" "} {"level":"info","ts":"2026-04-12T20:41:00.659926Z","caller":"traceutil/trace.go:171","msg":"trace[1953996919] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:367; }","duration":"1.730905691s","start":"2026-04-12T20:40:58.928995Z","end":"2026-04-12T20:41:00.659901Z","steps":["trace[1953996919] 'agreement among raft nodes before linearized reading' (duration: 1.73078613s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:00.660409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:58.928973Z","time spent":"1.731423215s","remote":"127.0.0.1:50122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":209,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-04-12T20:41:00.659581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.098323512s","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-04-12T20:41:00.660517Z","caller":"traceutil/trace.go:171","msg":"trace[2063528660] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:367; }","duration":"1.099311721s","start":"2026-04-12T20:40:59.561192Z","end":"2026-04-12T20:41:00.660504Z","steps":["trace[2063528660] 'agreement among raft nodes before linearized reading' (duration: 1.098306252s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:00.660553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:40:59.561176Z","time spent":"1.099369321s","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-12T20:41:00.660744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.582908ms","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-04-12T20:41:00.661024Z","caller":"traceutil/trace.go:171","msg":"trace[1992879936] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:367; }","duration":"586.90699ms","start":"2026-04-12T20:41:00.074086Z","end":"2026-04-12T20:41:00.660993Z","steps":["trace[1992879936] 'agreement among raft nodes before linearized reading' (duration: 586.070514ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:00.661621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:00.074067Z","time spent":"587.536844ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-12T20:41:00.899465Z","caller":"traceutil/trace.go:171","msg":"trace[1030216770] linearizableReadLoop","detail":"{readStateIndex:382; appliedIndex:381; }","duration":"239.904647ms","start":"2026-04-12T20:41:00.659535Z","end":"2026-04-12T20:41:00.899439Z","steps":["trace[1030216770] 'read index received' (duration: 239.752066ms)","trace[1030216770] 'applied index is now lower than readState.Index' (duration: 151.471µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:00.899515Z","caller":"traceutil/trace.go:171","msg":"trace[377581841] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"765.667299ms","start":"2026-04-12T20:41:00.133816Z","end":"2026-04-12T20:41:00.899483Z","steps":["trace[377581841] 'process raft request' (duration: 765.482687ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:00.899637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.486893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:3706"} {"level":"info","ts":"2026-04-12T20:41:00.899676Z","caller":"traceutil/trace.go:171","msg":"trace[483226124] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:368; }","duration":"767.568213ms","start":"2026-04-12T20:41:00.132098Z","end":"2026-04-12T20:41:00.899666Z","steps":["trace[483226124] 'agreement among raft nodes before linearized reading' (duration: 767.457822ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:00.89971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:00.132086Z","time spent":"767.616273ms","remote":"127.0.0.1:50358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":3730,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-04-12T20:41:00.899739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:00.133805Z","time spent":"765.82637ms","remote":"127.0.0.1:50104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5139,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:01.024907Z","caller":"traceutil/trace.go:171","msg":"trace[705066809] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"360.4701ms","start":"2026-04-12T20:41:00.664415Z","end":"2026-04-12T20:41:01.024885Z","steps":["trace[705066809] 'process raft request' (duration: 315.083098ms)","trace[705066809] 'compare' (duration: 45.196291ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:41:01.025043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:00.664395Z","time spent":"360.570732ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:01.025314Z","caller":"traceutil/trace.go:171","msg":"trace[336769041] transaction","detail":"{read_only:false; response_revision:370; number_of_response:1; }","duration":"359.01944ms","start":"2026-04-12T20:41:00.666275Z","end":"2026-04-12T20:41:01.025294Z","steps":["trace[336769041] 'process raft request' (duration: 358.571847ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:01.025371Z","caller":"traceutil/trace.go:171","msg":"trace[882885891] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"359.02385ms","start":"2026-04-12T20:41:00.666333Z","end":"2026-04-12T20:41:01.025357Z","steps":["trace[882885891] 'process raft request' (duration: 358.868118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:01.025414Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:00.666257Z","time spent":"359.113211ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:41:01.02544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:00.666317Z","time spent":"359.08534ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:41:01.02556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.970751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"warn","ts":"2026-04-12T20:41:01.025546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.333757ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:41:01.025546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.761983ms","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-04-12T20:41:01.0256Z","caller":"traceutil/trace.go:171","msg":"trace[1241882660] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:372; }","duration":"362.039571ms","start":"2026-04-12T20:41:00.66355Z","end":"2026-04-12T20:41:01.02559Z","steps":["trace[1241882660] 'agreement among raft nodes before linearized reading' (duration: 361.964761ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:01.02563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:00.663535Z","time spent":"362.088812ms","remote":"127.0.0.1:50376","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":2925,"request content":"key:\"/registry/daemonsets/kube-system/kube-proxy\" "} {"level":"info","ts":"2026-04-12T20:41:01.025623Z","caller":"traceutil/trace.go:171","msg":"trace[482950413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:372; }","duration":"357.428247ms","start":"2026-04-12T20:41:00.668175Z","end":"2026-04-12T20:41:01.025603Z","steps":["trace[482950413] 'agreement among raft nodes before linearized reading' (duration: 357.302976ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:01.02568Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:00.668168Z","time spent":"357.499158ms","remote":"127.0.0.1:49932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T20:41:01.025893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.689056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-m8jkv\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-04-12T20:41:01.025971Z","caller":"traceutil/trace.go:171","msg":"trace[1282092257] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-m8jkv; range_end:; response_count:1; response_revision:372; }","duration":"362.926458ms","start":"2026-04-12T20:41:00.663031Z","end":"2026-04-12T20:41:01.025957Z","steps":["trace[1282092257] 'agreement among raft nodes before linearized reading' (duration: 362.588235ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:01.026041Z","caller":"traceutil/trace.go:171","msg":"trace[1338651580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:372; }","duration":"259.890253ms","start":"2026-04-12T20:41:00.765722Z","end":"2026-04-12T20:41:01.025613Z","steps":["trace[1338651580] 'agreement among raft nodes before linearized reading' (duration: 259.732682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:01.026076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:00.663011Z","time spent":"363.031518ms","remote":"127.0.0.1:50104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":3463,"request content":"key:\"/registry/pods/kube-system/kube-proxy-m8jkv\" "} {"level":"info","ts":"2026-04-12T20:41:01.026648Z","caller":"traceutil/trace.go:171","msg":"trace[2084904458] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"356.065868ms","start":"2026-04-12T20:41:00.669495Z","end":"2026-04-12T20:41:01.025561Z","steps":["trace[2084904458] 'process raft request' (duration: 355.803406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:01.026737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:00.669485Z","time spent":"357.205437ms","remote":"127.0.0.1:50104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3527,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:41:01.027534Z","caller":"traceutil/trace.go:171","msg":"trace[73535666] linearizableReadLoop","detail":"{readStateIndex:386; appliedIndex:382; }","duration":"125.822792ms","start":"2026-04-12T20:41:00.899528Z","end":"2026-04-12T20:41:01.025351Z","steps":["trace[73535666] 'read index received' (duration: 79.983497ms)","trace[73535666] 'applied index is now lower than readState.Index' (duration: 45.838285ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:41:01.028451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.59707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-m8jkv\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-04-12T20:41:01.02852Z","caller":"traceutil/trace.go:171","msg":"trace[828787511] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-m8jkv; range_end:; response_count:1; response_revision:373; }","duration":"125.69798ms","start":"2026-04-12T20:41:00.902804Z","end":"2026-04-12T20:41:01.028502Z","steps":["trace[828787511] 'agreement among raft nodes before linearized reading' (duration: 125.559359ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:01.028465Z","caller":"traceutil/trace.go:171","msg":"trace[1342852843] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"121.427369ms","start":"2026-04-12T20:41:00.907015Z","end":"2026-04-12T20:41:01.028442Z","steps":["trace[1342852843] 'process raft request' (duration: 121.254358ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:01.245948Z","caller":"traceutil/trace.go:171","msg":"trace[768707415] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"212.640641ms","start":"2026-04-12T20:41:01.033276Z","end":"2026-04-12T20:41:01.245917Z","steps":["trace[768707415] 'process raft request' (duration: 212.51957ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:01.245993Z","caller":"traceutil/trace.go:171","msg":"trace[1626084680] transaction","detail":"{read_only:false; response_revision:375; number_of_response:1; }","duration":"212.904252ms","start":"2026-04-12T20:41:01.033053Z","end":"2026-04-12T20:41:01.245957Z","steps":["trace[1626084680] 'process raft request' (duration: 169.410935ms)","trace[1626084680] 'compare' (duration: 43.187365ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:01.246292Z","caller":"traceutil/trace.go:171","msg":"trace[1812466441] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"212.640571ms","start":"2026-04-12T20:41:01.033639Z","end":"2026-04-12T20:41:01.24628Z","steps":["trace[1812466441] 'process raft request' (duration: 212.193327ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:01.246624Z","caller":"traceutil/trace.go:171","msg":"trace[672881196] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:387; }","duration":"212.010107ms","start":"2026-04-12T20:41:01.034598Z","end":"2026-04-12T20:41:01.246609Z","steps":["trace[672881196] 'read index received' (duration: 55.852818ms)","trace[672881196] 'applied index is now lower than readState.Index' (duration: 156.155849ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:01.246741Z","caller":"traceutil/trace.go:171","msg":"trace[1346308482] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"200.970736ms","start":"2026-04-12T20:41:01.04575Z","end":"2026-04-12T20:41:01.246721Z","steps":["trace[1346308482] 'process raft request' (duration: 200.750914ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:01.246795Z","caller":"traceutil/trace.go:171","msg":"trace[243874579] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"203.672006ms","start":"2026-04-12T20:41:01.043105Z","end":"2026-04-12T20:41:01.246777Z","steps":["trace[243874579] 'process raft request' (duration: 202.76735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:01.246976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.85092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4017"} {"level":"warn","ts":"2026-04-12T20:41:01.247027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.41649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/kube-system/kube-proxy\" ","response":"range_response_count:1 size:2901"} {"level":"info","ts":"2026-04-12T20:41:01.247046Z","caller":"traceutil/trace.go:171","msg":"trace[1669647684] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:379; }","duration":"209.976712ms","start":"2026-04-12T20:41:01.037055Z","end":"2026-04-12T20:41:01.247031Z","steps":["trace[1669647684] 'agreement among raft nodes before linearized reading' (duration: 209.841251ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:01.247064Z","caller":"traceutil/trace.go:171","msg":"trace[1208575969] range","detail":"{range_begin:/registry/daemonsets/kube-system/kube-proxy; range_end:; response_count:1; response_revision:379; }","duration":"212.52101ms","start":"2026-04-12T20:41:01.034533Z","end":"2026-04-12T20:41:01.247054Z","steps":["trace[1208575969] 'agreement among raft nodes before linearized reading' (duration: 212.417089ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:01.416553Z","caller":"traceutil/trace.go:171","msg":"trace[1755887019] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"160.086857ms","start":"2026-04-12T20:41:01.256444Z","end":"2026-04-12T20:41:01.416531Z","steps":["trace[1755887019] 'process raft request' (duration: 160.027737ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:01.416589Z","caller":"traceutil/trace.go:171","msg":"trace[298901582] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"162.723876ms","start":"2026-04-12T20:41:01.253819Z","end":"2026-04-12T20:41:01.416543Z","steps":["trace[298901582] 'process raft request' (duration: 123.987714ms)","trace[298901582] 'compare' (duration: 38.491121ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:01.416622Z","caller":"traceutil/trace.go:171","msg":"trace[1265189107] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"162.752217ms","start":"2026-04-12T20:41:01.253835Z","end":"2026-04-12T20:41:01.416587Z","steps":["trace[1265189107] 'process raft request' (duration: 162.597256ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:01.441625Z","caller":"traceutil/trace.go:171","msg":"trace[1326770931] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"111.164831ms","start":"2026-04-12T20:41:01.33044Z","end":"2026-04-12T20:41:01.441605Z","steps":["trace[1326770931] 'process raft request' (duration: 111.04096ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:01.617963Z","caller":"traceutil/trace.go:171","msg":"trace[276056538] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"167.25346ms","start":"2026-04-12T20:41:01.450679Z","end":"2026-04-12T20:41:01.617932Z","steps":["trace[276056538] 'process raft request' (duration: 153.209348ms)","trace[276056538] 'compare' (duration: 13.892671ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:02.81945Z","caller":"traceutil/trace.go:171","msg":"trace[77112871] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"155.793111ms","start":"2026-04-12T20:41:02.66363Z","end":"2026-04-12T20:41:02.819423Z","steps":["trace[77112871] 'process raft request' (duration: 142.994528ms)","trace[77112871] 'compare' (duration: 12.659672ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:41:03.334292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.104857ms","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-04-12T20:41:03.334312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.810242ms","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-04-12T20:41:03.334374Z","caller":"traceutil/trace.go:171","msg":"trace[2063898456] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:389; }","duration":"304.240549ms","start":"2026-04-12T20:41:03.030115Z","end":"2026-04-12T20:41:03.334355Z","steps":["trace[2063898456] 'range keys from in-memory index tree' (duration: 304.002127ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:03.33442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:03.030094Z","time spent":"304.316199ms","remote":"127.0.0.1:50196","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-04-12T20:41:03.334475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.739907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:41:03.334521Z","caller":"traceutil/trace.go:171","msg":"trace[1396460727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:389; }","duration":"169.815467ms","start":"2026-04-12T20:41:03.164691Z","end":"2026-04-12T20:41:03.334506Z","steps":["trace[1396460727] 'range keys from in-memory index tree' (duration: 169.679756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:03.334477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.626188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.69\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-04-12T20:41:03.334552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.905237ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6960"} {"level":"info","ts":"2026-04-12T20:41:03.334567Z","caller":"traceutil/trace.go:171","msg":"trace[950531907] range","detail":"{range_begin:/registry/masterleases/199.204.45.69; range_end:; response_count:1; response_revision:389; }","duration":"262.713718ms","start":"2026-04-12T20:41:03.071841Z","end":"2026-04-12T20:41:03.334554Z","steps":["trace[950531907] 'range keys from in-memory index tree' (duration: 262.536557ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:03.334609Z","caller":"traceutil/trace.go:171","msg":"trace[1068795313] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:389; }","duration":"127.014398ms","start":"2026-04-12T20:41:03.207582Z","end":"2026-04-12T20:41:03.334596Z","steps":["trace[1068795313] 'range keys from in-memory index tree' (duration: 126.791256ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:03.334687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.923433ms","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-04-12T20:41:03.334724Z","caller":"traceutil/trace.go:171","msg":"trace[81021432] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:389; }","duration":"304.996533ms","start":"2026-04-12T20:41:03.029715Z","end":"2026-04-12T20:41:03.334712Z","steps":["trace[81021432] 'range keys from in-memory index tree' (duration: 304.844303ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:03.334751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:03.029696Z","time spent":"305.046625ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-12T20:41:03.334786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.682162ms","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-04-12T20:41:03.334852Z","caller":"traceutil/trace.go:171","msg":"trace[1787281370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:389; }","duration":"304.771412ms","start":"2026-04-12T20:41:03.030062Z","end":"2026-04-12T20:41:03.334833Z","steps":["trace[1787281370] 'range keys from in-memory index tree' (duration: 304.590642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:03.334896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:03.030049Z","time spent":"304.834343ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-12T20:41:03.335038Z","caller":"traceutil/trace.go:171","msg":"trace[268104553] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:389; }","duration":"239.946054ms","start":"2026-04-12T20:41:03.094427Z","end":"2026-04-12T20:41:03.334373Z","steps":["trace[268104553] 'range keys from in-memory index tree' (duration: 239.697512ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:03.721789Z","caller":"traceutil/trace.go:171","msg":"trace[728777253] linearizableReadLoop","detail":"{readStateIndex:405; appliedIndex:404; }","duration":"385.9238ms","start":"2026-04-12T20:41:03.335843Z","end":"2026-04-12T20:41:03.721767Z","steps":["trace[728777253] 'read index received' (duration: 385.89851ms)","trace[728777253] 'applied index is now lower than readState.Index' (duration: 23.77µs)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:41:03.721904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.042381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:41:03.721937Z","caller":"traceutil/trace.go:171","msg":"trace[589941871] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:389; }","duration":"386.112911ms","start":"2026-04-12T20:41:03.335813Z","end":"2026-04-12T20:41:03.721926Z","steps":["trace[589941871] 'agreement among raft nodes before linearized reading' (duration: 386.043521ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:03.721975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:03.335801Z","time spent":"386.165981ms","remote":"127.0.0.1:49940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T20:41:03.721811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:03.335758Z","time spent":"386.04642ms","remote":"127.0.0.1:49960","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-04-12T20:41:04.530228Z","caller":"traceutil/trace.go:171","msg":"trace[1561687487] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"1.191147135s","start":"2026-04-12T20:41:03.339055Z","end":"2026-04-12T20:41:04.530203Z","steps":["trace[1561687487] 'process raft request' (duration: 1.190940363s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:04.530387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:03.339042Z","time spent":"1.191262355s","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:04.530475Z","caller":"traceutil/trace.go:171","msg":"trace[40810899] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"1.191299705s","start":"2026-04-12T20:41:03.339159Z","end":"2026-04-12T20:41:04.530458Z","steps":["trace[40810899] 'process raft request' (duration: 1.191204455s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:04.530597Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:03.33915Z","time spent":"1.191387626s","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:04.530728Z","caller":"traceutil/trace.go:171","msg":"trace[1976626976] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"1.191521237s","start":"2026-04-12T20:41:03.339143Z","end":"2026-04-12T20:41:04.530665Z","steps":["trace[1976626976] 'process raft request' (duration: 1.191010273s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:04.530901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:03.339138Z","time spent":"1.191672138s","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:04.531892Z","caller":"traceutil/trace.go:171","msg":"trace[1377765015] transaction","detail":"{read_only:false; response_revision:393; number_of_response:1; }","duration":"1.19190285s","start":"2026-04-12T20:41:03.339971Z","end":"2026-04-12T20:41:04.531874Z","steps":["trace[1377765015] 'process raft request' (duration: 1.190458739s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:04.53199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:03.339964Z","time spent":"1.19197804s","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:04.5322Z","caller":"traceutil/trace.go:171","msg":"trace[1679339207] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"1.189857276s","start":"2026-04-12T20:41:03.342256Z","end":"2026-04-12T20:41:04.532113Z","steps":["trace[1679339207] 'process raft request' (duration: 1.189505913s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:04.532899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:03.342248Z","time spent":"1.190267548s","remote":"127.0.0.1:50104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6946,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:41:04.79689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.354357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:41:04.797046Z","caller":"traceutil/trace.go:171","msg":"trace[1484303200] transaction","detail":"{read_only:false; response_revision:395; number_of_response:1; }","duration":"1.073935558s","start":"2026-04-12T20:41:03.723084Z","end":"2026-04-12T20:41:04.797019Z","steps":["trace[1484303200] 'process raft request' (duration: 968.311929ms)","trace[1484303200] 'compare' (duration: 105.198736ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:04.797025Z","caller":"traceutil/trace.go:171","msg":"trace[284335738] linearizableReadLoop","detail":"{readStateIndex:411; appliedIndex:410; }","duration":"260.567585ms","start":"2026-04-12T20:41:04.536435Z","end":"2026-04-12T20:41:04.797003Z","steps":["trace[284335738] 'read index received' (duration: 154.994986ms)","trace[284335738] 'applied index is now lower than readState.Index' (duration: 105.570759ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:41:04.797189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:03.723064Z","time spent":"1.074047449s","remote":"127.0.0.1:49960","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:41:04.797385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.939469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6960"} {"level":"info","ts":"2026-04-12T20:41:04.797441Z","caller":"traceutil/trace.go:171","msg":"trace[1182208684] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:395; }","duration":"261.032049ms","start":"2026-04-12T20:41:04.536395Z","end":"2026-04-12T20:41:04.797427Z","steps":["trace[1182208684] 'agreement among raft nodes before linearized reading' (duration: 260.718236ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:05.049989Z","caller":"traceutil/trace.go:171","msg":"trace[546691440] transaction","detail":"{read_only:false; response_revision:397; number_of_response:1; }","duration":"150.812445ms","start":"2026-04-12T20:41:04.899154Z","end":"2026-04-12T20:41:05.049967Z","steps":["trace[546691440] 'process raft request' (duration: 150.664424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:05.453894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.109347ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:41:05.454004Z","caller":"traceutil/trace.go:171","msg":"trace[730546437] linearizableReadLoop","detail":"{readStateIndex:415; appliedIndex:414; }","duration":"288.487999ms","start":"2026-04-12T20:41:05.1655Z","end":"2026-04-12T20:41:05.453988Z","steps":["trace[730546437] 'read index received' (duration: 177.14777ms)","trace[730546437] 'applied index is now lower than readState.Index' (duration: 111.339019ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:41:05.454062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.560189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:41:05.454097Z","caller":"traceutil/trace.go:171","msg":"trace[217893803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:399; }","duration":"288.653689ms","start":"2026-04-12T20:41:05.165434Z","end":"2026-04-12T20:41:05.454087Z","steps":["trace[217893803] 'agreement among raft nodes before linearized reading' (duration: 288.599209ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:05.454088Z","caller":"traceutil/trace.go:171","msg":"trace[612329262] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"394.338267ms","start":"2026-04-12T20:41:05.059717Z","end":"2026-04-12T20:41:05.454055Z","steps":["trace[612329262] 'process raft request' (duration: 282.981138ms)","trace[612329262] 'compare' (duration: 110.963156ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:41:05.454328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:05.059698Z","time spent":"394.541608ms","remote":"127.0.0.1:50104","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4307,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:06.779659Z","caller":"traceutil/trace.go:171","msg":"trace[304163950] linearizableReadLoop","detail":"{readStateIndex:419; appliedIndex:418; }","duration":"230.240884ms","start":"2026-04-12T20:41:06.5494Z","end":"2026-04-12T20:41:06.779641Z","steps":["trace[304163950] 'read index received' (duration: 230.166073ms)","trace[304163950] 'applied index is now lower than readState.Index' (duration: 73.911µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:06.779916Z","caller":"traceutil/trace.go:171","msg":"trace[1150758576] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"230.633756ms","start":"2026-04-12T20:41:06.549165Z","end":"2026-04-12T20:41:06.779799Z","steps":["trace[1150758576] 'process raft request' (duration: 230.370454ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:06.77994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.483466ms","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-04-12T20:41:06.780077Z","caller":"traceutil/trace.go:171","msg":"trace[414302336] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:403; }","duration":"230.680797ms","start":"2026-04-12T20:41:06.549377Z","end":"2026-04-12T20:41:06.780058Z","steps":["trace[414302336] 'agreement among raft nodes before linearized reading' (duration: 230.420295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:06.810746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"261.254525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-12T20:41:06.810747Z","caller":"traceutil/trace.go:171","msg":"trace[70684612] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"261.288785ms","start":"2026-04-12T20:41:06.549436Z","end":"2026-04-12T20:41:06.810725Z","steps":["trace[70684612] 'process raft request' (duration: 261.118484ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:06.8108Z","caller":"traceutil/trace.go:171","msg":"trace[1977812849] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:404; }","duration":"261.330185ms","start":"2026-04-12T20:41:06.549457Z","end":"2026-04-12T20:41:06.810787Z","steps":["trace[1977812849] 'agreement among raft nodes before linearized reading' (duration: 261.220745ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:11.324303Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003493723570334,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-12T20:41:11.417277Z","caller":"traceutil/trace.go:171","msg":"trace[1728951474] transaction","detail":"{read_only:false; response_revision:411; number_of_response:1; }","duration":"620.908715ms","start":"2026-04-12T20:41:10.796339Z","end":"2026-04-12T20:41:11.417248Z","steps":["trace[1728951474] 'process raft request' (duration: 620.732638ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:11.41767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:10.796306Z","time spent":"621.073182ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:11.421418Z","caller":"traceutil/trace.go:171","msg":"trace[1166155977] linearizableReadLoop","detail":"{readStateIndex:429; appliedIndex:427; }","duration":"597.597759ms","start":"2026-04-12T20:41:10.823797Z","end":"2026-04-12T20:41:11.421395Z","steps":["trace[1166155977] 'read index received' (duration: 593.283319ms)","trace[1166155977] 'applied index is now lower than readState.Index' (duration: 4.31355ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:11.421529Z","caller":"traceutil/trace.go:171","msg":"trace[1840871863] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"425.173144ms","start":"2026-04-12T20:41:10.996336Z","end":"2026-04-12T20:41:11.421509Z","steps":["trace[1840871863] 'process raft request' (duration: 424.920154ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:11.421599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"597.778136ms","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-04-12T20:41:11.421641Z","caller":"traceutil/trace.go:171","msg":"trace[398375566] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:412; }","duration":"597.86306ms","start":"2026-04-12T20:41:10.823768Z","end":"2026-04-12T20:41:11.421631Z","steps":["trace[398375566] 'agreement among raft nodes before linearized reading' (duration: 597.773696ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:11.421645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.696837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-12T20:41:11.421692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:10.823754Z","time spent":"597.928402ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-12T20:41:11.421718Z","caller":"traceutil/trace.go:171","msg":"trace[90795438] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:412; }","duration":"476.824781ms","start":"2026-04-12T20:41:10.944876Z","end":"2026-04-12T20:41:11.421701Z","steps":["trace[90795438] 'agreement among raft nodes before linearized reading' (duration: 476.675106ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:11.42177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:10.944848Z","time spent":"476.910606ms","remote":"127.0.0.1:50196","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-04-12T20:41:11.421785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.95921ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:41:11.421849Z","caller":"traceutil/trace.go:171","msg":"trace[1220135937] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:412; }","duration":"257.096396ms","start":"2026-04-12T20:41:11.164742Z","end":"2026-04-12T20:41:11.421838Z","steps":["trace[1220135937] 'agreement among raft nodes before linearized reading' (duration: 257.009552ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:11.421899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"436.710705ms","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-04-12T20:41:11.421935Z","caller":"traceutil/trace.go:171","msg":"trace[1787506550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:412; }","duration":"436.775548ms","start":"2026-04-12T20:41:10.98515Z","end":"2026-04-12T20:41:11.421926Z","steps":["trace[1787506550] 'agreement among raft nodes before linearized reading' (duration: 436.722876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:11.421967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:10.985136Z","time spent":"436.82492ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-12T20:41:11.421693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:10.996316Z","time spent":"425.292429ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:16.558911Z","caller":"traceutil/trace.go:171","msg":"trace[1006576402] linearizableReadLoop","detail":"{readStateIndex:444; appliedIndex:443; }","duration":"519.981027ms","start":"2026-04-12T20:41:16.038905Z","end":"2026-04-12T20:41:16.558886Z","steps":["trace[1006576402] 'read index received' (duration: 438.506285ms)","trace[1006576402] 'applied index is now lower than readState.Index' (duration: 81.473852ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:16.559036Z","caller":"traceutil/trace.go:171","msg":"trace[1854227678] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"576.520429ms","start":"2026-04-12T20:41:15.982493Z","end":"2026-04-12T20:41:16.559013Z","steps":["trace[1854227678] 'process raft request' (duration: 494.987184ms)","trace[1854227678] 'compare' (duration: 81.300886ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:41:16.55907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"520.149534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"info","ts":"2026-04-12T20:41:16.559107Z","caller":"traceutil/trace.go:171","msg":"trace[1692525490] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:426; }","duration":"520.263598ms","start":"2026-04-12T20:41:16.038831Z","end":"2026-04-12T20:41:16.559094Z","steps":["trace[1692525490] 'agreement among raft nodes before linearized reading' (duration: 520.155424ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:16.559139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:16.038789Z","time spent":"520.342251ms","remote":"127.0.0.1:50358","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":4080,"request content":"key:\"/registry/deployments/kube-system/coredns\" "} {"level":"warn","ts":"2026-04-12T20:41:16.559161Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:15.982465Z","time spent":"576.615383ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:41:21.936071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.681123ms","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-04-12T20:41:21.936126Z","caller":"traceutil/trace.go:171","msg":"trace[531787033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:464; }","duration":"313.789529ms","start":"2026-04-12T20:41:21.622325Z","end":"2026-04-12T20:41:21.936114Z","steps":["trace[531787033] 'range keys from in-memory index tree' (duration: 313.602321ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:21.936111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.214624ms","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-04-12T20:41:21.936185Z","caller":"traceutil/trace.go:171","msg":"trace[1241023537] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:464; }","duration":"318.334539ms","start":"2026-04-12T20:41:21.61783Z","end":"2026-04-12T20:41:21.936164Z","steps":["trace[1241023537] 'range keys from in-memory index tree' (duration: 318.10632ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:21.936156Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:21.622306Z","time spent":"313.845021ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-12T20:41:21.936251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:21.61781Z","time spent":"318.430722ms","remote":"127.0.0.1:50196","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-04-12T20:41:21.936389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.062898ms","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-04-12T20:41:21.936448Z","caller":"traceutil/trace.go:171","msg":"trace[1639221518] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:464; }","duration":"314.156492ms","start":"2026-04-12T20:41:21.622281Z","end":"2026-04-12T20:41:21.936437Z","steps":["trace[1639221518] 'range keys from in-memory index tree' (duration: 314.013166ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:21.936547Z","caller":"traceutil/trace.go:171","msg":"trace[1792279773] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"114.628304ms","start":"2026-04-12T20:41:21.821908Z","end":"2026-04-12T20:41:21.936536Z","steps":["trace[1792279773] 'process raft request' (duration: 108.917472ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:21.9367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:21.622275Z","time spent":"314.192023ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-12T20:41:25.830553Z","caller":"traceutil/trace.go:171","msg":"trace[617425462] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"244.177986ms","start":"2026-04-12T20:41:25.586352Z","end":"2026-04-12T20:41:25.83053Z","steps":["trace[617425462] 'process raft request' (duration: 244.01681ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:30.298301Z","caller":"traceutil/trace.go:171","msg":"trace[2086141910] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"678.852191ms","start":"2026-04-12T20:41:29.619425Z","end":"2026-04-12T20:41:30.298277Z","steps":["trace[2086141910] 'process raft request' (duration: 678.684815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:30.298427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:29.619401Z","time spent":"678.968394ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:41:30.31049Z","caller":"traceutil/trace.go:171","msg":"trace[1084302173] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"683.549868ms","start":"2026-04-12T20:41:29.626923Z","end":"2026-04-12T20:41:30.310473Z","steps":["trace[1084302173] 'process raft request' (duration: 683.383353ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:30.31059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:29.626909Z","time spent":"683.637991ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:41:30.310726Z","caller":"traceutil/trace.go:171","msg":"trace[1801124442] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"675.628192ms","start":"2026-04-12T20:41:29.635056Z","end":"2026-04-12T20:41:30.310684Z","steps":["trace[1801124442] 'process raft request' (duration: 675.401535ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:30.310758Z","caller":"traceutil/trace.go:171","msg":"trace[309606796] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"673.9919ms","start":"2026-04-12T20:41:29.636751Z","end":"2026-04-12T20:41:30.310743Z","steps":["trace[309606796] 'process raft request' (duration: 673.847985ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:30.310817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:29.636745Z","time spent":"674.048712ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:41:30.310797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:29.635047Z","time spent":"675.720795ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:41:30.310824Z","caller":"traceutil/trace.go:171","msg":"trace[1227796669] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"671.101566ms","start":"2026-04-12T20:41:29.639681Z","end":"2026-04-12T20:41:30.310783Z","steps":["trace[1227796669] 'process raft request' (duration: 670.946921ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:30.310874Z","caller":"traceutil/trace.go:171","msg":"trace[218038276] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"675.753596ms","start":"2026-04-12T20:41:29.635112Z","end":"2026-04-12T20:41:30.310866Z","steps":["trace[218038276] 'process raft request' (duration: 675.413595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:30.310899Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:29.635107Z","time spent":"675.779806ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:41:30.31094Z","caller":"traceutil/trace.go:171","msg":"trace[1000125118] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"674.270748ms","start":"2026-04-12T20:41:29.636665Z","end":"2026-04-12T20:41:30.310936Z","steps":["trace[1000125118] 'process raft request' (duration: 673.907457ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:30.310922Z","caller":"traceutil/trace.go:171","msg":"trace[972284692] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"683.134834ms","start":"2026-04-12T20:41:29.627777Z","end":"2026-04-12T20:41:30.310912Z","steps":["trace[972284692] 'process raft request' (duration: 682.616848ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:30.310961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:29.636636Z","time spent":"674.315179ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:41:30.31098Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:29.627769Z","time spent":"683.191187ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:41:30.311008Z","caller":"traceutil/trace.go:171","msg":"trace[229416669] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"666.57727ms","start":"2026-04-12T20:41:29.644426Z","end":"2026-04-12T20:41:30.311003Z","steps":["trace[229416669] 'process raft request' (duration: 666.234439ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:30.310975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:29.639675Z","time spent":"671.21748ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:41:30.311029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:29.644412Z","time spent":"666.606021ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:41:30.311132Z","caller":"traceutil/trace.go:171","msg":"trace[1400384143] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"660.236866ms","start":"2026-04-12T20:41:29.650876Z","end":"2026-04-12T20:41:30.311113Z","steps":["trace[1400384143] 'process raft request' (duration: 659.825313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:30.311222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:29.650863Z","time spent":"660.299997ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:41:30.312303Z","caller":"traceutil/trace.go:171","msg":"trace[1146486985] linearizableReadLoop","detail":"{readStateIndex:551; appliedIndex:539; }","duration":"468.00175ms","start":"2026-04-12T20:41:29.844286Z","end":"2026-04-12T20:41:30.312288Z","steps":["trace[1146486985] 'read index received' (duration: 453.735563ms)","trace[1146486985] 'applied index is now lower than readState.Index' (duration: 14.265487ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:30.312376Z","caller":"traceutil/trace.go:171","msg":"trace[1966401204] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"648.589079ms","start":"2026-04-12T20:41:29.66378Z","end":"2026-04-12T20:41:30.312369Z","steps":["trace[1966401204] 'process raft request' (duration: 646.971667ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:30.312442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:29.663766Z","time spent":"648.62853ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:41:30.31336Z","caller":"traceutil/trace.go:171","msg":"trace[2014209491] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"523.900643ms","start":"2026-04-12T20:41:29.789451Z","end":"2026-04-12T20:41:30.313352Z","steps":["trace[2014209491] 'process raft request' (duration: 522.790507ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:30.313405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:29.789436Z","time spent":"523.951335ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:41:30.313532Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.247429ms","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-04-12T20:41:30.313566Z","caller":"traceutil/trace.go:171","msg":"trace[1124444051] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:528; }","duration":"469.387974ms","start":"2026-04-12T20:41:29.84417Z","end":"2026-04-12T20:41:30.313558Z","steps":["trace[1124444051] 'agreement among raft nodes before linearized reading' (duration: 469.302731ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:30.313587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:29.844151Z","time spent":"469.430285ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-12T20:41:30.314558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.708181ms","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-04-12T20:41:30.314646Z","caller":"traceutil/trace.go:171","msg":"trace[956894369] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:530; }","duration":"299.820394ms","start":"2026-04-12T20:41:30.014815Z","end":"2026-04-12T20:41:30.314635Z","steps":["trace[956894369] 'agreement among raft nodes before linearized reading' (duration: 299.70105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:30.314863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.814904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:41:30.314897Z","caller":"traceutil/trace.go:171","msg":"trace[1477759214] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:530; }","duration":"149.888036ms","start":"2026-04-12T20:41:30.165002Z","end":"2026-04-12T20:41:30.31489Z","steps":["trace[1477759214] 'agreement among raft nodes before linearized reading' (duration: 149.797313ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:30.315179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.189932ms","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-04-12T20:41:30.315209Z","caller":"traceutil/trace.go:171","msg":"trace[1612116308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:530; }","duration":"277.248644ms","start":"2026-04-12T20:41:30.037954Z","end":"2026-04-12T20:41:30.315202Z","steps":["trace[1612116308] 'agreement among raft nodes before linearized reading' (duration: 277.200992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:30.31529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.980022ms","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-04-12T20:41:30.315311Z","caller":"traceutil/trace.go:171","msg":"trace[101003850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:530; }","duration":"275.024003ms","start":"2026-04-12T20:41:30.040282Z","end":"2026-04-12T20:41:30.315306Z","steps":["trace[101003850] 'agreement among raft nodes before linearized reading' (duration: 274.989422ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:37.0129Z","caller":"traceutil/trace.go:171","msg":"trace[1227681959] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"631.139762ms","start":"2026-04-12T20:41:36.381543Z","end":"2026-04-12T20:41:37.012682Z","steps":["trace[1227681959] 'process raft request' (duration: 630.809003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:37.013213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:36.381526Z","time spent":"631.454801ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:37.852705Z","caller":"traceutil/trace.go:171","msg":"trace[124968354] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"1.4710267s","start":"2026-04-12T20:41:36.381633Z","end":"2026-04-12T20:41:37.85266Z","steps":["trace[124968354] 'process raft request' (duration: 1.47066656s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:37.853041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:36.381608Z","time spent":"1.471302588s","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:37.853253Z","caller":"traceutil/trace.go:171","msg":"trace[1749691092] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"1.470826404s","start":"2026-04-12T20:41:36.382358Z","end":"2026-04-12T20:41:37.853185Z","steps":["trace[1749691092] 'process raft request' (duration: 1.470234027s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:37.85345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:36.382325Z","time spent":"1.47101925s","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:37.853788Z","caller":"traceutil/trace.go:171","msg":"trace[1900634259] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"1.469545516s","start":"2026-04-12T20:41:36.384216Z","end":"2026-04-12T20:41:37.853762Z","steps":["trace[1900634259] 'process raft request' (duration: 1.468930987s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:37.853901Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:36.384174Z","time spent":"1.46967342s","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:37.855579Z","caller":"traceutil/trace.go:171","msg":"trace[1599570691] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:649; }","duration":"1.317431268s","start":"2026-04-12T20:41:36.538086Z","end":"2026-04-12T20:41:37.855517Z","steps":["trace[1599570691] 'read index received' (duration: 474.354195ms)","trace[1599570691] 'applied index is now lower than readState.Index' (duration: 843.076183ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:37.855627Z","caller":"traceutil/trace.go:171","msg":"trace[1515768969] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"1.436842155s","start":"2026-04-12T20:41:36.418756Z","end":"2026-04-12T20:41:37.855598Z","steps":["trace[1515768969] 'process raft request' (duration: 1.436213297s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:37.855851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:36.41872Z","time spent":"1.437050191s","remote":"127.0.0.1:50018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:41:37.855873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.317767279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rdkjv\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-12T20:41:37.856013Z","caller":"traceutil/trace.go:171","msg":"trace[2010524991] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rdkjv; range_end:; response_count:1; response_revision:627; }","duration":"1.317967594s","start":"2026-04-12T20:41:36.53803Z","end":"2026-04-12T20:41:37.855998Z","steps":["trace[2010524991] 'agreement among raft nodes before linearized reading' (duration: 1.317661445s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:37.85615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:36.538006Z","time spent":"1.318130859s","remote":"127.0.0.1:50104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-rdkjv\" "} {"level":"warn","ts":"2026-04-12T20:41:37.857717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"693.89388ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:41:37.857774Z","caller":"traceutil/trace.go:171","msg":"trace[300735461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:628; }","duration":"693.988614ms","start":"2026-04-12T20:41:37.16377Z","end":"2026-04-12T20:41:37.857758Z","steps":["trace[300735461] 'agreement among raft nodes before linearized reading' (duration: 693.891811ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:37.857805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:37.163748Z","time spent":"694.050535ms","remote":"127.0.0.1:49932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-12T20:41:37.857943Z","caller":"traceutil/trace.go:171","msg":"trace[360864176] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"665.670673ms","start":"2026-04-12T20:41:37.192252Z","end":"2026-04-12T20:41:37.857922Z","steps":["trace[360864176] 'process raft request' (duration: 665.308873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:37.858147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:37.192224Z","time spent":"665.774077ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:38.063537Z","caller":"traceutil/trace.go:171","msg":"trace[1462901895] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"191.624541ms","start":"2026-04-12T20:41:37.871892Z","end":"2026-04-12T20:41:38.063517Z","steps":["trace[1462901895] 'process raft request' (duration: 191.469446ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:38.130739Z","caller":"traceutil/trace.go:171","msg":"trace[2056204130] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"219.126366ms","start":"2026-04-12T20:41:37.911589Z","end":"2026-04-12T20:41:38.130715Z","steps":["trace[2056204130] 'process raft request' (duration: 219.001593ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:39.519076Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003493723570735,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-12T20:41:39.73531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.570068559s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:41:39.73537Z","caller":"traceutil/trace.go:171","msg":"trace[1475598322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"1.570178732s","start":"2026-04-12T20:41:38.165179Z","end":"2026-04-12T20:41:39.735358Z","steps":["trace[1475598322] 'range keys from in-memory index tree' (duration: 1.569995257s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:39.7354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:38.165157Z","time spent":"1.570236294s","remote":"127.0.0.1:49932","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-12T20:41:39.735314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.484684745s","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-04-12T20:41:39.735448Z","caller":"traceutil/trace.go:171","msg":"trace[571112510] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:630; }","duration":"1.484825359s","start":"2026-04-12T20:41:38.250603Z","end":"2026-04-12T20:41:39.735428Z","steps":["trace[571112510] 'range keys from in-memory index tree' (duration: 1.484663124s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:39.946794Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.362868307s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"warn","ts":"2026-04-12T20:41:39.962118Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.377623585s","expected-duration":"1s"} {"level":"info","ts":"2026-04-12T20:41:39.963045Z","caller":"traceutil/trace.go:171","msg":"trace[1525982480] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"944.966476ms","start":"2026-04-12T20:41:39.018058Z","end":"2026-04-12T20:41:39.963025Z","steps":["trace[1525982480] 'read index received' (duration: 944.207335ms)","trace[1525982480] 'applied index is now lower than readState.Index' (duration: 758.211µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:39.963404Z","caller":"traceutil/trace.go:171","msg":"trace[1239128941] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.443164518s","start":"2026-04-12T20:41:38.520209Z","end":"2026-04-12T20:41:39.963374Z","steps":["trace[1239128941] 'process raft request' (duration: 1.442292922s)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:39.963437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"945.366559ms","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-04-12T20:41:39.963551Z","caller":"traceutil/trace.go:171","msg":"trace[1184251138] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:631; }","duration":"945.530844ms","start":"2026-04-12T20:41:39.018004Z","end":"2026-04-12T20:41:39.963535Z","steps":["trace[1184251138] 'agreement among raft nodes before linearized reading' (duration: 945.135543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:39.963552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.03169ms","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-04-12T20:41:39.963576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:38.520179Z","time spent":"1.443297172s","remote":"127.0.0.1:50018","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:39.963621Z","caller":"traceutil/trace.go:171","msg":"trace[1922010034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:631; }","duration":"105.135203ms","start":"2026-04-12T20:41:39.858474Z","end":"2026-04-12T20:41:39.963609Z","steps":["trace[1922010034] 'agreement among raft nodes before linearized reading' (duration: 105.0453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:39.96367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:39.017976Z","time spent":"945.627657ms","remote":"127.0.0.1:50196","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-04-12T20:41:39.963783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.733271ms","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-04-12T20:41:39.963886Z","caller":"traceutil/trace.go:171","msg":"trace[1473646213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:631; }","duration":"104.928467ms","start":"2026-04-12T20:41:39.85894Z","end":"2026-04-12T20:41:39.963869Z","steps":["trace[1473646213] 'agreement among raft nodes before linearized reading' (duration: 104.460614ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:39.963438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:41:39.964283Z","caller":"traceutil/trace.go:171","msg":"trace[381007970] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:631; }","duration":"228.149765ms","start":"2026-04-12T20:41:39.736088Z","end":"2026-04-12T20:41:39.964238Z","steps":["trace[381007970] 'agreement among raft nodes before linearized reading' (duration: 227.068684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:39.964291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.336409ms","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-04-12T20:41:39.964406Z","caller":"traceutil/trace.go:171","msg":"trace[975375354] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:631; }","duration":"105.459583ms","start":"2026-04-12T20:41:39.858904Z","end":"2026-04-12T20:41:39.964364Z","steps":["trace[975375354] 'agreement among raft nodes before linearized reading' (duration: 104.555207ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:41.713463Z","caller":"traceutil/trace.go:171","msg":"trace[449259962] transaction","detail":"{read_only:false; response_revision:657; number_of_response:1; }","duration":"152.527758ms","start":"2026-04-12T20:41:41.560903Z","end":"2026-04-12T20:41:41.713431Z","steps":["trace[449259962] 'process raft request' (duration: 108.328052ms)","trace[449259962] 'compare' (duration: 43.947709ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:41.713769Z","caller":"traceutil/trace.go:171","msg":"trace[1618080268] transaction","detail":"{read_only:false; response_revision:658; number_of_response:1; }","duration":"152.296892ms","start":"2026-04-12T20:41:41.561452Z","end":"2026-04-12T20:41:41.713749Z","steps":["trace[1618080268] 'process raft request' (duration: 151.918162ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:41.713811Z","caller":"traceutil/trace.go:171","msg":"trace[448528248] transaction","detail":"{read_only:false; response_revision:659; number_of_response:1; }","duration":"150.301954ms","start":"2026-04-12T20:41:41.563484Z","end":"2026-04-12T20:41:41.713786Z","steps":["trace[448528248] 'process raft request' (duration: 150.117889ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:41.989239Z","caller":"traceutil/trace.go:171","msg":"trace[845617125] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"161.744341ms","start":"2026-04-12T20:41:41.827471Z","end":"2026-04-12T20:41:41.989215Z","steps":["trace[845617125] 'process raft request' (duration: 161.607057ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:42.216167Z","caller":"traceutil/trace.go:171","msg":"trace[2072879831] linearizableReadLoop","detail":"{readStateIndex:699; appliedIndex:698; }","duration":"188.855028ms","start":"2026-04-12T20:41:42.027294Z","end":"2026-04-12T20:41:42.21615Z","steps":["trace[2072879831] 'read index received' (duration: 188.733945ms)","trace[2072879831] 'applied index is now lower than readState.Index' (duration: 120.283µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:42.216265Z","caller":"traceutil/trace.go:171","msg":"trace[494618429] transaction","detail":"{read_only:false; response_revision:671; number_of_response:1; }","duration":"218.984327ms","start":"2026-04-12T20:41:41.997274Z","end":"2026-04-12T20:41:42.216258Z","steps":["trace[494618429] 'process raft request' (duration: 218.777541ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:42.216536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.053329ms","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-04-12T20:41:42.216618Z","caller":"traceutil/trace.go:171","msg":"trace[656562087] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:671; }","duration":"180.168933ms","start":"2026-04-12T20:41:42.036432Z","end":"2026-04-12T20:41:42.2166Z","steps":["trace[656562087] 'agreement among raft nodes before linearized reading' (duration: 179.974897ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:42.216658Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.364612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-12T20:41:42.216688Z","caller":"traceutil/trace.go:171","msg":"trace[138311955] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:0; response_revision:671; }","duration":"189.391523ms","start":"2026-04-12T20:41:42.027288Z","end":"2026-04-12T20:41:42.216679Z","steps":["trace[138311955] 'agreement among raft nodes before linearized reading' (duration: 189.357162ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:42.216627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.115551ms","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-04-12T20:41:42.216864Z","caller":"traceutil/trace.go:171","msg":"trace[2006725770] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:671; }","duration":"180.371068ms","start":"2026-04-12T20:41:42.036485Z","end":"2026-04-12T20:41:42.216856Z","steps":["trace[2006725770] 'agreement among raft nodes before linearized reading' (duration: 180.047569ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:42.216909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.356588ms","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-04-12T20:41:42.216976Z","caller":"traceutil/trace.go:171","msg":"trace[1197592261] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:671; }","duration":"180.486102ms","start":"2026-04-12T20:41:42.036474Z","end":"2026-04-12T20:41:42.21696Z","steps":["trace[1197592261] 'agreement among raft nodes before linearized reading' (duration: 180.198894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:42.216979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.497902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-04-12T20:41:42.217032Z","caller":"traceutil/trace.go:171","msg":"trace[588416282] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:671; }","duration":"180.576843ms","start":"2026-04-12T20:41:42.036449Z","end":"2026-04-12T20:41:42.217026Z","steps":["trace[588416282] 'agreement among raft nodes before linearized reading' (duration: 180.483451ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:44.368094Z","caller":"traceutil/trace.go:171","msg":"trace[543883217] linearizableReadLoop","detail":"{readStateIndex:729; appliedIndex:728; }","duration":"144.19407ms","start":"2026-04-12T20:41:44.223875Z","end":"2026-04-12T20:41:44.36807Z","steps":["trace[543883217] 'read index received' (duration: 144.015105ms)","trace[543883217] 'applied index is now lower than readState.Index' (duration: 177.905µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:44.368137Z","caller":"traceutil/trace.go:171","msg":"trace[162471178] transaction","detail":"{read_only:false; response_revision:700; number_of_response:1; }","duration":"340.655821ms","start":"2026-04-12T20:41:44.027461Z","end":"2026-04-12T20:41:44.368117Z","steps":["trace[162471178] 'process raft request' (duration: 340.489056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:44.368306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:44.027438Z","time spent":"340.795824ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9343,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:41:44.368401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.515698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-04-12T20:41:44.368456Z","caller":"traceutil/trace.go:171","msg":"trace[1591367420] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:700; }","duration":"144.607311ms","start":"2026-04-12T20:41:44.223834Z","end":"2026-04-12T20:41:44.368441Z","steps":["trace[1591367420] 'agreement among raft nodes before linearized reading' (duration: 144.400015ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:44.368481Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.452226ms","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-04-12T20:41:44.368513Z","caller":"traceutil/trace.go:171","msg":"trace[317162657] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:700; }","duration":"136.504407ms","start":"2026-04-12T20:41:44.231997Z","end":"2026-04-12T20:41:44.368502Z","steps":["trace[317162657] 'agreement among raft nodes before linearized reading' (duration: 136.406505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:44.36851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.61496ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"warn","ts":"2026-04-12T20:41:44.368403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.686294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:1 size:9320"} {"level":"info","ts":"2026-04-12T20:41:44.368578Z","caller":"traceutil/trace.go:171","msg":"trace[1762910685] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:700; }","duration":"140.680602ms","start":"2026-04-12T20:41:44.227864Z","end":"2026-04-12T20:41:44.368545Z","steps":["trace[1762910685] 'agreement among raft nodes before linearized reading' (duration: 140.568719ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:44.36863Z","caller":"traceutil/trace.go:171","msg":"trace[1270701939] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:1; response_revision:700; }","duration":"143.931731ms","start":"2026-04-12T20:41:44.224657Z","end":"2026-04-12T20:41:44.368588Z","steps":["trace[1270701939] 'agreement among raft nodes before linearized reading' (duration: 143.603762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:44.368652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.628011ms","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-04-12T20:41:44.368762Z","caller":"traceutil/trace.go:171","msg":"trace[1693580355] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:700; }","duration":"140.791475ms","start":"2026-04-12T20:41:44.22795Z","end":"2026-04-12T20:41:44.368741Z","steps":["trace[1693580355] 'agreement among raft nodes before linearized reading' (duration: 140.418895ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:44.65291Z","caller":"traceutil/trace.go:171","msg":"trace[2056483588] transaction","detail":"{read_only:false; response_revision:702; number_of_response:1; }","duration":"280.003007ms","start":"2026-04-12T20:41:44.372881Z","end":"2026-04-12T20:41:44.652884Z","steps":["trace[2056483588] 'process raft request' (duration: 227.541659ms)","trace[2056483588] 'compare' (duration: 52.275842ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:44.653154Z","caller":"traceutil/trace.go:171","msg":"trace[185235797] transaction","detail":"{read_only:false; response_revision:703; number_of_response:1; }","duration":"280.13393ms","start":"2026-04-12T20:41:44.373004Z","end":"2026-04-12T20:41:44.653138Z","steps":["trace[185235797] 'process raft request' (duration: 279.821592ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:44.653609Z","caller":"traceutil/trace.go:171","msg":"trace[365247880] transaction","detail":"{read_only:false; response_revision:705; number_of_response:1; }","duration":"276.10341ms","start":"2026-04-12T20:41:44.377489Z","end":"2026-04-12T20:41:44.653593Z","steps":["trace[365247880] 'process raft request' (duration: 276.001457ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:44.653642Z","caller":"traceutil/trace.go:171","msg":"trace[894172773] transaction","detail":"{read_only:false; response_revision:704; number_of_response:1; }","duration":"280.590823ms","start":"2026-04-12T20:41:44.373032Z","end":"2026-04-12T20:41:44.653622Z","steps":["trace[894172773] 'process raft request' (duration: 280.050518ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:44.653601Z","caller":"traceutil/trace.go:171","msg":"trace[1345536387] linearizableReadLoop","detail":"{readStateIndex:734; appliedIndex:729; }","duration":"228.851575ms","start":"2026-04-12T20:41:44.424728Z","end":"2026-04-12T20:41:44.65358Z","steps":["trace[1345536387] 'read index received' (duration: 10.983673ms)","trace[1345536387] 'applied index is now lower than readState.Index' (duration: 217.866952ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:41:44.653872Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.092312ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io\" ","response":"range_response_count:1 size:5177"} {"level":"info","ts":"2026-04-12T20:41:44.653982Z","caller":"traceutil/trace.go:171","msg":"trace[1502396997] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodeconfigs.cilium.io; range_end:; response_count:1; response_revision:705; }","duration":"229.280257ms","start":"2026-04-12T20:41:44.424681Z","end":"2026-04-12T20:41:44.653961Z","steps":["trace[1502396997] 'agreement among raft nodes before linearized reading' (duration: 228.965468ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:45.069948Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.353287ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-12T20:41:45.070061Z","caller":"traceutil/trace.go:171","msg":"trace[77820447] linearizableReadLoop","detail":"{readStateIndex:735; appliedIndex:734; }","duration":"416.236247ms","start":"2026-04-12T20:41:44.653808Z","end":"2026-04-12T20:41:45.070044Z","steps":["trace[77820447] 'read index received' (duration: 272.770956ms)","trace[77820447] 'applied index is now lower than readState.Index' (duration: 143.463911ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:41:45.070372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"445.783443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" ","response":"range_response_count:1 size:4333"} {"level":"info","ts":"2026-04-12T20:41:45.070446Z","caller":"traceutil/trace.go:171","msg":"trace[726596148] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:706; }","duration":"445.973188ms","start":"2026-04-12T20:41:44.624456Z","end":"2026-04-12T20:41:45.07043Z","steps":["trace[726596148] 'agreement among raft nodes before linearized reading' (duration: 445.65791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:45.070493Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:44.624444Z","time spent":"446.03883ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Range","request count":0,"request size":85,"response count":1,"response size":4357,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io\" "} {"level":"warn","ts":"2026-04-12T20:41:45.070469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.72085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-rdkjv\" ","response":"range_response_count:1 size:18063"} {"level":"info","ts":"2026-04-12T20:41:45.070552Z","caller":"traceutil/trace.go:171","msg":"trace[403092673] range","detail":"{range_begin:/registry/pods/kube-system/cilium-rdkjv; range_end:; response_count:1; response_revision:706; }","duration":"487.892084ms","start":"2026-04-12T20:41:44.582648Z","end":"2026-04-12T20:41:45.07054Z","steps":["trace[403092673] 'agreement among raft nodes before linearized reading' (duration: 487.535395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:45.070675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:44.58263Z","time spent":"488.003077ms","remote":"127.0.0.1:50104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18087,"request content":"key:\"/registry/pods/kube-system/cilium-rdkjv\" "} {"level":"info","ts":"2026-04-12T20:41:45.070891Z","caller":"traceutil/trace.go:171","msg":"trace[698846524] transaction","detail":"{read_only:false; response_revision:706; number_of_response:1; }","duration":"486.449444ms","start":"2026-04-12T20:41:44.584421Z","end":"2026-04-12T20:41:45.070871Z","steps":["trace[698846524] 'process raft request' (duration: 342.091709ms)","trace[698846524] 'compare' (duration: 143.039089ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:41:45.070986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:44.5844Z","time spent":"486.535487ms","remote":"127.0.0.1:49998","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":773,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-12T20:41:45.183105Z","caller":"traceutil/trace.go:171","msg":"trace[445247732] linearizableReadLoop","detail":"{readStateIndex:737; appliedIndex:735; }","duration":"112.973656ms","start":"2026-04-12T20:41:45.070089Z","end":"2026-04-12T20:41:45.183063Z","steps":["trace[445247732] 'read index received' (duration: 112.664688ms)","trace[445247732] 'applied index is now lower than readState.Index' (duration: 308.198µs)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:45.183271Z","caller":"traceutil/trace.go:171","msg":"trace[687369380] transaction","detail":"{read_only:false; response_revision:707; number_of_response:1; }","duration":"521.838769ms","start":"2026-04-12T20:41:44.661388Z","end":"2026-04-12T20:41:45.183227Z","steps":["trace[687369380] 'process raft request' (duration: 521.321455ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:45.183375Z","caller":"traceutil/trace.go:171","msg":"trace[1046162750] transaction","detail":"{read_only:false; response_revision:708; number_of_response:1; }","duration":"330.442939ms","start":"2026-04-12T20:41:44.852832Z","end":"2026-04-12T20:41:45.183275Z","steps":["trace[1046162750] 'process raft request' (duration: 330.070779ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:45.183419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:44.661362Z","time spent":"521.947453ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10362,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:41:45.183646Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:44.852803Z","time spent":"330.755387ms","remote":"127.0.0.1:49976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":187455,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-12T20:41:45.183889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.51911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io\" ","response":"range_response_count:1 size:13229"} {"level":"info","ts":"2026-04-12T20:41:45.183967Z","caller":"traceutil/trace.go:171","msg":"trace[1836249803] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:708; }","duration":"158.644444ms","start":"2026-04-12T20:41:45.0253Z","end":"2026-04-12T20:41:45.183945Z","steps":["trace[1836249803] 'agreement among raft nodes before linearized reading' (duration: 158.170501ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:45.211317Z","caller":"traceutil/trace.go:171","msg":"trace[1031925204] transaction","detail":"{read_only:false; response_revision:709; number_of_response:1; }","duration":"124.625485ms","start":"2026-04-12T20:41:45.086667Z","end":"2026-04-12T20:41:45.211293Z","steps":["trace[1031925204] 'process raft request' (duration: 124.491902ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:45.533812Z","caller":"traceutil/trace.go:171","msg":"trace[897793517] transaction","detail":"{read_only:false; response_revision:712; number_of_response:1; }","duration":"119.069442ms","start":"2026-04-12T20:41:45.414711Z","end":"2026-04-12T20:41:45.533781Z","steps":["trace[897793517] 'process raft request' (duration: 105.172233ms)","trace[897793517] 'compare' (duration: 13.090727ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:45.535762Z","caller":"traceutil/trace.go:171","msg":"trace[1192525993] transaction","detail":"{read_only:false; response_revision:713; number_of_response:1; }","duration":"113.235814ms","start":"2026-04-12T20:41:45.422498Z","end":"2026-04-12T20:41:45.535734Z","steps":["trace[1192525993] 'process raft request' (duration: 112.848783ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:45.53636Z","caller":"traceutil/trace.go:171","msg":"trace[2015944373] linearizableReadLoop","detail":"{readStateIndex:742; appliedIndex:740; }","duration":"109.228504ms","start":"2026-04-12T20:41:45.427085Z","end":"2026-04-12T20:41:45.536314Z","steps":["trace[2015944373] 'read index received' (duration: 92.804016ms)","trace[2015944373] 'applied index is now lower than readState.Index' (duration: 16.329496ms)"],"step_count":2} {"level":"warn","ts":"2026-04-12T20:41:45.536635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.528212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:1 size:24244"} {"level":"info","ts":"2026-04-12T20:41:45.537354Z","caller":"traceutil/trace.go:171","msg":"trace[1645411351] transaction","detail":"{read_only:false; response_revision:714; number_of_response:1; }","duration":"105.477371ms","start":"2026-04-12T20:41:45.431854Z","end":"2026-04-12T20:41:45.537331Z","steps":["trace[1645411351] 'process raft request' (duration: 103.628521ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:45.53728Z","caller":"traceutil/trace.go:171","msg":"trace[2145315538] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:1; response_revision:714; }","duration":"110.18545ms","start":"2026-04-12T20:41:45.427048Z","end":"2026-04-12T20:41:45.537233Z","steps":["trace[2145315538] 'agreement among raft nodes before linearized reading' (duration: 109.371068ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:45.767724Z","caller":"traceutil/trace.go:171","msg":"trace[840843964] linearizableReadLoop","detail":"{readStateIndex:746; appliedIndex:745; }","duration":"132.718306ms","start":"2026-04-12T20:41:45.634985Z","end":"2026-04-12T20:41:45.767704Z","steps":["trace[840843964] 'read index received' (duration: 131.518073ms)","trace[840843964] 'applied index is now lower than readState.Index' (duration: 1.199443ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:45.767762Z","caller":"traceutil/trace.go:171","msg":"trace[47510113] transaction","detail":"{read_only:false; response_revision:717; number_of_response:1; }","duration":"160.32183ms","start":"2026-04-12T20:41:45.607416Z","end":"2026-04-12T20:41:45.767737Z","steps":["trace[47510113] 'process raft request' (duration: 159.000324ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:45.767899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.890051ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-04-12T20:41:45.767934Z","caller":"traceutil/trace.go:171","msg":"trace[2039038316] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:717; }","duration":"132.971443ms","start":"2026-04-12T20:41:45.634953Z","end":"2026-04-12T20:41:45.767925Z","steps":["trace[2039038316] 'agreement among raft nodes before linearized reading' (duration: 132.83086ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:45.773828Z","caller":"traceutil/trace.go:171","msg":"trace[1434460712] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"130.190547ms","start":"2026-04-12T20:41:45.643617Z","end":"2026-04-12T20:41:45.773808Z","steps":["trace[1434460712] 'process raft request' (duration: 129.751465ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:57.151213Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":15036003493723571055,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-12T20:41:57.412532Z","caller":"traceutil/trace.go:171","msg":"trace[718754985] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"926.911238ms","start":"2026-04-12T20:41:56.485592Z","end":"2026-04-12T20:41:57.412503Z","steps":["trace[718754985] 'process raft request' (duration: 926.707603ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:57.412715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:56.485572Z","time spent":"927.04522ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-12T20:41:57.422159Z","caller":"traceutil/trace.go:171","msg":"trace[1173824939] linearizableReadLoop","detail":"{readStateIndex:830; appliedIndex:828; }","duration":"771.945579ms","start":"2026-04-12T20:41:56.650197Z","end":"2026-04-12T20:41:57.422143Z","steps":["trace[1173824939] 'read index received' (duration: 762.135479ms)","trace[1173824939] 'applied index is now lower than readState.Index' (duration: 9.80931ms)"],"step_count":2} {"level":"info","ts":"2026-04-12T20:41:57.422259Z","caller":"traceutil/trace.go:171","msg":"trace[1157113789] transaction","detail":"{read_only:false; response_revision:797; number_of_response:1; }","duration":"359.175012ms","start":"2026-04-12T20:41:57.06306Z","end":"2026-04-12T20:41:57.422235Z","steps":["trace[1157113789] 'process raft request' (duration: 359.000838ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:57.422398Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:57.063035Z","time spent":"359.251343ms","remote":"127.0.0.1:50096","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4904,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-12T20:41:57.422409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.097737ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-12T20:41:57.422456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"719.566592ms","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-04-12T20:41:57.422486Z","caller":"traceutil/trace.go:171","msg":"trace[678500311] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:797; }","duration":"257.2301ms","start":"2026-04-12T20:41:57.165233Z","end":"2026-04-12T20:41:57.422463Z","steps":["trace[678500311] 'agreement among raft nodes before linearized reading' (duration: 257.129848ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:57.422507Z","caller":"traceutil/trace.go:171","msg":"trace[282372745] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:797; }","duration":"719.673574ms","start":"2026-04-12T20:41:56.702819Z","end":"2026-04-12T20:41:57.422492Z","steps":["trace[282372745] 'agreement among raft nodes before linearized reading' (duration: 719.476599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:57.422543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:56.702784Z","time spent":"719.750416ms","remote":"127.0.0.1:50196","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-04-12T20:41:57.422721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"772.542373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rhbh4\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-04-12T20:41:57.422753Z","caller":"traceutil/trace.go:171","msg":"trace[1746939985] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rhbh4; range_end:; response_count:1; response_revision:797; }","duration":"772.648186ms","start":"2026-04-12T20:41:56.650097Z","end":"2026-04-12T20:41:57.422745Z","steps":["trace[1746939985] 'agreement among raft nodes before linearized reading' (duration: 772.556584ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:57.422773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:56.650041Z","time spent":"772.727028ms","remote":"127.0.0.1:50104","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":3988,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-rhbh4\" "} {"level":"warn","ts":"2026-04-12T20:41:57.422783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.536886ms","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-04-12T20:41:57.422831Z","caller":"traceutil/trace.go:171","msg":"trace[380771579] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:797; }","duration":"718.606328ms","start":"2026-04-12T20:41:56.704213Z","end":"2026-04-12T20:41:57.422819Z","steps":["trace[380771579] 'agreement among raft nodes before linearized reading' (duration: 718.459374ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:57.422861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:56.7042Z","time spent":"718.653189ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-12T20:41:57.422898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.698975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-04-12T20:41:57.422902Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"718.641479ms","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-04-12T20:41:57.422926Z","caller":"traceutil/trace.go:171","msg":"trace[614488494] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:797; }","duration":"221.772897ms","start":"2026-04-12T20:41:57.201146Z","end":"2026-04-12T20:41:57.422919Z","steps":["trace[614488494] 'agreement among raft nodes before linearized reading' (duration: 221.727966ms)"],"step_count":1} {"level":"info","ts":"2026-04-12T20:41:57.422937Z","caller":"traceutil/trace.go:171","msg":"trace[967525178] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:797; }","duration":"718.697049ms","start":"2026-04-12T20:41:56.704229Z","end":"2026-04-12T20:41:57.422926Z","steps":["trace[967525178] 'agreement among raft nodes before linearized reading' (duration: 718.583197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-12T20:41:57.422966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-12T20:41:56.704218Z","time spent":"718.741241ms","remote":"127.0.0.1:50196","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-12T20:43:44.236957Z","caller":"traceutil/trace.go:171","msg":"trace[1969820553] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"119.205399ms","start":"2026-04-12T20:43:44.117703Z","end":"2026-04-12T20:43:44.236374Z","steps":["trace[1969820553] 'process raft request' (duration: 118.091466ms)"],"step_count":1}