{"level":"warn","ts":"2026-05-05T20:55:59.660539Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T20:55:59.660631Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.49: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.49:2380","--initial-cluster=instance=https://199.204.45.49:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.49:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.49: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-05-05T20:55:59.660701Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T20:55:59.660714Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-05-05T20:55:59.660731Z","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-05-05T20:55:59.661413Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"]} {"level":"info","ts":"2026-05-05T20:55:59.661486Z","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.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.49: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-05-05T20:55:59.666753Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.042664ms"} {"level":"info","ts":"2026-05-05T20:55:59.675015Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d0ba3c4de8c6ef7a","cluster-id":"e76701b877f442ac"} {"level":"info","ts":"2026-05-05T20:55:59.675072Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T20:55:59.675087Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 0"} {"level":"info","ts":"2026-05-05T20:55:59.675097Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d0ba3c4de8c6ef7a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T20:55:59.675107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became follower at term 1"} {"level":"info","ts":"2026-05-05T20:55:59.675139Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"warn","ts":"2026-05-05T20:55:59.687687Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T20:55:59.689074Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T20:55:59.691321Z","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-05-05T20:55:59.694325Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d0ba3c4de8c6ef7a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T20:55:59.694465Z","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-05-05T20:55:59.694549Z","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-05-05T20:55:59.694563Z","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-05-05T20:55:59.694979Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d0ba3c4de8c6ef7a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T20:55:59.700276Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a switched to configuration voters=(15040400210872692602)"} {"level":"info","ts":"2026-05-05T20:55:59.700578Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","added-peer-id":"d0ba3c4de8c6ef7a","added-peer-peer-urls":["https://199.204.45.49:2380"]} {"level":"info","ts":"2026-05-05T20:55:59.703381Z","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-05-05T20:55:59.703569Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-05-05T20:55:59.703608Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.49:2380"} {"level":"info","ts":"2026-05-05T20:55:59.703688Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d0ba3c4de8c6ef7a","initial-advertise-peer-urls":["https://199.204.45.49:2380"],"listen-peer-urls":["https://199.204.45.49:2380"],"advertise-client-urls":["https://199.204.45.49:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.49:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T20:55:59.70373Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T20:56:00.675787Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T20:56:00.675855Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T20:56:00.675871Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgPreVoteResp from d0ba3c4de8c6ef7a at term 1"} {"level":"info","ts":"2026-05-05T20:56:00.675885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became candidate at term 2"} {"level":"info","ts":"2026-05-05T20:56:00.675892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a received MsgVoteResp from d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-05-05T20:56:00.675903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d0ba3c4de8c6ef7a became leader at term 2"} {"level":"info","ts":"2026-05-05T20:56:00.675911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d0ba3c4de8c6ef7a elected leader d0ba3c4de8c6ef7a at term 2"} {"level":"info","ts":"2026-05-05T20:56:00.677284Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d0ba3c4de8c6ef7a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.49:2379]}","request-path":"/0/members/d0ba3c4de8c6ef7a/attributes","cluster-id":"e76701b877f442ac","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T20:56:00.677296Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T20:56:00.677389Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T20:56:00.678049Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T20:56:00.678129Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T20:56:00.680981Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.49:2379"} {"level":"info","ts":"2026-05-05T20:56:00.681108Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T20:56:00.682006Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e76701b877f442ac","local-member-id":"d0ba3c4de8c6ef7a","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T20:56:00.682081Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T20:56:00.682105Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T20:56:00.682408Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-05-05T20:56:03.877025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.477651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:route-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:03.877141Z","caller":"traceutil/trace.go:171","msg":"trace[343016692] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:route-controller; range_end:; response_count:0; response_revision:168; }","duration":"141.658573ms","start":"2026-05-05T20:56:03.735455Z","end":"2026-05-05T20:56:03.877114Z","steps":["trace[343016692] 'range keys from in-memory index tree' (duration: 141.411651ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:04.583366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.158329ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T20:56:04.583623Z","caller":"traceutil/trace.go:171","msg":"trace[1236534665] linearizableReadLoop","detail":"{readStateIndex:211; appliedIndex:210; }","duration":"168.111596ms","start":"2026-05-05T20:56:04.415487Z","end":"2026-05-05T20:56:04.583598Z","steps":["trace[1236534665] 'read index received' (duration: 52.054229ms)","trace[1236534665] 'applied index is now lower than readState.Index' (duration: 116.055797ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:04.583625Z","caller":"traceutil/trace.go:171","msg":"trace[1168233627] transaction","detail":"{read_only:false; response_revision:205; number_of_response:1; }","duration":"176.553906ms","start":"2026-05-05T20:56:04.406959Z","end":"2026-05-05T20:56:04.583513Z","steps":["trace[1168233627] 'process raft request' (duration: 60.56464ms)","trace[1168233627] 'compare' (duration: 114.972558ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:04.583762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.276827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18acc6d2256f629c\" ","response":"range_response_count:1 size:653"} {"level":"info","ts":"2026-05-05T20:56:04.583805Z","caller":"traceutil/trace.go:171","msg":"trace[1769913184] range","detail":"{range_begin:/registry/events/default/instance.18acc6d2256f629c; range_end:; response_count:1; response_revision:205; }","duration":"168.359397ms","start":"2026-05-05T20:56:04.415435Z","end":"2026-05-05T20:56:04.583794Z","steps":["trace[1769913184] 'agreement among raft nodes before linearized reading' (duration: 168.237946ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:04.584161Z","caller":"traceutil/trace.go:171","msg":"trace[449966897] transaction","detail":"{read_only:false; response_revision:206; number_of_response:1; }","duration":"168.034205ms","start":"2026-05-05T20:56:04.416056Z","end":"2026-05-05T20:56:04.58409Z","steps":["trace[449966897] 'process raft request' (duration: 167.47958ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:04.756059Z","caller":"traceutil/trace.go:171","msg":"trace[1814900195] transaction","detail":"{read_only:false; response_revision:210; number_of_response:1; }","duration":"116.461941ms","start":"2026-05-05T20:56:04.639572Z","end":"2026-05-05T20:56:04.756034Z","steps":["trace[1814900195] 'process raft request' (duration: 81.331945ms)","trace[1814900195] 'compare' (duration: 34.961074ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:04.756096Z","caller":"traceutil/trace.go:171","msg":"trace[602935461] linearizableReadLoop","detail":"{readStateIndex:216; appliedIndex:215; }","duration":"109.026328ms","start":"2026-05-05T20:56:04.647031Z","end":"2026-05-05T20:56:04.756058Z","steps":["trace[602935461] 'read index received' (duration: 73.850532ms)","trace[602935461] 'applied index is now lower than readState.Index' (duration: 35.174296ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:04.75624Z","caller":"traceutil/trace.go:171","msg":"trace[1502593119] transaction","detail":"{read_only:false; response_revision:211; number_of_response:1; }","duration":"104.433261ms","start":"2026-05-05T20:56:04.651797Z","end":"2026-05-05T20:56:04.75623Z","steps":["trace[1502593119] 'process raft request' (duration: 104.222589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:04.756363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.043553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18acc6d2256f7e80\" ","response":"range_response_count:1 size:651"} {"level":"warn","ts":"2026-05-05T20:56:04.756414Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.845932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-05-05T20:56:04.756452Z","caller":"traceutil/trace.go:171","msg":"trace[1209052323] range","detail":"{range_begin:/registry/events/default/instance.18acc6d2256f7e80; range_end:; response_count:1; response_revision:211; }","duration":"118.183366ms","start":"2026-05-05T20:56:04.638233Z","end":"2026-05-05T20:56:04.756417Z","steps":["trace[1209052323] 'agreement among raft nodes before linearized reading' (duration: 118.011625ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:04.756514Z","caller":"traceutil/trace.go:171","msg":"trace[1035653948] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:211; }","duration":"106.000923ms","start":"2026-05-05T20:56:04.650493Z","end":"2026-05-05T20:56:04.756494Z","steps":["trace[1035653948] 'agreement among raft nodes before linearized reading' (duration: 105.830611ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:05.041568Z","caller":"traceutil/trace.go:171","msg":"trace[1855799593] linearizableReadLoop","detail":"{readStateIndex:222; appliedIndex:220; }","duration":"217.6326ms","start":"2026-05-05T20:56:04.823903Z","end":"2026-05-05T20:56:05.041535Z","steps":["trace[1855799593] 'read index received' (duration: 161.523309ms)","trace[1855799593] 'applied index is now lower than readState.Index' (duration: 56.108011ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:05.041654Z","caller":"traceutil/trace.go:171","msg":"trace[1692640900] transaction","detail":"{read_only:false; response_revision:215; number_of_response:1; }","duration":"219.795749ms","start":"2026-05-05T20:56:04.821824Z","end":"2026-05-05T20:56:05.041619Z","steps":["trace[1692640900] 'process raft request' (duration: 163.624778ms)","trace[1692640900] 'compare' (duration: 55.838218ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:05.041665Z","caller":"traceutil/trace.go:171","msg":"trace[1324003940] transaction","detail":"{read_only:false; response_revision:216; number_of_response:1; }","duration":"217.876642ms","start":"2026-05-05T20:56:04.823763Z","end":"2026-05-05T20:56:05.04164Z","steps":["trace[1324003940] 'process raft request' (duration: 217.70401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:05.041782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.860793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/ttl-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:05.041875Z","caller":"traceutil/trace.go:171","msg":"trace[1571250715] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/ttl-controller; range_end:; response_count:0; response_revision:216; }","duration":"217.963973ms","start":"2026-05-05T20:56:04.823857Z","end":"2026-05-05T20:56:05.041821Z","steps":["trace[1571250715] 'agreement among raft nodes before linearized reading' (duration: 217.806011ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:05.270958Z","caller":"traceutil/trace.go:171","msg":"trace[1713582386] transaction","detail":"{read_only:false; response_revision:221; number_of_response:1; }","duration":"109.425245ms","start":"2026-05-05T20:56:05.161504Z","end":"2026-05-05T20:56:05.270929Z","steps":["trace[1713582386] 'process raft request' (duration: 106.893343ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:05.475082Z","caller":"traceutil/trace.go:171","msg":"trace[370549066] linearizableReadLoop","detail":"{readStateIndex:230; appliedIndex:229; }","duration":"129.191059ms","start":"2026-05-05T20:56:05.345851Z","end":"2026-05-05T20:56:05.475042Z","steps":["trace[370549066] 'read index received' (duration: 40.65497ms)","trace[370549066] 'applied index is now lower than readState.Index' (duration: 88.534139ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:05.475205Z","caller":"traceutil/trace.go:171","msg":"trace[1055726501] transaction","detail":"{read_only:false; response_revision:224; number_of_response:1; }","duration":"168.694089ms","start":"2026-05-05T20:56:05.306475Z","end":"2026-05-05T20:56:05.475169Z","steps":["trace[1055726501] 'process raft request' (duration: 80.1203ms)","trace[1055726501] 'compare' (duration: 88.207366ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:05.475386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.488082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18acc6d2256f0963\" ","response":"range_response_count:1 size:657"} {"level":"info","ts":"2026-05-05T20:56:05.475486Z","caller":"traceutil/trace.go:171","msg":"trace[1469628084] range","detail":"{range_begin:/registry/events/default/instance.18acc6d2256f0963; range_end:; response_count:1; response_revision:224; }","duration":"129.622203ms","start":"2026-05-05T20:56:05.345845Z","end":"2026-05-05T20:56:05.475468Z","steps":["trace[1469628084] 'agreement among raft nodes before linearized reading' (duration: 129.33824ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:05.820503Z","caller":"traceutil/trace.go:171","msg":"trace[200981442] transaction","detail":"{read_only:false; response_revision:232; number_of_response:1; }","duration":"106.209497ms","start":"2026-05-05T20:56:05.714273Z","end":"2026-05-05T20:56:05.820483Z","steps":["trace[200981442] 'process raft request' (duration: 52.231675ms)","trace[200981442] 'compare' (duration: 53.88252ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:05.820794Z","caller":"traceutil/trace.go:171","msg":"trace[1401692745] linearizableReadLoop","detail":"{readStateIndex:240; appliedIndex:237; }","duration":"104.675996ms","start":"2026-05-05T20:56:05.716099Z","end":"2026-05-05T20:56:05.820775Z","steps":["trace[1401692745] 'read index received' (duration: 50.389401ms)","trace[1401692745] 'applied index is now lower than readState.Index' (duration: 54.285105ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:05.820819Z","caller":"traceutil/trace.go:171","msg":"trace[1421650133] transaction","detail":"{read_only:false; response_revision:234; number_of_response:1; }","duration":"105.659793ms","start":"2026-05-05T20:56:05.715146Z","end":"2026-05-05T20:56:05.820806Z","steps":["trace[1421650133] 'process raft request' (duration: 105.458881ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:05.820925Z","caller":"traceutil/trace.go:171","msg":"trace[1284499899] transaction","detail":"{read_only:false; response_revision:233; number_of_response:1; }","duration":"106.392029ms","start":"2026-05-05T20:56:05.714527Z","end":"2026-05-05T20:56:05.820919Z","steps":["trace[1284499899] 'process raft request' (duration: 106.003166ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:05.821104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.946628ms","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-05-05T20:56:05.821263Z","caller":"traceutil/trace.go:171","msg":"trace[317183133] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:234; }","duration":"105.099479ms","start":"2026-05-05T20:56:05.716068Z","end":"2026-05-05T20:56:05.821167Z","steps":["trace[317183133] 'agreement among raft nodes before linearized reading' (duration: 104.785626ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:06.061339Z","caller":"traceutil/trace.go:171","msg":"trace[1559984561] transaction","detail":"{read_only:false; response_revision:238; number_of_response:1; }","duration":"153.70334ms","start":"2026-05-05T20:56:05.907609Z","end":"2026-05-05T20:56:06.061313Z","steps":["trace[1559984561] 'process raft request' (duration: 56.28079ms)","trace[1559984561] 'compare' (duration: 97.124257ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:06.061626Z","caller":"traceutil/trace.go:171","msg":"trace[64083690] transaction","detail":"{read_only:false; response_revision:239; number_of_response:1; }","duration":"152.546091ms","start":"2026-05-05T20:56:05.908955Z","end":"2026-05-05T20:56:06.061501Z","steps":["trace[64083690] 'process raft request' (duration: 152.292128ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:06.061425Z","caller":"traceutil/trace.go:171","msg":"trace[298998550] transaction","detail":"{read_only:false; response_revision:240; number_of_response:1; }","duration":"152.067697ms","start":"2026-05-05T20:56:05.909323Z","end":"2026-05-05T20:56:06.061391Z","steps":["trace[298998550] 'process raft request' (duration: 152.015746ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:06.384012Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.710718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T20:56:06.384093Z","caller":"traceutil/trace.go:171","msg":"trace[1098903958] linearizableReadLoop","detail":"{readStateIndex:248; appliedIndex:247; }","duration":"252.324972ms","start":"2026-05-05T20:56:06.131756Z","end":"2026-05-05T20:56:06.384081Z","steps":["trace[1098903958] 'read index received' (duration: 58.318183ms)","trace[1098903958] 'applied index is now lower than readState.Index' (duration: 194.005889ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:06.384203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.451343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/service-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:06.384223Z","caller":"traceutil/trace.go:171","msg":"trace[1342388366] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/service-controller; range_end:; response_count:0; response_revision:242; }","duration":"252.507875ms","start":"2026-05-05T20:56:06.131709Z","end":"2026-05-05T20:56:06.384217Z","steps":["trace[1342388366] 'agreement among raft nodes before linearized reading' (duration: 252.432434ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:06.384186Z","caller":"traceutil/trace.go:171","msg":"trace[1057313841] transaction","detail":"{read_only:false; response_revision:242; number_of_response:1; }","duration":"314.767581ms","start":"2026-05-05T20:56:06.069366Z","end":"2026-05-05T20:56:06.384134Z","steps":["trace[1057313841] 'process raft request' (duration: 120.779961ms)","trace[1057313841] 'compare' (duration: 193.603147ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:06.384314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.525823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T20:56:06.384354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.183917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:06.384363Z","caller":"traceutil/trace.go:171","msg":"trace[2106615292] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:242; }","duration":"241.616844ms","start":"2026-05-05T20:56:06.142734Z","end":"2026-05-05T20:56:06.384351Z","steps":["trace[2106615292] 'agreement among raft nodes before linearized reading' (duration: 241.526153ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:06.384415Z","caller":"traceutil/trace.go:171","msg":"trace[2106841843] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:0; response_revision:242; }","duration":"214.273628ms","start":"2026-05-05T20:56:06.170106Z","end":"2026-05-05T20:56:06.38438Z","steps":["trace[2106841843] 'agreement among raft nodes before linearized reading' (duration: 214.171857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:06.384367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:06.06935Z","time spent":"314.921822ms","remote":"127.0.0.1:54840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":636,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T20:56:10.190033Z","caller":"traceutil/trace.go:171","msg":"trace[172267] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"129.07613ms","start":"2026-05-05T20:56:10.060901Z","end":"2026-05-05T20:56:10.189978Z","steps":["trace[172267] 'process raft request' (duration: 128.160533ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:10.30563Z","caller":"traceutil/trace.go:171","msg":"trace[623667459] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"105.824913ms","start":"2026-05-05T20:56:10.199762Z","end":"2026-05-05T20:56:10.305586Z","steps":["trace[623667459] 'process raft request' (duration: 104.30607ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:10.305711Z","caller":"traceutil/trace.go:171","msg":"trace[639942798] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"103.815637ms","start":"2026-05-05T20:56:10.201858Z","end":"2026-05-05T20:56:10.305673Z","steps":["trace[639942798] 'process raft request' (duration: 103.678616ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:10.305968Z","caller":"traceutil/trace.go:171","msg":"trace[64739231] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"105.36296ms","start":"2026-05-05T20:56:10.200477Z","end":"2026-05-05T20:56:10.305839Z","steps":["trace[64739231] 'process raft request' (duration: 105.005187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:10.935841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.784112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T20:56:10.936123Z","caller":"traceutil/trace.go:171","msg":"trace[1254043615] linearizableReadLoop","detail":"{readStateIndex:394; appliedIndex:390; }","duration":"157.761572ms","start":"2026-05-05T20:56:10.778342Z","end":"2026-05-05T20:56:10.936103Z","steps":["trace[1254043615] 'read index received' (duration: 139.234773ms)","trace[1254043615] 'applied index is now lower than readState.Index' (duration: 18.525709ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:10.936222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.882663ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T20:56:10.936253Z","caller":"traceutil/trace.go:171","msg":"trace[120149468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:385; }","duration":"157.974063ms","start":"2026-05-05T20:56:10.77827Z","end":"2026-05-05T20:56:10.936245Z","steps":["trace[120149468] 'agreement among raft nodes before linearized reading' (duration: 157.894603ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:10.936257Z","caller":"traceutil/trace.go:171","msg":"trace[861414880] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"275.214009ms","start":"2026-05-05T20:56:10.660965Z","end":"2026-05-05T20:56:10.936179Z","steps":["trace[861414880] 'process raft request' (duration: 39.996293ms)","trace[861414880] 'compare' (duration: 234.525669ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:10.936374Z","caller":"traceutil/trace.go:171","msg":"trace[1716758534] transaction","detail":"{read_only:false; response_revision:383; number_of_response:1; }","duration":"275.022046ms","start":"2026-05-05T20:56:10.661321Z","end":"2026-05-05T20:56:10.936343Z","steps":["trace[1716758534] 'process raft request' (duration: 274.640263ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:10.936511Z","caller":"traceutil/trace.go:171","msg":"trace[1620130672] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"271.165186ms","start":"2026-05-05T20:56:10.665275Z","end":"2026-05-05T20:56:10.93644Z","steps":["trace[1620130672] 'process raft request' (duration: 270.787523ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:10.936583Z","caller":"traceutil/trace.go:171","msg":"trace[1502123726] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"274.401372ms","start":"2026-05-05T20:56:10.662139Z","end":"2026-05-05T20:56:10.93654Z","steps":["trace[1502123726] 'process raft request' (duration: 273.866337ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:11.127852Z","caller":"traceutil/trace.go:171","msg":"trace[1699212672] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"186.013673ms","start":"2026-05-05T20:56:10.941793Z","end":"2026-05-05T20:56:11.127806Z","steps":["trace[1699212672] 'process raft request' (duration: 116.743948ms)","trace[1699212672] 'compare' (duration: 69.010123ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:11.130729Z","caller":"traceutil/trace.go:171","msg":"trace[400463748] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"188.060509ms","start":"2026-05-05T20:56:10.942631Z","end":"2026-05-05T20:56:11.130691Z","steps":["trace[400463748] 'process raft request' (duration: 187.868318ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:11.351679Z","caller":"traceutil/trace.go:171","msg":"trace[1668279834] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"212.35436ms","start":"2026-05-05T20:56:11.139287Z","end":"2026-05-05T20:56:11.351641Z","steps":["trace[1668279834] 'process raft request' (duration: 145.719527ms)","trace[1668279834] 'compare' (duration: 66.474092ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:11.371234Z","caller":"traceutil/trace.go:171","msg":"trace[325765535] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"231.686446ms","start":"2026-05-05T20:56:11.139451Z","end":"2026-05-05T20:56:11.371137Z","steps":["trace[325765535] 'process raft request' (duration: 230.843879ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:11.696929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.243735ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T20:56:11.697053Z","caller":"traceutil/trace.go:171","msg":"trace[1161726982] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"314.192206ms","start":"2026-05-05T20:56:11.382837Z","end":"2026-05-05T20:56:11.69703Z","steps":["trace[1161726982] 'process raft request' (duration: 119.744939ms)","trace[1161726982] 'compare' (duration: 193.989183ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:11.697123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:11.382805Z","time spent":"314.283936ms","remote":"127.0.0.1:54956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6736,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T20:56:11.71589Z","caller":"traceutil/trace.go:171","msg":"trace[2017365365] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:399; }","duration":"146.395742ms","start":"2026-05-05T20:56:11.569466Z","end":"2026-05-05T20:56:11.715862Z","steps":["trace[2017365365] 'read index received' (duration: 146.16662ms)","trace[2017365365] 'applied index is now lower than readState.Index' (duration: 227.742µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:11.715948Z","caller":"traceutil/trace.go:171","msg":"trace[888760882] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"207.083238ms","start":"2026-05-05T20:56:11.50883Z","end":"2026-05-05T20:56:11.715913Z","steps":["trace[888760882] 'process raft request' (duration: 206.881027ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:11.716099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.622853ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:11.7162Z","caller":"traceutil/trace.go:171","msg":"trace[513751518] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:391; }","duration":"146.743835ms","start":"2026-05-05T20:56:11.569441Z","end":"2026-05-05T20:56:11.716185Z","steps":["trace[513751518] 'agreement among raft nodes before linearized reading' (duration: 146.545404ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:12.079971Z","caller":"traceutil/trace.go:171","msg":"trace[883478141] linearizableReadLoop","detail":"{readStateIndex:401; appliedIndex:400; }","duration":"109.247611ms","start":"2026-05-05T20:56:11.970687Z","end":"2026-05-05T20:56:12.079935Z","steps":["trace[883478141] 'read index received' (duration: 10.802197ms)","trace[883478141] 'applied index is now lower than readState.Index' (duration: 98.444314ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:12.080375Z","caller":"traceutil/trace.go:171","msg":"trace[1721684126] transaction","detail":"{read_only:false; response_revision:392; number_of_response:1; }","duration":"353.610797ms","start":"2026-05-05T20:56:11.726709Z","end":"2026-05-05T20:56:12.080319Z","steps":["trace[1721684126] 'process raft request' (duration: 254.996902ms)","trace[1721684126] 'compare' (duration: 98.061011ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:12.080449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.435232ms","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-05-05T20:56:12.080559Z","caller":"traceutil/trace.go:171","msg":"trace[1348339565] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:392; }","duration":"109.921316ms","start":"2026-05-05T20:56:11.970615Z","end":"2026-05-05T20:56:12.080536Z","steps":["trace[1348339565] 'agreement among raft nodes before linearized reading' (duration: 109.450592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:12.080589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:11.726688Z","time spent":"353.787698ms","remote":"127.0.0.1:54956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5311,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T20:56:12.424766Z","caller":"traceutil/trace.go:171","msg":"trace[2027897409] transaction","detail":"{read_only:false; response_revision:394; number_of_response:1; }","duration":"310.42167ms","start":"2026-05-05T20:56:12.11431Z","end":"2026-05-05T20:56:12.424731Z","steps":["trace[2027897409] 'process raft request' (duration: 252.192987ms)","trace[2027897409] 'compare' (duration: 58.049312ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:12.425002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:12.114279Z","time spent":"310.603631ms","remote":"127.0.0.1:54956","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-05-05T20:56:12.80795Z","caller":"traceutil/trace.go:171","msg":"trace[562594480] linearizableReadLoop","detail":"{readStateIndex:408; appliedIndex:407; }","duration":"131.066163ms","start":"2026-05-05T20:56:12.676858Z","end":"2026-05-05T20:56:12.807924Z","steps":["trace[562594480] 'read index received' (duration: 122.042192ms)","trace[562594480] 'applied index is now lower than readState.Index' (duration: 9.019491ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:12.808164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.265755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4368"} {"level":"info","ts":"2026-05-05T20:56:12.808205Z","caller":"traceutil/trace.go:171","msg":"trace[167296489] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:399; }","duration":"131.367035ms","start":"2026-05-05T20:56:12.676828Z","end":"2026-05-05T20:56:12.808195Z","steps":["trace[167296489] 'agreement among raft nodes before linearized reading' (duration: 131.213334ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:12.808367Z","caller":"traceutil/trace.go:171","msg":"trace[1679656499] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"138.047368ms","start":"2026-05-05T20:56:12.670271Z","end":"2026-05-05T20:56:12.808319Z","steps":["trace[1679656499] 'process raft request' (duration: 128.612573ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:12.876304Z","caller":"traceutil/trace.go:171","msg":"trace[155037157] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"158.765072ms","start":"2026-05-05T20:56:12.717504Z","end":"2026-05-05T20:56:12.876269Z","steps":["trace[155037157] 'process raft request' (duration: 158.576221ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:13.180767Z","caller":"traceutil/trace.go:171","msg":"trace[1281981195] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"144.576713ms","start":"2026-05-05T20:56:13.036136Z","end":"2026-05-05T20:56:13.180712Z","steps":["trace[1281981195] 'process raft request' (duration: 117.397748ms)","trace[1281981195] 'compare' (duration: 26.985404ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:14.793911Z","caller":"traceutil/trace.go:171","msg":"trace[624985820] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"119.461459ms","start":"2026-05-05T20:56:14.674422Z","end":"2026-05-05T20:56:14.793884Z","steps":["trace[624985820] 'process raft request' (duration: 119.276028ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:17.017543Z","caller":"traceutil/trace.go:171","msg":"trace[947753595] transaction","detail":"{read_only:false; response_revision:414; number_of_response:1; }","duration":"144.376711ms","start":"2026-05-05T20:56:16.873128Z","end":"2026-05-05T20:56:17.017505Z","steps":["trace[947753595] 'process raft request' (duration: 85.766397ms)","trace[947753595] 'compare' (duration: 58.336451ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:24.512881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.842727213s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T20:56:24.513006Z","caller":"traceutil/trace.go:171","msg":"trace[281337854] linearizableReadLoop","detail":"{readStateIndex:439; appliedIndex:438; }","duration":"1.601570818s","start":"2026-05-05T20:56:22.911417Z","end":"2026-05-05T20:56:24.512988Z","steps":["trace[281337854] 'read index received' (duration: 50.24µs)","trace[281337854] 'applied index is now lower than readState.Index' (duration: 1.601519118s)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:24.513098Z","caller":"traceutil/trace.go:171","msg":"trace[1662896349] transaction","detail":"{read_only:false; response_revision:427; number_of_response:1; }","duration":"2.068792962s","start":"2026-05-05T20:56:22.444296Z","end":"2026-05-05T20:56:24.513089Z","steps":["trace[1662896349] 'process raft request' (duration: 225.744827ms)","trace[1662896349] 'compare' (duration: 1.842208699s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:24.513148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:22.444263Z","time spent":"2.068862002s","remote":"127.0.0.1:55078","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-05-05T20:56:24.513476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.602068752s","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-05-05T20:56:24.513506Z","caller":"traceutil/trace.go:171","msg":"trace[849976980] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:427; }","duration":"1.602142813s","start":"2026-05-05T20:56:22.911356Z","end":"2026-05-05T20:56:24.513499Z","steps":["trace[849976980] 'agreement among raft nodes before linearized reading' (duration: 1.602023222s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:24.513528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:22.911328Z","time spent":"1.602195934s","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T20:56:24.513715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.439647253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-05T20:56:24.513801Z","caller":"traceutil/trace.go:171","msg":"trace[336351725] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:427; }","duration":"1.439782716s","start":"2026-05-05T20:56:23.073995Z","end":"2026-05-05T20:56:24.513778Z","steps":["trace[336351725] 'agreement among raft nodes before linearized reading' (duration: 1.439619864s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:24.513914Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:23.073974Z","time spent":"1.439923667s","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T20:56:24.513941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.418049523s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/kube-system/coredns\" ","response":"range_response_count:1 size:4056"} {"level":"warn","ts":"2026-05-05T20:56:24.513752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.84311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-05T20:56:24.513967Z","caller":"traceutil/trace.go:171","msg":"trace[426676032] range","detail":"{range_begin:/registry/deployments/kube-system/coredns; range_end:; response_count:1; response_revision:427; }","duration":"1.418074683s","start":"2026-05-05T20:56:23.095885Z","end":"2026-05-05T20:56:24.513959Z","steps":["trace[426676032] 'agreement among raft nodes before linearized reading' (duration: 1.418026573s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:24.513996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:23.095798Z","time spent":"1.418193574s","remote":"127.0.0.1:55276","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":"info","ts":"2026-05-05T20:56:24.514021Z","caller":"traceutil/trace.go:171","msg":"trace[28810341] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:427; }","duration":"274.137281ms","start":"2026-05-05T20:56:24.239842Z","end":"2026-05-05T20:56:24.51398Z","steps":["trace[28810341] 'agreement among raft nodes before linearized reading' (duration: 273.880619ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:24.514041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.255388757s","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-05-05T20:56:24.514029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.249889ms","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-05-05T20:56:24.514139Z","caller":"traceutil/trace.go:171","msg":"trace[1213159555] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:427; }","duration":"1.255552047s","start":"2026-05-05T20:56:23.258561Z","end":"2026-05-05T20:56:24.514113Z","steps":["trace[1213159555] 'agreement among raft nodes before linearized reading' (duration: 1.255382706s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:24.514183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:23.258528Z","time spent":"1.255645768s","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T20:56:24.51419Z","caller":"traceutil/trace.go:171","msg":"trace[432243415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:427; }","duration":"311.44409ms","start":"2026-05-05T20:56:24.202718Z","end":"2026-05-05T20:56:24.514162Z","steps":["trace[432243415] 'agreement among raft nodes before linearized reading' (duration: 311.162738ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:24.513764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.354906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:24.514327Z","caller":"traceutil/trace.go:171","msg":"trace[1159050700] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:427; }","duration":"461.98076ms","start":"2026-05-05T20:56:24.052323Z","end":"2026-05-05T20:56:24.514304Z","steps":["trace[1159050700] 'agreement among raft nodes before linearized reading' (duration: 461.356294ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:24.514397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:24.052295Z","time spent":"462.086011ms","remote":"127.0.0.1:54746","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-05-05T20:56:24.514361Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:24.202694Z","time spent":"311.611511ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T20:56:24.685611Z","caller":"traceutil/trace.go:171","msg":"trace[626683748] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"166.620241ms","start":"2026-05-05T20:56:24.51895Z","end":"2026-05-05T20:56:24.68557Z","steps":["trace[626683748] 'process raft request' (duration: 111.869133ms)","trace[626683748] 'compare' (duration: 54.505096ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:24.686035Z","caller":"traceutil/trace.go:171","msg":"trace[268380746] transaction","detail":"{read_only:false; response_revision:429; number_of_response:1; }","duration":"167.017584ms","start":"2026-05-05T20:56:24.518976Z","end":"2026-05-05T20:56:24.685994Z","steps":["trace[268380746] 'process raft request' (duration: 166.548241ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:24.686351Z","caller":"traceutil/trace.go:171","msg":"trace[1296741236] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"167.192815ms","start":"2026-05-05T20:56:24.519061Z","end":"2026-05-05T20:56:24.686254Z","steps":["trace[1296741236] 'process raft request' (duration: 166.769602ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:24.686625Z","caller":"traceutil/trace.go:171","msg":"trace[945190164] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"167.368126ms","start":"2026-05-05T20:56:24.519092Z","end":"2026-05-05T20:56:24.68646Z","steps":["trace[945190164] 'process raft request' (duration: 167.010704ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:24.686813Z","caller":"traceutil/trace.go:171","msg":"trace[2048978186] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"160.127733ms","start":"2026-05-05T20:56:24.526667Z","end":"2026-05-05T20:56:24.686795Z","steps":["trace[2048978186] 'process raft request' (duration: 159.73745ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:24.686815Z","caller":"traceutil/trace.go:171","msg":"trace[1472719972] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"146.711902ms","start":"2026-05-05T20:56:24.540073Z","end":"2026-05-05T20:56:24.686785Z","steps":["trace[1472719972] 'process raft request' (duration: 146.5408ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:24.686938Z","caller":"traceutil/trace.go:171","msg":"trace[256576253] linearizableReadLoop","detail":"{readStateIndex:443; appliedIndex:439; }","duration":"167.81736ms","start":"2026-05-05T20:56:24.519109Z","end":"2026-05-05T20:56:24.686927Z","steps":["trace[256576253] 'read index received' (duration: 20.231691ms)","trace[256576253] 'applied index is now lower than readState.Index' (duration: 147.584249ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:24.687191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.058431ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:24.688333Z","caller":"traceutil/trace.go:171","msg":"trace[383405189] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:433; }","duration":"169.21594ms","start":"2026-05-05T20:56:24.519102Z","end":"2026-05-05T20:56:24.688318Z","steps":["trace[383405189] 'agreement among raft nodes before linearized reading' (duration: 167.90209ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:28.961418Z","caller":"traceutil/trace.go:171","msg":"trace[141532330] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"252.249545ms","start":"2026-05-05T20:56:28.709142Z","end":"2026-05-05T20:56:28.961392Z","steps":["trace[141532330] 'process raft request' (duration: 252.130034ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:28.961437Z","caller":"traceutil/trace.go:171","msg":"trace[279367209] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:481; }","duration":"250.963485ms","start":"2026-05-05T20:56:28.710435Z","end":"2026-05-05T20:56:28.961399Z","steps":["trace[279367209] 'read index received' (duration: 250.953115ms)","trace[279367209] 'applied index is now lower than readState.Index' (duration: 8.17µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:28.961666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.883155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T20:56:28.961669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.199608ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T20:56:28.961679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.879625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T20:56:28.961731Z","caller":"traceutil/trace.go:171","msg":"trace[633919160] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:467; }","duration":"251.011096ms","start":"2026-05-05T20:56:28.710688Z","end":"2026-05-05T20:56:28.961699Z","steps":["trace[633919160] 'agreement among raft nodes before linearized reading' (duration: 250.865965ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:28.961758Z","caller":"traceutil/trace.go:171","msg":"trace[1697128849] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:467; }","duration":"251.320218ms","start":"2026-05-05T20:56:28.710411Z","end":"2026-05-05T20:56:28.961731Z","steps":["trace[1697128849] 'agreement among raft nodes before linearized reading' (duration: 251.123007ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:28.961776Z","caller":"traceutil/trace.go:171","msg":"trace[183667047] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:467; }","duration":"250.998896ms","start":"2026-05-05T20:56:28.710755Z","end":"2026-05-05T20:56:28.961754Z","steps":["trace[183667047] 'agreement among raft nodes before linearized reading' (duration: 250.842555ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:33.35086Z","caller":"traceutil/trace.go:171","msg":"trace[1052509222] linearizableReadLoop","detail":"{readStateIndex:490; appliedIndex:489; }","duration":"267.875288ms","start":"2026-05-05T20:56:33.082952Z","end":"2026-05-05T20:56:33.350828Z","steps":["trace[1052509222] 'read index received' (duration: 267.644321ms)","trace[1052509222] 'applied index is now lower than readState.Index' (duration: 229.687µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:33.351157Z","caller":"traceutil/trace.go:171","msg":"trace[1720276168] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"274.303344ms","start":"2026-05-05T20:56:33.07684Z","end":"2026-05-05T20:56:33.351144Z","steps":["trace[1720276168] 'process raft request' (duration: 273.82484ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:33.351237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.550731ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T20:56:33.351242Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.273789ms","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-05-05T20:56:33.351323Z","caller":"traceutil/trace.go:171","msg":"trace[1520453697] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:475; }","duration":"268.372921ms","start":"2026-05-05T20:56:33.082935Z","end":"2026-05-05T20:56:33.351308Z","steps":["trace[1520453697] 'agreement among raft nodes before linearized reading' (duration: 268.226207ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:33.351323Z","caller":"traceutil/trace.go:171","msg":"trace[490128880] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:475; }","duration":"263.697685ms","start":"2026-05-05T20:56:33.087603Z","end":"2026-05-05T20:56:33.351301Z","steps":["trace[490128880] 'agreement among raft nodes before linearized reading' (duration: 263.481579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:33.351306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.626163ms","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-05-05T20:56:33.351405Z","caller":"traceutil/trace.go:171","msg":"trace[1761988739] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:475; }","duration":"263.753546ms","start":"2026-05-05T20:56:33.087628Z","end":"2026-05-05T20:56:33.351382Z","steps":["trace[1761988739] 'agreement among raft nodes before linearized reading' (duration: 263.545771ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:36.590233Z","caller":"traceutil/trace.go:171","msg":"trace[1927979720] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"105.205256ms","start":"2026-05-05T20:56:36.484993Z","end":"2026-05-05T20:56:36.590198Z","steps":["trace[1927979720] 'process raft request' (duration: 104.932318ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:36.780413Z","caller":"traceutil/trace.go:171","msg":"trace[986955690] transaction","detail":"{read_only:false; response_revision:487; number_of_response:1; }","duration":"165.375818ms","start":"2026-05-05T20:56:36.615006Z","end":"2026-05-05T20:56:36.780382Z","steps":["trace[986955690] 'process raft request' (duration: 165.324916ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:36.780561Z","caller":"traceutil/trace.go:171","msg":"trace[332135419] transaction","detail":"{read_only:false; response_revision:486; number_of_response:1; }","duration":"165.720307ms","start":"2026-05-05T20:56:36.61479Z","end":"2026-05-05T20:56:36.78051Z","steps":["trace[332135419] 'process raft request' (duration: 85.638397ms)","trace[332135419] 'compare' (duration: 79.7111ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:37.046117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.529568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T20:56:37.046278Z","caller":"traceutil/trace.go:171","msg":"trace[1568137024] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"210.269487ms","start":"2026-05-05T20:56:36.835988Z","end":"2026-05-05T20:56:37.046257Z","steps":["trace[1568137024] 'process raft request' (duration: 80.531373ms)","trace[1568137024] 'compare' (duration: 129.188619ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:37.068355Z","caller":"traceutil/trace.go:171","msg":"trace[365172304] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"231.547648ms","start":"2026-05-05T20:56:36.836776Z","end":"2026-05-05T20:56:37.068324Z","steps":["trace[365172304] 'process raft request' (duration: 231.227129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:37.432844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.822465ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T20:56:37.432997Z","caller":"traceutil/trace.go:171","msg":"trace[1380622813] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"288.226773ms","start":"2026-05-05T20:56:37.144738Z","end":"2026-05-05T20:56:37.432965Z","steps":["trace[1380622813] 'process raft request' (duration: 43.191652ms)","trace[1380622813] 'compare' (duration: 244.673281ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:37.721776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.403989ms","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-05-05T20:56:37.721939Z","caller":"traceutil/trace.go:171","msg":"trace[1417896039] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:495; }","duration":"119.586994ms","start":"2026-05-05T20:56:37.602329Z","end":"2026-05-05T20:56:37.721916Z","steps":["trace[1417896039] 'range keys from in-memory index tree' (duration: 119.380058ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:37.722339Z","caller":"traceutil/trace.go:171","msg":"trace[313830984] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"281.091984ms","start":"2026-05-05T20:56:37.440944Z","end":"2026-05-05T20:56:37.722036Z","steps":["trace[313830984] 'process raft request' (duration: 195.260529ms)","trace[313830984] 'compare' (duration: 85.496496ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:37.722634Z","caller":"traceutil/trace.go:171","msg":"trace[1115190409] linearizableReadLoop","detail":"{readStateIndex:513; appliedIndex:510; }","duration":"279.563672ms","start":"2026-05-05T20:56:37.443029Z","end":"2026-05-05T20:56:37.722592Z","steps":["trace[1115190409] 'read index received' (duration: 78.592105ms)","trace[1115190409] 'applied index is now lower than readState.Index' (duration: 200.970407ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:37.722841Z","caller":"traceutil/trace.go:171","msg":"trace[1147905438] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"280.602711ms","start":"2026-05-05T20:56:37.44219Z","end":"2026-05-05T20:56:37.722793Z","steps":["trace[1147905438] 'process raft request' (duration: 279.833198ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:37.72342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.48827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T20:56:37.723591Z","caller":"traceutil/trace.go:171","msg":"trace[480332386] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:497; }","duration":"279.710356ms","start":"2026-05-05T20:56:37.443865Z","end":"2026-05-05T20:56:37.723575Z","steps":["trace[480332386] 'agreement among raft nodes before linearized reading' (duration: 279.49553ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:37.723013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.945673ms","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-05-05T20:56:37.723734Z","caller":"traceutil/trace.go:171","msg":"trace[2056977369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:497; }","duration":"280.688574ms","start":"2026-05-05T20:56:37.443009Z","end":"2026-05-05T20:56:37.723697Z","steps":["trace[2056977369] 'agreement among raft nodes before linearized reading' (duration: 279.82223ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:37.871082Z","caller":"traceutil/trace.go:171","msg":"trace[50571104] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"123.373729ms","start":"2026-05-05T20:56:37.747674Z","end":"2026-05-05T20:56:37.871047Z","steps":["trace[50571104] 'process raft request' (duration: 123.305148ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:37.871173Z","caller":"traceutil/trace.go:171","msg":"trace[243804941] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"140.813086ms","start":"2026-05-05T20:56:37.730311Z","end":"2026-05-05T20:56:37.871124Z","steps":["trace[243804941] 'process raft request' (duration: 114.158094ms)","trace[243804941] 'compare' (duration: 26.167008ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:37.871212Z","caller":"traceutil/trace.go:171","msg":"trace[2066573682] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"140.233449ms","start":"2026-05-05T20:56:37.730941Z","end":"2026-05-05T20:56:37.871174Z","steps":["trace[2066573682] 'process raft request' (duration: 139.936711ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:37.871366Z","caller":"traceutil/trace.go:171","msg":"trace[1257847505] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"138.626764ms","start":"2026-05-05T20:56:37.732711Z","end":"2026-05-05T20:56:37.871337Z","steps":["trace[1257847505] 'process raft request' (duration: 138.222573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:40.034163Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278618974044019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T20:56:40.534412Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278618974044019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T20:56:41.034582Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278618974044019,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T20:56:41.141539Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.653016981s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T20:56:41.141913Z","caller":"traceutil/trace.go:171","msg":"trace[1694072446] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"1.653537514s","start":"2026-05-05T20:56:39.488352Z","end":"2026-05-05T20:56:41.141889Z","steps":["trace[1694072446] 'process raft request' (duration: 1.653402471s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:41.142028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:39.488329Z","time spent":"1.653634227s","remote":"127.0.0.1:54806","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-05-05T20:56:41.638558Z","caller":"traceutil/trace.go:171","msg":"trace[467570933] transaction","detail":"{read_only:false; response_revision:526; number_of_response:1; }","duration":"2.149608683s","start":"2026-05-05T20:56:39.488919Z","end":"2026-05-05T20:56:41.638528Z","steps":["trace[467570933] 'process raft request' (duration: 2.149407417s)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:41.638592Z","caller":"traceutil/trace.go:171","msg":"trace[1240605907] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"2.144980047s","start":"2026-05-05T20:56:39.493578Z","end":"2026-05-05T20:56:41.638558Z","steps":["trace[1240605907] 'process raft request' (duration: 2.144865964s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:41.638706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:39.488896Z","time spent":"2.149745677s","remote":"127.0.0.1:54806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T20:56:41.638707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:39.493554Z","time spent":"2.14509059s","remote":"127.0.0.1:54806","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-05-05T20:56:41.638727Z","caller":"traceutil/trace.go:171","msg":"trace[1128549952] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"2.116975064s","start":"2026-05-05T20:56:39.521737Z","end":"2026-05-05T20:56:41.638712Z","steps":["trace[1128549952] 'process raft request' (duration: 2.116773978s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:41.638788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:39.521713Z","time spent":"2.117043226s","remote":"127.0.0.1:54806","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-05-05T20:56:41.638812Z","caller":"traceutil/trace.go:171","msg":"trace[1540635381] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"2.110690953s","start":"2026-05-05T20:56:39.528112Z","end":"2026-05-05T20:56:41.638803Z","steps":["trace[1540635381] 'process raft request' (duration: 2.110498268s)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:41.640301Z","caller":"traceutil/trace.go:171","msg":"trace[588925820] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"2.074813145s","start":"2026-05-05T20:56:39.565468Z","end":"2026-05-05T20:56:41.640282Z","steps":["trace[588925820] 'process raft request' (duration: 2.073319006s)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:41.640015Z","caller":"traceutil/trace.go:171","msg":"trace[426458436] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"2.101823902s","start":"2026-05-05T20:56:39.536983Z","end":"2026-05-05T20:56:41.638807Z","steps":["trace[426458436] 'process raft request' (duration: 2.101680058s)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:41.639069Z","caller":"traceutil/trace.go:171","msg":"trace[1463425346] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"2.095585162s","start":"2026-05-05T20:56:39.543224Z","end":"2026-05-05T20:56:41.638809Z","steps":["trace[1463425346] 'process raft request' (duration: 2.095521291s)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:41.639315Z","caller":"traceutil/trace.go:171","msg":"trace[1845034639] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"2.100727041s","start":"2026-05-05T20:56:39.538084Z","end":"2026-05-05T20:56:41.638811Z","steps":["trace[1845034639] 'process raft request' (duration: 2.100628289s)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:41.639319Z","caller":"traceutil/trace.go:171","msg":"trace[1045860208] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"2.11056428s","start":"2026-05-05T20:56:39.528223Z","end":"2026-05-05T20:56:41.638787Z","steps":["trace[1045860208] 'process raft request' (duration: 2.110417316s)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:41.639409Z","caller":"traceutil/trace.go:171","msg":"trace[841909832] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"2.129031472s","start":"2026-05-05T20:56:39.50975Z","end":"2026-05-05T20:56:41.638781Z","steps":["trace[841909832] 'process raft request' (duration: 2.128730964s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:41.64042Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:39.565434Z","time spent":"2.074917328s","remote":"127.0.0.1:54806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T20:56:41.640768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:39.536955Z","time spent":"2.103734593s","remote":"127.0.0.1:54806","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-05-05T20:56:41.640967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:39.543207Z","time spent":"2.097716928s","remote":"127.0.0.1:54806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T20:56:41.641099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:39.538065Z","time spent":"2.103011702s","remote":"127.0.0.1:54806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T20:56:41.641281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:39.528215Z","time spent":"2.113008925s","remote":"127.0.0.1:54806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T20:56:41.641678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:39.509728Z","time spent":"2.131911299s","remote":"127.0.0.1:54806","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-05-05T20:56:41.643842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:39.528088Z","time spent":"2.115708406s","remote":"127.0.0.1:54806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T20:56:41.64693Z","caller":"traceutil/trace.go:171","msg":"trace[1175746856] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"1.987315172s","start":"2026-05-05T20:56:39.656469Z","end":"2026-05-05T20:56:41.643785Z","steps":["trace[1175746856] 'process raft request' (duration: 1.987037975s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:41.646998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:39.656427Z","time spent":"1.990539189s","remote":"127.0.0.1:54806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T20:56:41.697976Z","caller":"traceutil/trace.go:171","msg":"trace[990886291] linearizableReadLoop","detail":"{readStateIndex:553; appliedIndex:541; }","duration":"2.163973099s","start":"2026-05-05T20:56:39.533957Z","end":"2026-05-05T20:56:41.69793Z","steps":["trace[990886291] 'read index received' (duration: 1.607933433s)","trace[990886291] 'applied index is now lower than readState.Index' (duration: 556.038476ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:41.69824Z","caller":"traceutil/trace.go:171","msg":"trace[1149382514] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"550.769605ms","start":"2026-05-05T20:56:41.147464Z","end":"2026-05-05T20:56:41.698233Z","steps":["trace[1149382514] 'process raft request' (duration: 550.392275ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:41.698268Z","caller":"traceutil/trace.go:171","msg":"trace[1968011794] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"548.579517ms","start":"2026-05-05T20:56:41.149681Z","end":"2026-05-05T20:56:41.698261Z","steps":["trace[1968011794] 'process raft request' (duration: 548.503765ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:41.698302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:41.147441Z","time spent":"550.820827ms","remote":"127.0.0.1:55344","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T20:56:41.698326Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:41.149668Z","time spent":"548.626048ms","remote":"127.0.0.1:54806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T20:56:41.698414Z","caller":"traceutil/trace.go:171","msg":"trace[1160727182] transaction","detail":"{read_only:false; number_of_response:1; response_revision:538; }","duration":"548.230398ms","start":"2026-05-05T20:56:41.150175Z","end":"2026-05-05T20:56:41.698405Z","steps":["trace[1160727182] 'process raft request' (duration: 548.063473ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:41.698454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:41.150157Z","time spent":"548.27579ms","remote":"127.0.0.1:54806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7367,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T20:56:41.698751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.164788582s","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-05-05T20:56:41.698818Z","caller":"traceutil/trace.go:171","msg":"trace[202831213] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:537; }","duration":"2.164855203s","start":"2026-05-05T20:56:39.533952Z","end":"2026-05-05T20:56:41.698807Z","steps":["trace[202831213] 'agreement among raft nodes before linearized reading' (duration: 2.164193666s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:41.698882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:39.533912Z","time spent":"2.164945676s","remote":"127.0.0.1:55078","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-05-05T20:56:41.706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"654.88768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T20:56:41.706081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.895496108s","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-05-05T20:56:41.706127Z","caller":"traceutil/trace.go:171","msg":"trace[1171204722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:546; }","duration":"1.895589372s","start":"2026-05-05T20:56:39.810524Z","end":"2026-05-05T20:56:41.706114Z","steps":["trace[1171204722] 'agreement among raft nodes before linearized reading' (duration: 1.89552629s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:41.706171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:39.810487Z","time spent":"1.895669424s","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T20:56:41.70601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.979573407s","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-05-05T20:56:41.706242Z","caller":"traceutil/trace.go:171","msg":"trace[1050104996] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:546; }","duration":"1.979840514s","start":"2026-05-05T20:56:39.726394Z","end":"2026-05-05T20:56:41.706235Z","steps":["trace[1050104996] 'agreement among raft nodes before linearized reading' (duration: 1.979546946s)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:41.706138Z","caller":"traceutil/trace.go:171","msg":"trace[1797002401] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"655.031583ms","start":"2026-05-05T20:56:41.05107Z","end":"2026-05-05T20:56:41.706102Z","steps":["trace[1797002401] 'agreement among raft nodes before linearized reading' (duration: 654.89654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:41.706302Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:41.051055Z","time spent":"655.236109ms","remote":"127.0.0.1:54752","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-05-05T20:56:41.706343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:39.726357Z","time spent":"1.979918466s","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T20:56:41.706049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.829674207s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T20:56:41.706436Z","caller":"traceutil/trace.go:171","msg":"trace[501280009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:546; }","duration":"1.830096709s","start":"2026-05-05T20:56:39.876327Z","end":"2026-05-05T20:56:41.706424Z","steps":["trace[501280009] 'agreement among raft nodes before linearized reading' (duration: 1.829702018s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:41.706476Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:39.8763Z","time spent":"1.830166131s","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T20:56:41.706001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"685.205768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:41.706533Z","caller":"traceutil/trace.go:171","msg":"trace[340261003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:546; }","duration":"685.796693ms","start":"2026-05-05T20:56:41.020725Z","end":"2026-05-05T20:56:41.706522Z","steps":["trace[340261003] 'agreement among raft nodes before linearized reading' (duration: 685.229348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:41.706572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:41.020699Z","time spent":"685.863885ms","remote":"127.0.0.1:54746","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-05-05T20:56:42.872707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.007057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-05-05T20:56:42.872822Z","caller":"traceutil/trace.go:171","msg":"trace[1180295552] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:599; }","duration":"155.252293ms","start":"2026-05-05T20:56:42.717546Z","end":"2026-05-05T20:56:42.872799Z","steps":["trace[1180295552] 'range keys from in-memory index tree' (duration: 154.833212ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:42.87309Z","caller":"traceutil/trace.go:171","msg":"trace[1316420604] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"135.276557ms","start":"2026-05-05T20:56:42.737778Z","end":"2026-05-05T20:56:42.873055Z","steps":["trace[1316420604] 'process raft request' (duration: 125.689634ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:47.214297Z","caller":"traceutil/trace.go:171","msg":"trace[664284191] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"163.879795ms","start":"2026-05-05T20:56:47.050379Z","end":"2026-05-05T20:56:47.214258Z","steps":["trace[664284191] 'read index received' (duration: 163.199958ms)","trace[664284191] 'applied index is now lower than readState.Index' (duration: 675.967µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:47.21447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.084999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:47.214517Z","caller":"traceutil/trace.go:171","msg":"trace[986311294] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:624; }","duration":"164.184142ms","start":"2026-05-05T20:56:47.050316Z","end":"2026-05-05T20:56:47.2145Z","steps":["trace[986311294] 'agreement among raft nodes before linearized reading' (duration: 164.067019ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:47.215546Z","caller":"traceutil/trace.go:171","msg":"trace[573087929] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"372.463309ms","start":"2026-05-05T20:56:46.843071Z","end":"2026-05-05T20:56:47.215534Z","steps":["trace[573087929] 'process raft request' (duration: 370.622374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:47.215648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:46.843035Z","time spent":"372.549061ms","remote":"127.0.0.1:54864","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036229,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T20:56:48.358829Z","caller":"traceutil/trace.go:171","msg":"trace[1331174212] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"155.827363ms","start":"2026-05-05T20:56:48.202934Z","end":"2026-05-05T20:56:48.358762Z","steps":["trace[1331174212] 'process raft request' (duration: 155.255679ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:48.829943Z","caller":"traceutil/trace.go:171","msg":"trace[82204567] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"613.358705ms","start":"2026-05-05T20:56:48.216549Z","end":"2026-05-05T20:56:48.829908Z","steps":["trace[82204567] 'process raft request' (duration: 613.15876ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:48.830105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:48.216505Z","time spent":"613.512969ms","remote":"127.0.0.1:54956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T20:56:48.830407Z","caller":"traceutil/trace.go:171","msg":"trace[1637403131] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"578.355398ms","start":"2026-05-05T20:56:48.251958Z","end":"2026-05-05T20:56:48.830313Z","steps":["trace[1637403131] 'process raft request' (duration: 577.896107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:48.831183Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:48.25192Z","time spent":"578.661646ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T20:56:49.360613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.943196ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T20:56:49.360826Z","caller":"traceutil/trace.go:171","msg":"trace[1396575520] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"942.905966ms","start":"2026-05-05T20:56:48.417879Z","end":"2026-05-05T20:56:49.360785Z","steps":["trace[1396575520] 'process raft request' (duration: 751.669043ms)","trace[1396575520] 'compare' (duration: 190.71484ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:49.36099Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:48.41784Z","time spent":"943.06383ms","remote":"127.0.0.1:55326","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T20:56:49.361226Z","caller":"traceutil/trace.go:171","msg":"trace[2056740164] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"308.897151ms","start":"2026-05-05T20:56:49.052256Z","end":"2026-05-05T20:56:49.361153Z","steps":["trace[2056740164] 'read index received' (duration: 117.300409ms)","trace[2056740164] 'applied index is now lower than readState.Index' (duration: 191.592852ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:51.631675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.270887752s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-05-05T20:56:51.631778Z","caller":"traceutil/trace.go:171","msg":"trace[1357650687] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"2.258367184s","start":"2026-05-05T20:56:49.373392Z","end":"2026-05-05T20:56:51.631759Z","steps":["trace[1357650687] 'read index received' (duration: 62.001µs)","trace[1357650687] 'applied index is now lower than readState.Index' (duration: 2.258303303s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:51.631791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.579529946s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:51.631885Z","caller":"traceutil/trace.go:171","msg":"trace[1211012163] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"2.57967213s","start":"2026-05-05T20:56:49.05218Z","end":"2026-05-05T20:56:51.631853Z","steps":["trace[1211012163] 'agreement among raft nodes before linearized reading' (duration: 309.135757ms)","trace[1211012163] 'range keys from in-memory index tree' (duration: 2.27041362s)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:51.63196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:49.052125Z","time spent":"2.579818583s","remote":"127.0.0.1:54746","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-05-05T20:56:51.632533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.259127253s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:51.632593Z","caller":"traceutil/trace.go:171","msg":"trace[559006061] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:638; }","duration":"2.259235985s","start":"2026-05-05T20:56:49.373339Z","end":"2026-05-05T20:56:51.632575Z","steps":["trace[559006061] 'agreement among raft nodes before linearized reading' (duration: 2.259128643s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:51.632632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:49.373319Z","time spent":"2.259304786s","remote":"127.0.0.1:55042","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-05-05T20:56:51.632656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.869641194s","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-05-05T20:56:51.632671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"579.91222ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:51.632717Z","caller":"traceutil/trace.go:171","msg":"trace[1588373220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:638; }","duration":"1.869756656s","start":"2026-05-05T20:56:49.762944Z","end":"2026-05-05T20:56:51.632701Z","steps":["trace[1588373220] 'agreement among raft nodes before linearized reading' (duration: 1.869574552s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:51.632723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.931134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:51.632721Z","caller":"traceutil/trace.go:171","msg":"trace[923820849] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"580.051713ms","start":"2026-05-05T20:56:51.052655Z","end":"2026-05-05T20:56:51.632707Z","steps":["trace[923820849] 'agreement among raft nodes before linearized reading' (duration: 579.966691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:51.632762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:51.052632Z","time spent":"580.123775ms","remote":"127.0.0.1:54752","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-05-05T20:56:51.632759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:49.762913Z","time spent":"1.869835238s","remote":"127.0.0.1:55078","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-05-05T20:56:51.632768Z","caller":"traceutil/trace.go:171","msg":"trace[1399109940] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:638; }","duration":"613.005966ms","start":"2026-05-05T20:56:51.019745Z","end":"2026-05-05T20:56:51.632751Z","steps":["trace[1399109940] 'agreement among raft nodes before linearized reading' (duration: 612.953515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:51.632811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:51.019724Z","time spent":"613.077157ms","remote":"127.0.0.1:54746","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-05-05T20:56:51.632799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.854108073s","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-05-05T20:56:51.632876Z","caller":"traceutil/trace.go:171","msg":"trace[187837772] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:638; }","duration":"1.854218036s","start":"2026-05-05T20:56:49.778635Z","end":"2026-05-05T20:56:51.632853Z","steps":["trace[187837772] 'agreement among raft nodes before linearized reading' (duration: 1.85397985s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:51.632915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:49.778616Z","time spent":"1.854290847s","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T20:56:51.633061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.855490476s","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-05-05T20:56:51.633111Z","caller":"traceutil/trace.go:171","msg":"trace[1483836370] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:638; }","duration":"1.855593998s","start":"2026-05-05T20:56:49.777503Z","end":"2026-05-05T20:56:51.633097Z","steps":["trace[1483836370] 'agreement among raft nodes before linearized reading' (duration: 1.855457925s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:51.633152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:49.777456Z","time spent":"1.85568482s","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T20:56:51.632666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.847182312s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T20:56:51.633311Z","caller":"traceutil/trace.go:171","msg":"trace[97015079] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"1.847823297s","start":"2026-05-05T20:56:49.785469Z","end":"2026-05-05T20:56:51.633292Z","steps":["trace[97015079] 'agreement among raft nodes before linearized reading' (duration: 1.84709646s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:51.633355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:49.785436Z","time spent":"1.847906499s","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T20:56:52.183327Z","caller":"traceutil/trace.go:171","msg":"trace[866041890] linearizableReadLoop","detail":"{readStateIndex:668; appliedIndex:667; }","duration":"133.575602ms","start":"2026-05-05T20:56:52.049718Z","end":"2026-05-05T20:56:52.183293Z","steps":["trace[866041890] 'read index received' (duration: 132.593778ms)","trace[866041890] 'applied index is now lower than readState.Index' (duration: 980.464µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:52.183472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.725875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:52.183493Z","caller":"traceutil/trace.go:171","msg":"trace[280466675] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"147.816791ms","start":"2026-05-05T20:56:52.035611Z","end":"2026-05-05T20:56:52.183428Z","steps":["trace[280466675] 'process raft request' (duration: 147.041942ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:52.183597Z","caller":"traceutil/trace.go:171","msg":"trace[2052375936] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:644; }","duration":"133.894259ms","start":"2026-05-05T20:56:52.049686Z","end":"2026-05-05T20:56:52.183581Z","steps":["trace[2052375936] 'agreement among raft nodes before linearized reading' (duration: 133.722835ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:53.752258Z","caller":"traceutil/trace.go:171","msg":"trace[1556945979] transaction","detail":"{read_only:false; response_revision:679; number_of_response:1; }","duration":"201.008452ms","start":"2026-05-05T20:56:53.551218Z","end":"2026-05-05T20:56:53.752226Z","steps":["trace[1556945979] 'process raft request' (duration: 200.706775ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:54.078739Z","caller":"traceutil/trace.go:171","msg":"trace[1768064272] transaction","detail":"{read_only:false; response_revision:686; number_of_response:1; }","duration":"130.363237ms","start":"2026-05-05T20:56:53.948334Z","end":"2026-05-05T20:56:54.078697Z","steps":["trace[1768064272] 'process raft request' (duration: 130.137032ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:54.314589Z","caller":"traceutil/trace.go:171","msg":"trace[47797662] transaction","detail":"{read_only:false; response_revision:689; number_of_response:1; }","duration":"165.996457ms","start":"2026-05-05T20:56:54.148566Z","end":"2026-05-05T20:56:54.314563Z","steps":["trace[47797662] 'process raft request' (duration: 164.322648ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:54.322861Z","caller":"traceutil/trace.go:171","msg":"trace[1791140939] transaction","detail":"{read_only:false; response_revision:690; number_of_response:1; }","duration":"116.315836ms","start":"2026-05-05T20:56:54.206522Z","end":"2026-05-05T20:56:54.322838Z","steps":["trace[1791140939] 'process raft request' (duration: 116.171543ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:56.718578Z","caller":"traceutil/trace.go:171","msg":"trace[972721187] linearizableReadLoop","detail":"{readStateIndex:747; appliedIndex:746; }","duration":"171.317214ms","start":"2026-05-05T20:56:56.54723Z","end":"2026-05-05T20:56:56.718547Z","steps":["trace[972721187] 'read index received' (duration: 170.652258ms)","trace[972721187] 'applied index is now lower than readState.Index' (duration: 662.866µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:56.718904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.620192ms","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-05-05T20:56:56.718956Z","caller":"traceutil/trace.go:171","msg":"trace[1890709025] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumidentities.cilium.io; range_end:; response_count:1; response_revision:722; }","duration":"171.782245ms","start":"2026-05-05T20:56:56.547158Z","end":"2026-05-05T20:56:56.71894Z","steps":["trace[1890709025] 'agreement among raft nodes before linearized reading' (duration: 171.53279ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:56.71899Z","caller":"traceutil/trace.go:171","msg":"trace[1027384281] transaction","detail":"{read_only:false; response_revision:722; number_of_response:1; }","duration":"343.833936ms","start":"2026-05-05T20:56:56.375092Z","end":"2026-05-05T20:56:56.718925Z","steps":["trace[1027384281] 'process raft request' (duration: 343.052527ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:56.719224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:56.375047Z","time spent":"344.059841ms","remote":"127.0.0.1:54806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188480,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T20:56:56.939822Z","caller":"traceutil/trace.go:171","msg":"trace[565415861] transaction","detail":"{read_only:false; response_revision:723; number_of_response:1; }","duration":"165.927561ms","start":"2026-05-05T20:56:56.773862Z","end":"2026-05-05T20:56:56.939789Z","steps":["trace[565415861] 'process raft request' (duration: 165.690555ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:57.562588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.969456ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T20:56:57.562822Z","caller":"traceutil/trace.go:171","msg":"trace[432521474] transaction","detail":"{read_only:false; response_revision:725; number_of_response:1; }","duration":"388.396362ms","start":"2026-05-05T20:56:57.174382Z","end":"2026-05-05T20:56:57.562778Z","steps":["trace[432521474] 'process raft request' (duration: 73.144979ms)","trace[432521474] 'compare' (duration: 314.826443ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:57.563028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:57.17436Z","time spent":"388.551725ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T20:56:57.563137Z","caller":"traceutil/trace.go:171","msg":"trace[1333076511] linearizableReadLoop","detail":"{readStateIndex:751; appliedIndex:749; }","duration":"308.367005ms","start":"2026-05-05T20:56:57.254714Z","end":"2026-05-05T20:56:57.563081Z","steps":["trace[1333076511] 'read index received' (duration: 307.463805ms)","trace[1333076511] 'applied index is now lower than readState.Index' (duration: 901.37µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:57.563264Z","caller":"traceutil/trace.go:171","msg":"trace[1132118999] transaction","detail":"{read_only:false; response_revision:726; number_of_response:1; }","duration":"367.301982ms","start":"2026-05-05T20:56:57.195916Z","end":"2026-05-05T20:56:57.563218Z","steps":["trace[1132118999] 'process raft request' (duration: 366.856012ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:57.5636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:57.195885Z","time spent":"367.564099ms","remote":"127.0.0.1:54806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":188237,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T20:56:57.563584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.755014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9rfr4\" ","response":"range_response_count:1 size:18333"} {"level":"info","ts":"2026-05-05T20:56:57.563687Z","caller":"traceutil/trace.go:171","msg":"trace[1677326280] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9rfr4; range_end:; response_count:1; response_revision:726; }","duration":"309.00347ms","start":"2026-05-05T20:56:57.254665Z","end":"2026-05-05T20:56:57.563669Z","steps":["trace[1677326280] 'agreement among raft nodes before linearized reading' (duration: 308.539969ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:57.563739Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:57.254643Z","time spent":"309.078762ms","remote":"127.0.0.1:54956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":18357,"request content":"key:\"/registry/pods/kube-system/cilium-9rfr4\" "} {"level":"info","ts":"2026-05-05T20:56:57.614602Z","caller":"traceutil/trace.go:171","msg":"trace[2070787407] transaction","detail":"{read_only:false; response_revision:727; number_of_response:1; }","duration":"359.346542ms","start":"2026-05-05T20:56:57.255223Z","end":"2026-05-05T20:56:57.614569Z","steps":["trace[2070787407] 'process raft request' (duration: 359.200929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:57.614763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:57.255211Z","time spent":"359.473185ms","remote":"127.0.0.1:54840","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":761,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T20:56:57.614817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.97531ms","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-05-05T20:56:57.614904Z","caller":"traceutil/trace.go:171","msg":"trace[1889420844] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumendpoints.cilium.io; range_end:; response_count:1; response_revision:727; }","duration":"268.156924ms","start":"2026-05-05T20:56:57.346724Z","end":"2026-05-05T20:56:57.614881Z","steps":["trace[1889420844] 'agreement among raft nodes before linearized reading' (duration: 267.840936ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:57.891452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.552321ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T20:56:57.891573Z","caller":"traceutil/trace.go:171","msg":"trace[1324807070] linearizableReadLoop","detail":"{readStateIndex:753; appliedIndex:752; }","duration":"144.368715ms","start":"2026-05-05T20:56:57.747186Z","end":"2026-05-05T20:56:57.891555Z","steps":["trace[1324807070] 'read index received' (duration: 39.531037ms)","trace[1324807070] 'applied index is now lower than readState.Index' (duration: 104.836298ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:56:57.891607Z","caller":"traceutil/trace.go:171","msg":"trace[1985496196] transaction","detail":"{read_only:false; response_revision:728; number_of_response:1; }","duration":"308.686183ms","start":"2026-05-05T20:56:57.582886Z","end":"2026-05-05T20:56:57.891573Z","steps":["trace[1985496196] 'process raft request' (duration: 203.917816ms)","trace[1985496196] 'compare' (duration: 104.429089ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:57.891759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:57.582858Z","time spent":"308.818576ms","remote":"127.0.0.1:54956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":18497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T20:56:57.891799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.483936ms","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-05-05T20:56:57.891828Z","caller":"traceutil/trace.go:171","msg":"trace[278841178] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:728; }","duration":"110.573128ms","start":"2026-05-05T20:56:57.781246Z","end":"2026-05-05T20:56:57.891819Z","steps":["trace[278841178] 'agreement among raft nodes before linearized reading' (duration: 110.441425ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:57.891895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.686522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io\" ","response":"range_response_count:1 size:188252"} {"level":"info","ts":"2026-05-05T20:56:57.892012Z","caller":"traceutil/trace.go:171","msg":"trace[777091313] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnetworkpolicies.cilium.io; range_end:; response_count:1; response_revision:728; }","duration":"144.852086ms","start":"2026-05-05T20:56:57.747128Z","end":"2026-05-05T20:56:57.89198Z","steps":["trace[777091313] 'agreement among raft nodes before linearized reading' (duration: 144.491508ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:57.892062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.724432ms","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-05-05T20:56:57.892107Z","caller":"traceutil/trace.go:171","msg":"trace[1661791580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:728; }","duration":"110.807934ms","start":"2026-05-05T20:56:57.781288Z","end":"2026-05-05T20:56:57.892096Z","steps":["trace[1661791580] 'agreement among raft nodes before linearized reading' (duration: 110.408654ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:57.892229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.747461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T20:56:57.89226Z","caller":"traceutil/trace.go:171","msg":"trace[1685201412] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:728; }","duration":"109.795392ms","start":"2026-05-05T20:56:57.782456Z","end":"2026-05-05T20:56:57.892252Z","steps":["trace[1685201412] 'agreement among raft nodes before linearized reading' (duration: 109.248518ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:57.892348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.04303ms","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-05-05T20:56:57.892388Z","caller":"traceutil/trace.go:171","msg":"trace[366799361] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:728; }","duration":"111.122472ms","start":"2026-05-05T20:56:57.781256Z","end":"2026-05-05T20:56:57.892379Z","steps":["trace[366799361] 'agreement among raft nodes before linearized reading' (duration: 110.394364ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:58.141534Z","caller":"traceutil/trace.go:171","msg":"trace[1584517899] transaction","detail":"{read_only:false; response_revision:729; number_of_response:1; }","duration":"243.496744ms","start":"2026-05-05T20:56:57.898024Z","end":"2026-05-05T20:56:58.14152Z","steps":["trace[1584517899] 'process raft request' (duration: 243.372351ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:58.298264Z","caller":"traceutil/trace.go:171","msg":"trace[1196311575] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"399.950439ms","start":"2026-05-05T20:56:57.898268Z","end":"2026-05-05T20:56:58.298219Z","steps":["trace[1196311575] 'process raft request' (duration: 396.39917ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:58.298276Z","caller":"traceutil/trace.go:171","msg":"trace[694436015] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"399.542941ms","start":"2026-05-05T20:56:57.898703Z","end":"2026-05-05T20:56:58.298246Z","steps":["trace[694436015] 'process raft request' (duration: 399.422107ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:58.298459Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:57.898259Z","time spent":"400.097003ms","remote":"127.0.0.1:55078","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-05-05T20:56:58.298469Z","caller":"traceutil/trace.go:171","msg":"trace[239354249] linearizableReadLoop","detail":"{readStateIndex:757; appliedIndex:754; }","duration":"315.258869ms","start":"2026-05-05T20:56:57.983176Z","end":"2026-05-05T20:56:58.298435Z","steps":["trace[239354249] 'read index received' (duration: 158.702621ms)","trace[239354249] 'applied index is now lower than readState.Index' (duration: 156.554638ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:56:58.298519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:57.898681Z","time spent":"399.707505ms","remote":"127.0.0.1:55078","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-05-05T20:56:58.298629Z","caller":"traceutil/trace.go:171","msg":"trace[1753460054] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"399.142452ms","start":"2026-05-05T20:56:57.89947Z","end":"2026-05-05T20:56:58.298612Z","steps":["trace[1753460054] 'process raft request' (duration: 398.725992ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:58.298814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:57.899461Z","time spent":"399.214853ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T20:56:58.298932Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.183546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:58.298976Z","caller":"traceutil/trace.go:171","msg":"trace[689981485] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"223.345849ms","start":"2026-05-05T20:56:58.075608Z","end":"2026-05-05T20:56:58.298954Z","steps":["trace[689981485] 'process raft request' (duration: 222.713494ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:58.298994Z","caller":"traceutil/trace.go:171","msg":"trace[197574008] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:733; }","duration":"138.266817ms","start":"2026-05-05T20:56:58.160709Z","end":"2026-05-05T20:56:58.298975Z","steps":["trace[197574008] 'agreement among raft nodes before linearized reading' (duration: 138.141604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:58.299038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.723837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T20:56:58.298977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.227492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:58.299166Z","caller":"traceutil/trace.go:171","msg":"trace[1346874437] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:733; }","duration":"201.434756ms","start":"2026-05-05T20:56:58.097716Z","end":"2026-05-05T20:56:58.299151Z","steps":["trace[1346874437] 'agreement among raft nodes before linearized reading' (duration: 201.205661ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:58.299222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.264742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:58.299278Z","caller":"traceutil/trace.go:171","msg":"trace[3569996] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:733; }","duration":"201.327934ms","start":"2026-05-05T20:56:58.097936Z","end":"2026-05-05T20:56:58.299264Z","steps":["trace[3569996] 'agreement among raft nodes before linearized reading' (duration: 201.255533ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:58.299302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.570489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:58.299357Z","caller":"traceutil/trace.go:171","msg":"trace[1712024760] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:733; }","duration":"201.687892ms","start":"2026-05-05T20:56:58.097656Z","end":"2026-05-05T20:56:58.299344Z","steps":["trace[1712024760] 'agreement among raft nodes before linearized reading' (duration: 201.60282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:58.299335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.930759ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T20:56:58.298809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.412863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:58.299468Z","caller":"traceutil/trace.go:171","msg":"trace[964179388] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:733; }","duration":"316.107368ms","start":"2026-05-05T20:56:57.983347Z","end":"2026-05-05T20:56:58.299455Z","steps":["trace[964179388] 'agreement among raft nodes before linearized reading' (duration: 315.392202ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:58.299492Z","caller":"traceutil/trace.go:171","msg":"trace[1804671838] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:733; }","duration":"158.147655ms","start":"2026-05-05T20:56:58.14131Z","end":"2026-05-05T20:56:58.299457Z","steps":["trace[1804671838] 'agreement among raft nodes before linearized reading' (duration: 157.783777ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:58.299517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:57.983341Z","time spent":"316.160439ms","remote":"127.0.0.1:60224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 "} {"level":"info","ts":"2026-05-05T20:56:58.299111Z","caller":"traceutil/trace.go:171","msg":"trace[1826322552] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:733; }","duration":"138.802609ms","start":"2026-05-05T20:56:58.160273Z","end":"2026-05-05T20:56:58.299075Z","steps":["trace[1826322552] 'agreement among raft nodes before linearized reading' (duration: 138.738908ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:58.299657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.385922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:58.299741Z","caller":"traceutil/trace.go:171","msg":"trace[554083916] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:733; }","duration":"139.523856ms","start":"2026-05-05T20:56:58.160202Z","end":"2026-05-05T20:56:58.299726Z","steps":["trace[554083916] 'agreement among raft nodes before linearized reading' (duration: 139.367532ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:58.299862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.506292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T20:56:58.299922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.30323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:58.300497Z","caller":"traceutil/trace.go:171","msg":"trace[441182943] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:733; }","duration":"248.945824ms","start":"2026-05-05T20:56:58.051534Z","end":"2026-05-05T20:56:58.30048Z","steps":["trace[441182943] 'agreement among raft nodes before linearized reading' (duration: 248.343231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:58.299167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.862448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" limit:10 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:58.302739Z","caller":"traceutil/trace.go:171","msg":"trace[526049053] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:733; }","duration":"161.461149ms","start":"2026-05-05T20:56:58.141261Z","end":"2026-05-05T20:56:58.302722Z","steps":["trace[526049053] 'agreement among raft nodes before linearized reading' (duration: 157.874429ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:56:58.299927Z","caller":"traceutil/trace.go:171","msg":"trace[2018679128] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:733; }","duration":"158.578854ms","start":"2026-05-05T20:56:58.141333Z","end":"2026-05-05T20:56:58.299912Z","steps":["trace[2018679128] 'agreement among raft nodes before linearized reading' (duration: 158.488272ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:58.300011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.372835ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:58.302866Z","caller":"traceutil/trace.go:171","msg":"trace[375181873] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:733; }","duration":"301.241059ms","start":"2026-05-05T20:56:58.00161Z","end":"2026-05-05T20:56:58.302851Z","steps":["trace[375181873] 'agreement among raft nodes before linearized reading' (duration: 298.365584ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:58.302916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:58.001601Z","time spent":"301.296571ms","remote":"127.0.0.1:60232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 "} {"level":"warn","ts":"2026-05-05T20:56:58.300062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.825783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:58.303072Z","caller":"traceutil/trace.go:171","msg":"trace[1980180671] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:733; }","duration":"300.84839ms","start":"2026-05-05T20:56:58.002213Z","end":"2026-05-05T20:56:58.303061Z","steps":["trace[1980180671] 'agreement among raft nodes before linearized reading' (duration: 297.820732ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:58.30311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:58.002206Z","time spent":"300.891911ms","remote":"127.0.0.1:60232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 "} {"level":"warn","ts":"2026-05-05T20:56:58.300113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.299573ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:58.303174Z","caller":"traceutil/trace.go:171","msg":"trace[616501673] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:733; }","duration":"301.383113ms","start":"2026-05-05T20:56:58.00178Z","end":"2026-05-05T20:56:58.303163Z","steps":["trace[616501673] 'agreement among raft nodes before linearized reading' (duration: 298.304804ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:58.303236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:58.001763Z","time spent":"301.462534ms","remote":"127.0.0.1:60232","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true "} {"level":"warn","ts":"2026-05-05T20:56:58.300119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.903095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T20:56:58.300338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.081407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:56:58.303436Z","caller":"traceutil/trace.go:171","msg":"trace[365135952] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:733; }","duration":"319.3663ms","start":"2026-05-05T20:56:57.984049Z","end":"2026-05-05T20:56:58.303415Z","steps":["trace[365135952] 'agreement among raft nodes before linearized reading' (duration: 316.071756ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:58.303498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:57.984043Z","time spent":"319.439703ms","remote":"127.0.0.1:60224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 "} {"level":"info","ts":"2026-05-05T20:56:58.303293Z","caller":"traceutil/trace.go:171","msg":"trace[2010122971] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:733; }","duration":"320.116697ms","start":"2026-05-05T20:56:57.983169Z","end":"2026-05-05T20:56:58.303286Z","steps":["trace[2010122971] 'agreement among raft nodes before linearized reading' (duration: 315.474384ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:56:58.304312Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:56:57.98312Z","time spent":"321.182082ms","remote":"127.0.0.1:60224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true "} {"level":"info","ts":"2026-05-05T20:57:06.020944Z","caller":"traceutil/trace.go:171","msg":"trace[1630518517] transaction","detail":"{read_only:false; response_revision:791; number_of_response:1; }","duration":"416.559136ms","start":"2026-05-05T20:57:05.604355Z","end":"2026-05-05T20:57:06.020914Z","steps":["trace[1630518517] 'process raft request' (duration: 416.387663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:57:06.021142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:57:05.604326Z","time spent":"416.73393ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T20:57:08.608917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.704495ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T20:57:08.609018Z","caller":"traceutil/trace.go:171","msg":"trace[1304331738] linearizableReadLoop","detail":"{readStateIndex:833; appliedIndex:832; }","duration":"558.254158ms","start":"2026-05-05T20:57:08.050747Z","end":"2026-05-05T20:57:08.609001Z","steps":["trace[1304331738] 'read index received' (duration: 22.311207ms)","trace[1304331738] 'applied index is now lower than readState.Index' (duration: 535.941491ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:57:08.6091Z","caller":"traceutil/trace.go:171","msg":"trace[853275690] transaction","detail":"{read_only:false; response_revision:804; number_of_response:1; }","duration":"580.34769ms","start":"2026-05-05T20:57:08.028733Z","end":"2026-05-05T20:57:08.60908Z","steps":["trace[853275690] 'process raft request' (duration: 44.38426ms)","trace[853275690] 'compare' (duration: 535.601903ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:57:08.609245Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"558.497913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T20:57:08.609269Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:57:08.028706Z","time spent":"580.450172ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T20:57:08.609299Z","caller":"traceutil/trace.go:171","msg":"trace[1170942593] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:804; }","duration":"558.578664ms","start":"2026-05-05T20:57:08.050705Z","end":"2026-05-05T20:57:08.609283Z","steps":["trace[1170942593] 'agreement among raft nodes before linearized reading' (duration: 558.469311ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:57:08.609332Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:57:08.05069Z","time spent":"558.634985ms","remote":"127.0.0.1:54746","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-05-05T20:57:09.109224Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278618974044521,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T20:57:09.615327Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":17256278618974044521,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T20:57:09.637456Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.129679222s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T20:57:09.63799Z","caller":"traceutil/trace.go:171","msg":"trace[1134666144] transaction","detail":"{read_only:false; response_revision:805; number_of_response:1; }","duration":"1.130315705s","start":"2026-05-05T20:57:08.507653Z","end":"2026-05-05T20:57:09.637969Z","steps":["trace[1134666144] 'process raft request' (duration: 1.130139151s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:57:09.638165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:57:08.507621Z","time spent":"1.130420766s","remote":"127.0.0.1:54948","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4904,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T20:57:09.638826Z","caller":"traceutil/trace.go:171","msg":"trace[431975789] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"1.029756874s","start":"2026-05-05T20:57:08.609045Z","end":"2026-05-05T20:57:09.638802Z","steps":["trace[431975789] 'read index received' (duration: 1.029458999s)","trace[431975789] 'applied index is now lower than readState.Index' (duration: 296.665µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:57:09.639045Z","caller":"traceutil/trace.go:171","msg":"trace[121449752] transaction","detail":"{read_only:false; response_revision:806; number_of_response:1; }","duration":"323.454259ms","start":"2026-05-05T20:57:09.315583Z","end":"2026-05-05T20:57:09.639037Z","steps":["trace[121449752] 'process raft request' (duration: 323.110982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:57:09.639147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:57:09.315561Z","time spent":"323.50678ms","remote":"127.0.0.1:55078","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-05-05T20:57:09.639339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.29802664s","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-05-05T20:57:09.639362Z","caller":"traceutil/trace.go:171","msg":"trace[310096340] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:806; }","duration":"1.2980473s","start":"2026-05-05T20:57:08.341307Z","end":"2026-05-05T20:57:09.639354Z","steps":["trace[310096340] 'agreement among raft nodes before linearized reading' (duration: 1.297957979s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:57:09.639412Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:57:08.341278Z","time spent":"1.298128552s","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T20:57:09.639664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.298909219s","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-05-05T20:57:09.63969Z","caller":"traceutil/trace.go:171","msg":"trace[1947063970] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:806; }","duration":"1.298981121s","start":"2026-05-05T20:57:08.340702Z","end":"2026-05-05T20:57:09.639683Z","steps":["trace[1947063970] 'agreement among raft nodes before linearized reading' (duration: 1.298893629s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:57:09.639708Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:57:08.340661Z","time spent":"1.299042291s","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T20:57:09.639854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.299287646s","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-05-05T20:57:09.639896Z","caller":"traceutil/trace.go:171","msg":"trace[581550236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:806; }","duration":"1.299344278s","start":"2026-05-05T20:57:08.340527Z","end":"2026-05-05T20:57:09.639872Z","steps":["trace[581550236] 'agreement among raft nodes before linearized reading' (duration: 1.299257226s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:57:09.639918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:57:08.340512Z","time spent":"1.299400169s","remote":"127.0.0.1:55078","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-05-05T20:57:09.640148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.340898448s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nfnmj\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-05T20:57:09.640208Z","caller":"traceutil/trace.go:171","msg":"trace[2067176602] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-nfnmj; range_end:; response_count:1; response_revision:806; }","duration":"1.34098528s","start":"2026-05-05T20:57:08.299212Z","end":"2026-05-05T20:57:09.640197Z","steps":["trace[2067176602] 'agreement among raft nodes before linearized reading' (duration: 1.340621833s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:57:09.640254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:57:08.2992Z","time spent":"1.341046671s","remote":"127.0.0.1:54956","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-nfnmj\" "} {"level":"warn","ts":"2026-05-05T20:57:09.64034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.029797054s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:57:09.640366Z","caller":"traceutil/trace.go:171","msg":"trace[1782407671] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:806; }","duration":"1.029849665s","start":"2026-05-05T20:57:08.610509Z","end":"2026-05-05T20:57:09.640359Z","steps":["trace[1782407671] 'agreement among raft nodes before linearized reading' (duration: 1.029807465s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:57:09.640385Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:57:08.610494Z","time spent":"1.029886646s","remote":"127.0.0.1:54752","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-05-05T20:57:09.640386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.447695766s","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-05-05T20:57:09.640546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.213443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T20:57:09.640579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.816555ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T20:57:09.640556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.490266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T20:57:09.640621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.884806ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T20:57:09.640676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"915.36394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T20:57:09.640697Z","caller":"traceutil/trace.go:171","msg":"trace[2098019047] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:806; }","duration":"915.40927ms","start":"2026-05-05T20:57:08.725282Z","end":"2026-05-05T20:57:09.640691Z","steps":["trace[2098019047] 'agreement among raft nodes before linearized reading' (duration: 915.353699ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:57:09.640663Z","caller":"traceutil/trace.go:171","msg":"trace[1679640006] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:806; }","duration":"1.447781229s","start":"2026-05-05T20:57:08.19264Z","end":"2026-05-05T20:57:09.640422Z","steps":["trace[1679640006] 'agreement among raft nodes before linearized reading' (duration: 1.447558564s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:57:09.640716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:57:08.72527Z","time spent":"915.44136ms","remote":"127.0.0.1:54982","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":42,"response size":31,"request content":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true "} {"level":"info","ts":"2026-05-05T20:57:09.640659Z","caller":"traceutil/trace.go:171","msg":"trace[761881312] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:806; }","duration":"117.604148ms","start":"2026-05-05T20:57:09.523043Z","end":"2026-05-05T20:57:09.640647Z","steps":["trace[761881312] 'agreement among raft nodes before linearized reading' (duration: 117.457585ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:57:09.640977Z","caller":"traceutil/trace.go:171","msg":"trace[1122814187] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:806; }","duration":"256.853825ms","start":"2026-05-05T20:57:09.383746Z","end":"2026-05-05T20:57:09.6406Z","steps":["trace[1122814187] 'agreement among raft nodes before linearized reading' (duration: 256.810454ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:57:09.640991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.997807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:57:09.641092Z","caller":"traceutil/trace.go:171","msg":"trace[1422416799] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:806; }","duration":"118.13357ms","start":"2026-05-05T20:57:09.52295Z","end":"2026-05-05T20:57:09.641084Z","steps":["trace[1422416799] 'agreement among raft nodes before linearized reading' (duration: 118.015367ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:57:09.641274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.457699ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:57:09.641326Z","caller":"traceutil/trace.go:171","msg":"trace[1229261467] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:806; }","duration":"162.529631ms","start":"2026-05-05T20:57:09.478789Z","end":"2026-05-05T20:57:09.641319Z","steps":["trace[1229261467] 'agreement among raft nodes before linearized reading' (duration: 162.462579ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:57:09.64142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.093922ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:57:09.641451Z","caller":"traceutil/trace.go:171","msg":"trace[1411939532] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:806; }","duration":"163.184434ms","start":"2026-05-05T20:57:09.478259Z","end":"2026-05-05T20:57:09.641443Z","steps":["trace[1411939532] 'agreement among raft nodes before linearized reading' (duration: 163.134333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:57:09.641582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.44558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:57:09.641606Z","caller":"traceutil/trace.go:171","msg":"trace[1380548981] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:806; }","duration":"163.49689ms","start":"2026-05-05T20:57:09.478102Z","end":"2026-05-05T20:57:09.641599Z","steps":["trace[1380548981] 'agreement among raft nodes before linearized reading' (duration: 163.461279ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:57:09.640736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:57:08.192617Z","time spent":"1.448109505s","remote":"127.0.0.1:55078","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-05-05T20:57:09.64192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.351363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:57:09.641996Z","caller":"traceutil/trace.go:171","msg":"trace[763492263] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:806; }","duration":"118.448865ms","start":"2026-05-05T20:57:09.523529Z","end":"2026-05-05T20:57:09.641978Z","steps":["trace[763492263] 'agreement among raft nodes before linearized reading' (duration: 118.337923ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:57:09.642017Z","caller":"traceutil/trace.go:171","msg":"trace[1234470356] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:806; }","duration":"256.313504ms","start":"2026-05-05T20:57:09.384299Z","end":"2026-05-05T20:57:09.640612Z","steps":["trace[1234470356] 'agreement among raft nodes before linearized reading' (duration: 256.219683ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:57:09.642114Z","caller":"traceutil/trace.go:171","msg":"trace[137411053] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:806; }","duration":"256.922737ms","start":"2026-05-05T20:57:09.383711Z","end":"2026-05-05T20:57:09.640633Z","steps":["trace[137411053] 'agreement among raft nodes before linearized reading' (duration: 256.892046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:57:23.786814Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.429262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T20:57:23.787354Z","caller":"traceutil/trace.go:171","msg":"trace[1480698300] transaction","detail":"{read_only:false; response_revision:967; number_of_response:1; }","duration":"346.38903ms","start":"2026-05-05T20:57:23.44092Z","end":"2026-05-05T20:57:23.787309Z","steps":["trace[1480698300] 'process raft request' (duration: 71.388988ms)","trace[1480698300] 'compare' (duration: 274.188408ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:57:23.787623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:57:23.4409Z","time spent":"346.616484ms","remote":"127.0.0.1:55276","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3745,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T20:57:36.013305Z","caller":"traceutil/trace.go:171","msg":"trace[1554423134] transaction","detail":"{read_only:false; response_revision:1018; number_of_response:1; }","duration":"151.535984ms","start":"2026-05-05T20:57:35.861743Z","end":"2026-05-05T20:57:36.013279Z","steps":["trace[1554423134] 'process raft request' (duration: 151.381382ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:57:36.023021Z","caller":"traceutil/trace.go:171","msg":"trace[1067826589] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"160.759134ms","start":"2026-05-05T20:57:35.862229Z","end":"2026-05-05T20:57:36.022988Z","steps":["trace[1067826589] 'process raft request' (duration: 160.572471ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:57:36.023453Z","caller":"traceutil/trace.go:171","msg":"trace[1911130866] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"160.624992ms","start":"2026-05-05T20:57:35.862795Z","end":"2026-05-05T20:57:36.02342Z","steps":["trace[1911130866] 'process raft request' (duration: 160.149324ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:57:36.023536Z","caller":"traceutil/trace.go:171","msg":"trace[938196356] linearizableReadLoop","detail":"{readStateIndex:1055; appliedIndex:1052; }","duration":"158.436115ms","start":"2026-05-05T20:57:35.865061Z","end":"2026-05-05T20:57:36.023498Z","steps":["trace[938196356] 'read index received' (duration: 148.166988ms)","trace[938196356] 'applied index is now lower than readState.Index' (duration: 10.267047ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:57:36.02382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.72294ms","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-05-05T20:57:36.023902Z","caller":"traceutil/trace.go:171","msg":"trace[9294905] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1021; }","duration":"158.886762ms","start":"2026-05-05T20:57:35.865001Z","end":"2026-05-05T20:57:36.023887Z","steps":["trace[9294905] 'agreement among raft nodes before linearized reading' (duration: 158.637888ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:57:54.834476Z","caller":"traceutil/trace.go:171","msg":"trace[924871390] transaction","detail":"{read_only:false; response_revision:1087; number_of_response:1; }","duration":"223.615583ms","start":"2026-05-05T20:57:54.610838Z","end":"2026-05-05T20:57:54.834454Z","steps":["trace[924871390] 'process raft request' (duration: 223.45597ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:57:58.406243Z","caller":"traceutil/trace.go:171","msg":"trace[1073053603] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"137.807311ms","start":"2026-05-05T20:57:58.268405Z","end":"2026-05-05T20:57:58.406212Z","steps":["trace[1073053603] 'process raft request' (duration: 137.103061ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:57:58.406405Z","caller":"traceutil/trace.go:171","msg":"trace[1849648421] transaction","detail":"{read_only:false; response_revision:1099; number_of_response:1; }","duration":"137.75198ms","start":"2026-05-05T20:57:58.268628Z","end":"2026-05-05T20:57:58.40638Z","steps":["trace[1849648421] 'process raft request' (duration: 137.657339ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:58:32.574067Z","caller":"traceutil/trace.go:171","msg":"trace[665996533] linearizableReadLoop","detail":"{readStateIndex:1255; appliedIndex:1254; }","duration":"114.843995ms","start":"2026-05-05T20:58:32.4592Z","end":"2026-05-05T20:58:32.574044Z","steps":["trace[665996533] 'read index received' (duration: 114.676923ms)","trace[665996533] 'applied index is now lower than readState.Index' (duration: 165.831µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:58:32.57417Z","caller":"traceutil/trace.go:171","msg":"trace[1955805614] transaction","detail":"{read_only:false; response_revision:1208; number_of_response:1; }","duration":"125.639344ms","start":"2026-05-05T20:58:32.448497Z","end":"2026-05-05T20:58:32.574136Z","steps":["trace[1955805614] 'process raft request' (duration: 125.398041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:58:32.574434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.222809ms","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-05-05T20:58:32.574482Z","caller":"traceutil/trace.go:171","msg":"trace[219876251] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1208; }","duration":"115.34523ms","start":"2026-05-05T20:58:32.459126Z","end":"2026-05-05T20:58:32.574471Z","steps":["trace[219876251] 'agreement among raft nodes before linearized reading' (duration: 115.046807ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:58:34.808027Z","caller":"traceutil/trace.go:171","msg":"trace[1907848583] transaction","detail":"{read_only:false; response_revision:1218; number_of_response:1; }","duration":"140.104423ms","start":"2026-05-05T20:58:34.667891Z","end":"2026-05-05T20:58:34.807996Z","steps":["trace[1907848583] 'process raft request' (duration: 140.033912ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:58:34.808182Z","caller":"traceutil/trace.go:171","msg":"trace[1798647489] transaction","detail":"{read_only:false; response_revision:1217; number_of_response:1; }","duration":"140.397017ms","start":"2026-05-05T20:58:34.667754Z","end":"2026-05-05T20:58:34.808151Z","steps":["trace[1798647489] 'process raft request' (duration: 70.083762ms)","trace[1798647489] 'compare' (duration: 69.94116ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:58:44.64085Z","caller":"traceutil/trace.go:171","msg":"trace[1942667829] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"228.170949ms","start":"2026-05-05T20:58:44.412647Z","end":"2026-05-05T20:58:44.640818Z","steps":["trace[1942667829] 'process raft request' (duration: 227.979557ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:58:45.069023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.789113ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:58:45.06914Z","caller":"traceutil/trace.go:171","msg":"trace[931191595] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1252; }","duration":"116.943565ms","start":"2026-05-05T20:58:44.952165Z","end":"2026-05-05T20:58:45.069109Z","steps":["trace[931191595] 'count revisions from in-memory index tree' (duration: 116.67072ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:58:45.647829Z","caller":"traceutil/trace.go:171","msg":"trace[1358420297] linearizableReadLoop","detail":"{readStateIndex:1313; appliedIndex:1312; }","duration":"113.906718ms","start":"2026-05-05T20:58:45.533898Z","end":"2026-05-05T20:58:45.647805Z","steps":["trace[1358420297] 'read index received' (duration: 113.638515ms)","trace[1358420297] 'applied index is now lower than readState.Index' (duration: 267.343µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:58:45.647924Z","caller":"traceutil/trace.go:171","msg":"trace[1824306537] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"115.160122ms","start":"2026-05-05T20:58:45.532716Z","end":"2026-05-05T20:58:45.647876Z","steps":["trace[1824306537] 'process raft request' (duration: 114.9423ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:58:45.647963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.04379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4f881aca-306d-49ee-9b1c-aa2cb40dcd29\" ","response":"range_response_count:1 size:3578"} {"level":"info","ts":"2026-05-05T20:58:45.648044Z","caller":"traceutil/trace.go:171","msg":"trace[155443166] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4f881aca-306d-49ee-9b1c-aa2cb40dcd29; range_end:; response_count:1; response_revision:1262; }","duration":"114.150001ms","start":"2026-05-05T20:58:45.533878Z","end":"2026-05-05T20:58:45.648028Z","steps":["trace[155443166] 'agreement among raft nodes before linearized reading' (duration: 114.016209ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:58:48.916536Z","caller":"traceutil/trace.go:171","msg":"trace[186721564] transaction","detail":"{read_only:false; response_revision:1276; number_of_response:1; }","duration":"178.12274ms","start":"2026-05-05T20:58:48.738351Z","end":"2026-05-05T20:58:48.916473Z","steps":["trace[186721564] 'process raft request' (duration: 172.774409ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:58:53.309714Z","caller":"traceutil/trace.go:171","msg":"trace[586264216] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"130.455177ms","start":"2026-05-05T20:58:53.179234Z","end":"2026-05-05T20:58:53.30969Z","steps":["trace[586264216] 'process raft request' (duration: 130.323456ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:58:55.290422Z","caller":"traceutil/trace.go:171","msg":"trace[1397586719] linearizableReadLoop","detail":"{readStateIndex:1366; appliedIndex:1365; }","duration":"222.888217ms","start":"2026-05-05T20:58:55.067504Z","end":"2026-05-05T20:58:55.290392Z","steps":["trace[1397586719] 'read index received' (duration: 222.630464ms)","trace[1397586719] 'applied index is now lower than readState.Index' (duration: 256.063µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:58:55.290487Z","caller":"traceutil/trace.go:171","msg":"trace[267741798] transaction","detail":"{read_only:false; response_revision:1312; number_of_response:1; }","duration":"254.309612ms","start":"2026-05-05T20:58:55.036155Z","end":"2026-05-05T20:58:55.290465Z","steps":["trace[267741798] 'process raft request' (duration: 254.05614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:58:55.290699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.17304ms","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-05-05T20:58:55.290929Z","caller":"traceutil/trace.go:171","msg":"trace[1317395503] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1312; }","duration":"223.454113ms","start":"2026-05-05T20:58:55.067453Z","end":"2026-05-05T20:58:55.290907Z","steps":["trace[1317395503] 'agreement among raft nodes before linearized reading' (duration: 223.106779ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:58:55.290804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.702916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T20:58:55.290817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.276501ms","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-05-05T20:58:55.291078Z","caller":"traceutil/trace.go:171","msg":"trace[450980567] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1312; }","duration":"214.959869ms","start":"2026-05-05T20:58:55.076068Z","end":"2026-05-05T20:58:55.291028Z","steps":["trace[450980567] 'agreement among raft nodes before linearized reading' (duration: 214.699767ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:58:55.291143Z","caller":"traceutil/trace.go:171","msg":"trace[1609574584] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1312; }","duration":"223.579605ms","start":"2026-05-05T20:58:55.067523Z","end":"2026-05-05T20:58:55.291102Z","steps":["trace[1609574584] 'agreement among raft nodes before linearized reading' (duration: 223.17369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:58:55.29083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.845364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T20:58:55.291333Z","caller":"traceutil/trace.go:171","msg":"trace[310531883] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1312; }","duration":"114.376481ms","start":"2026-05-05T20:58:55.17694Z","end":"2026-05-05T20:58:55.291317Z","steps":["trace[310531883] 'agreement among raft nodes before linearized reading' (duration: 113.795724ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:58:55.291626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.131648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-05-05T20:58:55.291697Z","caller":"traceutil/trace.go:171","msg":"trace[1995626083] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1312; }","duration":"114.796915ms","start":"2026-05-05T20:58:55.17689Z","end":"2026-05-05T20:58:55.291687Z","steps":["trace[1995626083] 'agreement among raft nodes before linearized reading' (duration: 113.844554ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:58:55.844151Z","caller":"traceutil/trace.go:171","msg":"trace[1289257728] transaction","detail":"{read_only:false; response_revision:1313; number_of_response:1; }","duration":"548.641159ms","start":"2026-05-05T20:58:55.29548Z","end":"2026-05-05T20:58:55.844121Z","steps":["trace[1289257728] 'process raft request' (duration: 504.198239ms)","trace[1289257728] 'compare' (duration: 44.015255ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:58:55.844357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:58:55.295464Z","time spent":"548.76944ms","remote":"127.0.0.1:55078","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-05-05T20:58:55.913828Z","caller":"traceutil/trace.go:171","msg":"trace[1043687369] linearizableReadLoop","detail":"{readStateIndex:1369; appliedIndex:1366; }","duration":"534.939827ms","start":"2026-05-05T20:58:55.378863Z","end":"2026-05-05T20:58:55.913803Z","steps":["trace[1043687369] 'read index received' (duration: 420.833689ms)","trace[1043687369] 'applied index is now lower than readState.Index' (duration: 114.104718ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:58:55.913914Z","caller":"traceutil/trace.go:171","msg":"trace[1680775112] transaction","detail":"{read_only:false; response_revision:1314; number_of_response:1; }","duration":"618.014014ms","start":"2026-05-05T20:58:55.295887Z","end":"2026-05-05T20:58:55.913901Z","steps":["trace[1680775112] 'process raft request' (duration: 617.688541ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:58:55.913907Z","caller":"traceutil/trace.go:171","msg":"trace[1721772918] transaction","detail":"{read_only:false; response_revision:1316; number_of_response:1; }","duration":"423.571329ms","start":"2026-05-05T20:58:55.490313Z","end":"2026-05-05T20:58:55.913884Z","steps":["trace[1721772918] 'process raft request' (duration: 423.441537ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:58:55.914014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:58:55.490272Z","time spent":"423.689821ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T20:58:55.914135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.255151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/test-pvc-pod\" ","response":"range_response_count:1 size:2475"} {"level":"warn","ts":"2026-05-05T20:58:55.914006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:58:55.295867Z","time spent":"618.086435ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T20:58:55.913856Z","caller":"traceutil/trace.go:171","msg":"trace[988843169] transaction","detail":"{read_only:false; response_revision:1315; number_of_response:1; }","duration":"617.67799ms","start":"2026-05-05T20:58:55.296155Z","end":"2026-05-05T20:58:55.913833Z","steps":["trace[988843169] 'process raft request' (duration: 617.561189ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:58:55.914213Z","caller":"traceutil/trace.go:171","msg":"trace[1141852387] range","detail":"{range_begin:/registry/pods/default/test-pvc-pod; range_end:; response_count:1; response_revision:1316; }","duration":"535.386742ms","start":"2026-05-05T20:58:55.378812Z","end":"2026-05-05T20:58:55.914199Z","steps":["trace[1141852387] 'agreement among raft nodes before linearized reading' (duration: 535.16917ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:58:55.914176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"484.865366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T20:58:55.914268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:58:55.378792Z","time spent":"535.458303ms","remote":"127.0.0.1:54956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":37,"response count":1,"response size":2499,"request content":"key:\"/registry/pods/default/test-pvc-pod\" "} {"level":"info","ts":"2026-05-05T20:58:55.914307Z","caller":"traceutil/trace.go:171","msg":"trace[1875892109] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1316; }","duration":"485.026097ms","start":"2026-05-05T20:58:55.429258Z","end":"2026-05-05T20:58:55.914284Z","steps":["trace[1875892109] 'agreement among raft nodes before linearized reading' (duration: 484.874795ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:58:55.91436Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:58:55.429233Z","time spent":"485.110488ms","remote":"127.0.0.1:39082","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":29,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-05-05T20:58:55.914377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.196275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-05T20:58:55.914385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.517569ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:58:55.914421Z","caller":"traceutil/trace.go:171","msg":"trace[316960560] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1316; }","duration":"276.283356ms","start":"2026-05-05T20:58:55.638125Z","end":"2026-05-05T20:58:55.914408Z","steps":["trace[316960560] 'agreement among raft nodes before linearized reading' (duration: 276.154135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:58:55.914427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.603201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T20:58:55.91445Z","caller":"traceutil/trace.go:171","msg":"trace[1925947480] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1316; }","duration":"256.62399ms","start":"2026-05-05T20:58:55.657812Z","end":"2026-05-05T20:58:55.914436Z","steps":["trace[1925947480] 'agreement among raft nodes before linearized reading' (duration: 256.464108ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:58:55.914469Z","caller":"traceutil/trace.go:171","msg":"trace[1229796084] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1316; }","duration":"425.673743ms","start":"2026-05-05T20:58:55.488783Z","end":"2026-05-05T20:58:55.914457Z","steps":["trace[1229796084] 'agreement among raft nodes before linearized reading' (duration: 425.596882ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:58:55.914506Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:58:55.488771Z","time spent":"425.726653ms","remote":"127.0.0.1:54876","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":16,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-05-05T20:58:55.914253Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:58:55.296147Z","time spent":"618.067944ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T20:59:02.230667Z","caller":"traceutil/trace.go:171","msg":"trace[1107817018] linearizableReadLoop","detail":"{readStateIndex:1396; appliedIndex:1395; }","duration":"207.956397ms","start":"2026-05-05T20:59:02.022687Z","end":"2026-05-05T20:59:02.230643Z","steps":["trace[1107817018] 'read index received' (duration: 20.616263ms)","trace[1107817018] 'applied index is now lower than readState.Index' (duration: 187.338824ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:59:02.230933Z","caller":"traceutil/trace.go:171","msg":"trace[1853654519] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"208.299861ms","start":"2026-05-05T20:59:02.022619Z","end":"2026-05-05T20:59:02.230919Z","steps":["trace[1853654519] 'process raft request' (duration: 204.335908ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:59:02.23113Z","caller":"traceutil/trace.go:171","msg":"trace[932465637] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"208.31054ms","start":"2026-05-05T20:59:02.022809Z","end":"2026-05-05T20:59:02.231119Z","steps":["trace[932465637] 'process raft request' (duration: 207.783605ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:02.231296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.586344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-05-05T20:59:02.231337Z","caller":"traceutil/trace.go:171","msg":"trace[274204837] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:1342; }","duration":"208.658344ms","start":"2026-05-05T20:59:02.022668Z","end":"2026-05-05T20:59:02.231326Z","steps":["trace[274204837] 'agreement among raft nodes before linearized reading' (duration: 208.573573ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:02.23149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.176486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:59:02.231526Z","caller":"traceutil/trace.go:171","msg":"trace[1377484962] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1342; }","duration":"180.238656ms","start":"2026-05-05T20:59:02.051277Z","end":"2026-05-05T20:59:02.231516Z","steps":["trace[1377484962] 'agreement among raft nodes before linearized reading' (duration: 180.190546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:04.205744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"771.412992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-05-05T20:59:04.206027Z","caller":"traceutil/trace.go:171","msg":"trace[1029678672] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1343; }","duration":"771.757236ms","start":"2026-05-05T20:59:03.434236Z","end":"2026-05-05T20:59:04.205994Z","steps":["trace[1029678672] 'range keys from in-memory index tree' (duration: 771.144389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:04.206165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:03.434212Z","time spent":"771.931438ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T20:59:04.206584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.154305348s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:59:04.206695Z","caller":"traceutil/trace.go:171","msg":"trace[919307673] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1343; }","duration":"1.154509949s","start":"2026-05-05T20:59:03.052162Z","end":"2026-05-05T20:59:04.206672Z","steps":["trace[919307673] 'range keys from in-memory index tree' (duration: 1.154175496s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:04.206742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:03.052133Z","time spent":"1.154599611s","remote":"127.0.0.1:54746","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-05-05T20:59:04.207035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.880498ms","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-05-05T20:59:04.207123Z","caller":"traceutil/trace.go:171","msg":"trace[799048641] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1343; }","duration":"280.020849ms","start":"2026-05-05T20:59:03.927082Z","end":"2026-05-05T20:59:04.207103Z","steps":["trace[799048641] 'range keys from in-memory index tree' (duration: 279.699686ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:59:04.470817Z","caller":"traceutil/trace.go:171","msg":"trace[71166775] linearizableReadLoop","detail":"{readStateIndex:1399; appliedIndex:1398; }","duration":"424.942513ms","start":"2026-05-05T20:59:04.045832Z","end":"2026-05-05T20:59:04.470774Z","steps":["trace[71166775] 'read index received' (duration: 424.790622ms)","trace[71166775] 'applied index is now lower than readState.Index' (duration: 150.161µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:59:04.470846Z","caller":"traceutil/trace.go:171","msg":"trace[618120037] transaction","detail":"{read_only:false; response_revision:1344; number_of_response:1; }","duration":"447.807789ms","start":"2026-05-05T20:59:04.022991Z","end":"2026-05-05T20:59:04.470799Z","steps":["trace[618120037] 'process raft request' (duration: 447.524696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:04.471224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:04.022966Z","time spent":"448.125013ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T20:59:04.471206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.336208ms","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-05-05T20:59:04.471573Z","caller":"traceutil/trace.go:171","msg":"trace[1840000250] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1344; }","duration":"425.773553ms","start":"2026-05-05T20:59:04.045764Z","end":"2026-05-05T20:59:04.471538Z","steps":["trace[1840000250] 'agreement among raft nodes before linearized reading' (duration: 425.196256ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:04.471662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:04.045734Z","time spent":"425.908594ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T20:59:04.94466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.371616ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T20:59:04.944935Z","caller":"traceutil/trace.go:171","msg":"trace[1477082757] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1399; }","duration":"473.995963ms","start":"2026-05-05T20:59:04.470918Z","end":"2026-05-05T20:59:04.944914Z","steps":["trace[1477082757] 'read index received' (duration: 324.422515ms)","trace[1477082757] 'applied index is now lower than readState.Index' (duration: 149.572298ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:59:04.945062Z","caller":"traceutil/trace.go:171","msg":"trace[1371948467] transaction","detail":"{read_only:false; response_revision:1345; number_of_response:1; }","duration":"733.121975ms","start":"2026-05-05T20:59:04.211926Z","end":"2026-05-05T20:59:04.945048Z","steps":["trace[1371948467] 'process raft request' (duration: 583.218244ms)","trace[1371948467] 'compare' (duration: 149.204453ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:59:04.945128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:04.211899Z","time spent":"733.193536ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T20:59:04.945142Z","caller":"traceutil/trace.go:171","msg":"trace[108756671] transaction","detail":"{read_only:false; response_revision:1346; number_of_response:1; }","duration":"731.137834ms","start":"2026-05-05T20:59:04.213949Z","end":"2026-05-05T20:59:04.945087Z","steps":["trace[108756671] 'process raft request' (duration: 730.851501ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:04.945662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"698.939678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.49\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-05T20:59:04.945715Z","caller":"traceutil/trace.go:171","msg":"trace[1009679834] range","detail":"{range_begin:/registry/masterleases/199.204.45.49; range_end:; response_count:1; response_revision:1347; }","duration":"699.006859ms","start":"2026-05-05T20:59:04.246694Z","end":"2026-05-05T20:59:04.945701Z","steps":["trace[1009679834] 'agreement among raft nodes before linearized reading' (duration: 698.353162ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:04.945722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.601944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T20:59:04.945748Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:04.246683Z","time spent":"699.05712ms","remote":"127.0.0.1:54790","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.49\" "} {"level":"warn","ts":"2026-05-05T20:59:04.945632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:04.213933Z","time spent":"731.570608ms","remote":"127.0.0.1:55078","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-05-05T20:59:04.945722Z","caller":"traceutil/trace.go:171","msg":"trace[1602024352] transaction","detail":"{read_only:false; response_revision:1347; number_of_response:1; }","duration":"469.211122ms","start":"2026-05-05T20:59:04.476453Z","end":"2026-05-05T20:59:04.945664Z","steps":["trace[1602024352] 'process raft request' (duration: 468.423293ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:59:04.945777Z","caller":"traceutil/trace.go:171","msg":"trace[1296171516] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:1347; }","duration":"333.690594ms","start":"2026-05-05T20:59:04.612063Z","end":"2026-05-05T20:59:04.945753Z","steps":["trace[1296171516] 'agreement among raft nodes before linearized reading' (duration: 333.624744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:04.945806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.306751ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"warn","ts":"2026-05-05T20:59:04.945835Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:04.612044Z","time spent":"333.782386ms","remote":"127.0.0.1:55268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-05-05T20:59:04.945871Z","caller":"traceutil/trace.go:171","msg":"trace[939926902] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1347; }","duration":"710.417542ms","start":"2026-05-05T20:59:04.235438Z","end":"2026-05-05T20:59:04.945855Z","steps":["trace[939926902] 'agreement among raft nodes before linearized reading' (duration: 709.636073ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:04.945909Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:04.23542Z","time spent":"710.483162ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T20:59:04.945979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"710.561673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-05-05T20:59:04.94563Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.692638ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T20:59:04.945982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:04.476428Z","time spent":"469.409633ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T20:59:04.946015Z","caller":"traceutil/trace.go:171","msg":"trace[1742329192] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1347; }","duration":"710.623664ms","start":"2026-05-05T20:59:04.235382Z","end":"2026-05-05T20:59:04.946006Z","steps":["trace[1742329192] 'agreement among raft nodes before linearized reading' (duration: 709.656104ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:59:04.946056Z","caller":"traceutil/trace.go:171","msg":"trace[1721197227] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1347; }","duration":"432.157242ms","start":"2026-05-05T20:59:04.513868Z","end":"2026-05-05T20:59:04.946026Z","steps":["trace[1721197227] 'agreement among raft nodes before linearized reading' (duration: 431.680617ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:04.946045Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:04.235369Z","time spent":"710.668004ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T20:59:04.946139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:04.513852Z","time spent":"432.263293ms","remote":"127.0.0.1:55030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-05T20:59:04.94599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"737.832036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:59:04.946209Z","caller":"traceutil/trace.go:171","msg":"trace[823733556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1347; }","duration":"738.066158ms","start":"2026-05-05T20:59:04.208131Z","end":"2026-05-05T20:59:04.946197Z","steps":["trace[823733556] 'agreement among raft nodes before linearized reading' (duration: 736.961497ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:04.946247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:04.208111Z","time spent":"738.125968ms","remote":"127.0.0.1:54752","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-05-05T20:59:05.32012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:04.947187Z","time spent":"372.924456ms","remote":"127.0.0.1:54790","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-05T20:59:05.341133Z","caller":"traceutil/trace.go:171","msg":"trace[866467688] transaction","detail":"{read_only:false; response_revision:1348; number_of_response:1; }","duration":"390.086619ms","start":"2026-05-05T20:59:04.951018Z","end":"2026-05-05T20:59:05.341104Z","steps":["trace[866467688] 'process raft request' (duration: 389.856677ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:59:05.341294Z","caller":"traceutil/trace.go:171","msg":"trace[2001658439] linearizableReadLoop","detail":"{readStateIndex:1405; appliedIndex:1402; }","duration":"385.730593ms","start":"2026-05-05T20:59:04.955544Z","end":"2026-05-05T20:59:05.341274Z","steps":["trace[2001658439] 'read index received' (duration: 344.610342ms)","trace[2001658439] 'applied index is now lower than readState.Index' (duration: 41.117801ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T20:59:05.341357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:04.95099Z","time spent":"390.23355ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T20:59:05.341505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.956795ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T20:59:05.34154Z","caller":"traceutil/trace.go:171","msg":"trace[1548165474] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1349; }","duration":"386.019366ms","start":"2026-05-05T20:59:04.955512Z","end":"2026-05-05T20:59:05.341531Z","steps":["trace[1548165474] 'agreement among raft nodes before linearized reading' (duration: 385.825614ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:05.341562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:04.955494Z","time spent":"386.063317ms","remote":"127.0.0.1:54746","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-05-05T20:59:05.341554Z","caller":"traceutil/trace.go:171","msg":"trace[402106604] transaction","detail":"{read_only:false; response_revision:1349; number_of_response:1; }","duration":"389.583604ms","start":"2026-05-05T20:59:04.951909Z","end":"2026-05-05T20:59:05.341492Z","steps":["trace[402106604] 'process raft request' (duration: 389.14704ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:05.341852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T20:59:04.951899Z","time spent":"389.775606ms","remote":"127.0.0.1:55078","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T20:59:23.23162Z","caller":"traceutil/trace.go:171","msg":"trace[1247604255] linearizableReadLoop","detail":"{readStateIndex:1470; appliedIndex:1469; }","duration":"111.332841ms","start":"2026-05-05T20:59:23.120261Z","end":"2026-05-05T20:59:23.231594Z","steps":["trace[1247604255] 'read index received' (duration: 111.141449ms)","trace[1247604255] 'applied index is now lower than readState.Index' (duration: 190.212µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:59:23.231656Z","caller":"traceutil/trace.go:171","msg":"trace[1141164147] transaction","detail":"{read_only:false; response_revision:1410; number_of_response:1; }","duration":"167.589948ms","start":"2026-05-05T20:59:23.064027Z","end":"2026-05-05T20:59:23.231617Z","steps":["trace[1141164147] 'process raft request' (duration: 167.406676ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:23.231834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.3024ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/envoy-gateway-system/\" range_end:\"/registry/persistentvolumeclaims/envoy-gateway-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T20:59:23.231916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.636345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-97ll4\" ","response":"range_response_count:1 size:5486"} {"level":"info","ts":"2026-05-05T20:59:23.231937Z","caller":"traceutil/trace.go:171","msg":"trace[414638715] range","detail":"{range_begin:/registry/persistentvolumeclaims/envoy-gateway-system/; range_end:/registry/persistentvolumeclaims/envoy-gateway-system0; response_count:0; response_revision:1410; }","duration":"103.458261ms","start":"2026-05-05T20:59:23.128465Z","end":"2026-05-05T20:59:23.231923Z","steps":["trace[414638715] 'agreement among raft nodes before linearized reading' (duration: 103.281429ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:59:23.232005Z","caller":"traceutil/trace.go:171","msg":"trace[107759064] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-97ll4; range_end:; response_count:1; response_revision:1410; }","duration":"111.764877ms","start":"2026-05-05T20:59:23.120219Z","end":"2026-05-05T20:59:23.231984Z","steps":["trace[107759064] 'agreement among raft nodes before linearized reading' (duration: 111.492724ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:59:37.599303Z","caller":"traceutil/trace.go:171","msg":"trace[858161799] linearizableReadLoop","detail":"{readStateIndex:1519; appliedIndex:1518; }","duration":"238.779163ms","start":"2026-05-05T20:59:37.360486Z","end":"2026-05-05T20:59:37.599265Z","steps":["trace[858161799] 'read index received' (duration: 238.500631ms)","trace[858161799] 'applied index is now lower than readState.Index' (duration: 277.242µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T20:59:37.599384Z","caller":"traceutil/trace.go:171","msg":"trace[853816835] transaction","detail":"{read_only:false; response_revision:1456; number_of_response:1; }","duration":"246.102896ms","start":"2026-05-05T20:59:37.353237Z","end":"2026-05-05T20:59:37.59934Z","steps":["trace[853816835] 'process raft request' (duration: 245.829033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:37.599608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.101406ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-05-05T20:59:37.59972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.5246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-9rfr4\" ","response":"range_response_count:1 size:18521"} {"level":"info","ts":"2026-05-05T20:59:37.599702Z","caller":"traceutil/trace.go:171","msg":"trace[1466125584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1456; }","duration":"239.242378ms","start":"2026-05-05T20:59:37.360437Z","end":"2026-05-05T20:59:37.599679Z","steps":["trace[1466125584] 'agreement among raft nodes before linearized reading' (duration: 238.973196ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T20:59:37.599778Z","caller":"traceutil/trace.go:171","msg":"trace[12143908] range","detail":"{range_begin:/registry/pods/kube-system/cilium-9rfr4; range_end:; response_count:1; response_revision:1456; }","duration":"215.655143ms","start":"2026-05-05T20:59:37.384106Z","end":"2026-05-05T20:59:37.599761Z","steps":["trace[12143908] 'agreement among raft nodes before linearized reading' (duration: 215.364649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:37.599882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.555042ms","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-05-05T20:59:37.599953Z","caller":"traceutil/trace.go:171","msg":"trace[791848848] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1456; }","duration":"209.686594ms","start":"2026-05-05T20:59:37.39025Z","end":"2026-05-05T20:59:37.599937Z","steps":["trace[791848848] 'agreement among raft nodes before linearized reading' (duration: 209.465722ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T20:59:37.600431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.915678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T20:59:37.600935Z","caller":"traceutil/trace.go:171","msg":"trace[434494203] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1456; }","duration":"153.115249ms","start":"2026-05-05T20:59:37.447789Z","end":"2026-05-05T20:59:37.600904Z","steps":["trace[434494203] 'agreement among raft nodes before linearized reading' (duration: 151.822166ms)"],"step_count":1}