{"level":"warn","ts":"2026-06-09T22:24:37.918453Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T22:24:37.918623Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.65:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.65:2380","--initial-cluster=instance=https://199.19.213.65:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.65:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.65: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-06-09T22:24:37.918748Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T22:24:37.918772Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.65:2380"]} {"level":"info","ts":"2026-06-09T22:24:37.918807Z","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-06-09T22:24:37.919842Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"]} {"level":"info","ts":"2026-06-09T22:24:37.919965Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.65:2380"],"listen-peer-urls":["https://199.19.213.65:2380"],"advertise-client-urls":["https://199.19.213.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.65: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-06-09T22:24:37.923993Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.65496ms"} {"level":"info","ts":"2026-06-09T22:24:37.93209Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a071a454a8148542","cluster-id":"aa233e43aeaf7fe5"} {"level":"info","ts":"2026-06-09T22:24:37.932242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=()"} {"level":"info","ts":"2026-06-09T22:24:37.932283Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became follower at term 0"} {"level":"info","ts":"2026-06-09T22:24:37.932326Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a071a454a8148542 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-09T22:24:37.932342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became follower at term 1"} {"level":"info","ts":"2026-06-09T22:24:37.932433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=(11561202401940899138)"} {"level":"warn","ts":"2026-06-09T22:24:37.943687Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-09T22:24:37.945526Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-09T22:24:37.961581Z","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-06-09T22:24:38.000062Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a071a454a8148542","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-09T22:24:38.000326Z","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-06-09T22:24:38.000415Z","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-06-09T22:24:38.000431Z","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-06-09T22:24:38.001823Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a071a454a8148542","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-09T22:24:38.005351Z","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-06-09T22:24:38.005579Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.65:2380"} {"level":"info","ts":"2026-06-09T22:24:38.005642Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-09T22:24:38.005647Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.65:2380"} {"level":"info","ts":"2026-06-09T22:24:38.005596Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a071a454a8148542","initial-advertise-peer-urls":["https://199.19.213.65:2380"],"listen-peer-urls":["https://199.19.213.65:2380"],"advertise-client-urls":["https://199.19.213.65:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.65:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-09T22:24:38.009157Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 switched to configuration voters=(11561202401940899138)"} {"level":"info","ts":"2026-06-09T22:24:38.009239Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"aa233e43aeaf7fe5","local-member-id":"a071a454a8148542","added-peer-id":"a071a454a8148542","added-peer-peer-urls":["https://199.19.213.65:2380"]} {"level":"info","ts":"2026-06-09T22:24:38.633652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 is starting a new election at term 1"} {"level":"info","ts":"2026-06-09T22:24:38.633725Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became pre-candidate at term 1"} {"level":"info","ts":"2026-06-09T22:24:38.633749Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 received MsgPreVoteResp from a071a454a8148542 at term 1"} {"level":"info","ts":"2026-06-09T22:24:38.633769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became candidate at term 2"} {"level":"info","ts":"2026-06-09T22:24:38.633781Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 received MsgVoteResp from a071a454a8148542 at term 2"} {"level":"info","ts":"2026-06-09T22:24:38.633798Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a071a454a8148542 became leader at term 2"} {"level":"info","ts":"2026-06-09T22:24:38.633813Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a071a454a8148542 elected leader a071a454a8148542 at term 2"} {"level":"info","ts":"2026-06-09T22:24:38.635609Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T22:24:38.636125Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a071a454a8148542","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.65:2379]}","request-path":"/0/members/a071a454a8148542/attributes","cluster-id":"aa233e43aeaf7fe5","publish-timeout":"7s"} {"level":"info","ts":"2026-06-09T22:24:38.636165Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T22:24:38.63614Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T22:24:38.63644Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-09T22:24:38.636551Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-09T22:24:38.639057Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"aa233e43aeaf7fe5","local-member-id":"a071a454a8148542","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T22:24:38.639193Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T22:24:38.639233Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T22:24:38.639898Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-09T22:24:38.64332Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.65:2379"} {"level":"info","ts":"2026-06-09T22:24:40.001616Z","caller":"traceutil/trace.go:171","msg":"trace[473038186] linearizableReadLoop","detail":"{readStateIndex:5; appliedIndex:4; }","duration":"133.443193ms","start":"2026-06-09T22:24:39.868152Z","end":"2026-06-09T22:24:40.001595Z","steps":["trace[473038186] 'read index received' (duration: 133.271549ms)","trace[473038186] 'applied index is now lower than readState.Index' (duration: 170.434µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:40.001707Z","caller":"traceutil/trace.go:171","msg":"trace[448154985] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"135.867323ms","start":"2026-06-09T22:24:39.865811Z","end":"2026-06-09T22:24:40.001679Z","steps":["trace[448154985] 'process raft request' (duration: 135.537595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:40.001975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.793611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T22:24:40.002113Z","caller":"traceutil/trace.go:171","msg":"trace[559615500] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:2; }","duration":"133.962696ms","start":"2026-06-09T22:24:39.868135Z","end":"2026-06-09T22:24:40.002097Z","steps":["trace[559615500] 'agreement among raft nodes before linearized reading' (duration: 133.675229ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.095825Z","caller":"traceutil/trace.go:171","msg":"trace[739570909] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"203.500171ms","start":"2026-06-09T22:24:39.892271Z","end":"2026-06-09T22:24:40.095771Z","steps":["trace[739570909] 'process raft request' (duration: 187.480506ms)","trace[739570909] 'compare' (duration: 15.82547ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:40.095864Z","caller":"traceutil/trace.go:171","msg":"trace[342658282] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"148.210686ms","start":"2026-06-09T22:24:39.947627Z","end":"2026-06-09T22:24:40.095838Z","steps":["trace[342658282] 'process raft request' (duration: 148.115034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:40.095992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.203811ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T22:24:40.096028Z","caller":"traceutil/trace.go:171","msg":"trace[1092728132] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:10; }","duration":"149.313834ms","start":"2026-06-09T22:24:39.946705Z","end":"2026-06-09T22:24:40.096019Z","steps":["trace[1092728132] 'agreement among raft nodes before linearized reading' (duration: 149.18225ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.096008Z","caller":"traceutil/trace.go:171","msg":"trace[934286305] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"148.509884ms","start":"2026-06-09T22:24:39.947469Z","end":"2026-06-09T22:24:40.095979Z","steps":["trace[934286305] 'process raft request' (duration: 148.239507ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.096163Z","caller":"traceutil/trace.go:171","msg":"trace[1027526744] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"148.641918ms","start":"2026-06-09T22:24:39.947503Z","end":"2026-06-09T22:24:40.096145Z","steps":["trace[1027526744] 'process raft request' (duration: 148.222386ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.096302Z","caller":"traceutil/trace.go:171","msg":"trace[1531770669] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"200.682291ms","start":"2026-06-09T22:24:39.895608Z","end":"2026-06-09T22:24:40.09629Z","steps":["trace[1531770669] 'process raft request' (duration: 200.060195ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.096436Z","caller":"traceutil/trace.go:171","msg":"trace[1459797846] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"148.780561ms","start":"2026-06-09T22:24:39.947645Z","end":"2026-06-09T22:24:40.096426Z","steps":["trace[1459797846] 'process raft request' (duration: 148.118813ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.096362Z","caller":"traceutil/trace.go:171","msg":"trace[1333720875] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"148.509264ms","start":"2026-06-09T22:24:39.947766Z","end":"2026-06-09T22:24:40.096275Z","steps":["trace[1333720875] 'process raft request' (duration: 148.011271ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:40.096319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.414057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T22:24:40.096713Z","caller":"traceutil/trace.go:171","msg":"trace[2031229805] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:10; }","duration":"226.795026ms","start":"2026-06-09T22:24:39.869886Z","end":"2026-06-09T22:24:40.096681Z","steps":["trace[2031229805] 'agreement among raft nodes before linearized reading' (duration: 226.412206ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.096628Z","caller":"traceutil/trace.go:171","msg":"trace[1779504208] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"149.230062ms","start":"2026-06-09T22:24:39.947391Z","end":"2026-06-09T22:24:40.096621Z","steps":["trace[1779504208] 'process raft request' (duration: 148.299089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:40.096597Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.239842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T22:24:40.097113Z","caller":"traceutil/trace.go:171","msg":"trace[1826041180] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"228.812326ms","start":"2026-06-09T22:24:39.868283Z","end":"2026-06-09T22:24:40.097095Z","steps":["trace[1826041180] 'agreement among raft nodes before linearized reading' (duration: 228.237552ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:40.09616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.272288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.65\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T22:24:40.09731Z","caller":"traceutil/trace.go:171","msg":"trace[1235469307] range","detail":"{range_begin:/registry/masterleases/199.19.213.65; range_end:; response_count:0; response_revision:10; }","duration":"151.354625ms","start":"2026-06-09T22:24:39.945854Z","end":"2026-06-09T22:24:40.097209Z","steps":["trace[1235469307] 'agreement among raft nodes before linearized reading' (duration: 150.291039ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:40.097361Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.650992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T22:24:40.097587Z","caller":"traceutil/trace.go:171","msg":"trace[2019887930] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:10; }","duration":"149.895168ms","start":"2026-06-09T22:24:39.947662Z","end":"2026-06-09T22:24:40.097557Z","steps":["trace[2019887930] 'agreement among raft nodes before linearized reading' (duration: 148.193566ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.343256Z","caller":"traceutil/trace.go:171","msg":"trace[1359482185] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"240.985266ms","start":"2026-06-09T22:24:40.102242Z","end":"2026-06-09T22:24:40.343227Z","steps":["trace[1359482185] 'process raft request' (duration: 240.859273ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.343309Z","caller":"traceutil/trace.go:171","msg":"trace[1750920354] linearizableReadLoop","detail":"{readStateIndex:23; appliedIndex:14; }","duration":"159.890785ms","start":"2026-06-09T22:24:40.183403Z","end":"2026-06-09T22:24:40.343294Z","steps":["trace[1750920354] 'read index received' (duration: 86.952005ms)","trace[1750920354] 'applied index is now lower than readState.Index' (duration: 72.93792ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:40.343377Z","caller":"traceutil/trace.go:171","msg":"trace[2044329824] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"239.90255ms","start":"2026-06-09T22:24:40.103454Z","end":"2026-06-09T22:24:40.343357Z","steps":["trace[2044329824] 'process raft request' (duration: 239.757977ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.343342Z","caller":"traceutil/trace.go:171","msg":"trace[573982959] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"241.500259ms","start":"2026-06-09T22:24:40.101825Z","end":"2026-06-09T22:24:40.343325Z","steps":["trace[573982959] 'process raft request' (duration: 241.251973ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.34329Z","caller":"traceutil/trace.go:171","msg":"trace[1087155916] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"241.501379ms","start":"2026-06-09T22:24:40.101768Z","end":"2026-06-09T22:24:40.343269Z","steps":["trace[1087155916] 'process raft request' (duration: 241.254103ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.343593Z","caller":"traceutil/trace.go:171","msg":"trace[931026709] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"241.295844ms","start":"2026-06-09T22:24:40.102287Z","end":"2026-06-09T22:24:40.343583Z","steps":["trace[931026709] 'process raft request' (duration: 240.843423ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:40.343682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.106185ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T22:24:40.343743Z","caller":"traceutil/trace.go:171","msg":"trace[439680117] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:19; }","duration":"242.196376ms","start":"2026-06-09T22:24:40.101532Z","end":"2026-06-09T22:24:40.343729Z","steps":["trace[439680117] 'agreement among raft nodes before linearized reading' (duration: 242.097794ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.343765Z","caller":"traceutil/trace.go:171","msg":"trace[1681145126] transaction","detail":"{read_only:false; response_revision:19; number_of_response:1; }","duration":"238.559236ms","start":"2026-06-09T22:24:40.105168Z","end":"2026-06-09T22:24:40.343727Z","steps":["trace[1681145126] 'process raft request' (duration: 238.081405ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:40.343689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.016802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"warn","ts":"2026-06-09T22:24:40.343874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.728013ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:350"} {"level":"info","ts":"2026-06-09T22:24:40.343944Z","caller":"traceutil/trace.go:171","msg":"trace[1289166689] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:19; }","duration":"185.820544ms","start":"2026-06-09T22:24:40.158112Z","end":"2026-06-09T22:24:40.343932Z","steps":["trace[1289166689] 'agreement among raft nodes before linearized reading' (duration: 185.732202ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.343989Z","caller":"traceutil/trace.go:171","msg":"trace[1428475740] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"240.902305ms","start":"2026-06-09T22:24:40.103075Z","end":"2026-06-09T22:24:40.343977Z","steps":["trace[1428475740] 'process raft request' (duration: 240.100895ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.343904Z","caller":"traceutil/trace.go:171","msg":"trace[989774060] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:19; }","duration":"242.626907ms","start":"2026-06-09T22:24:40.101264Z","end":"2026-06-09T22:24:40.343891Z","steps":["trace[989774060] 'agreement among raft nodes before linearized reading' (duration: 242.183397ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:40.344003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.256127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/csr-dm227\" ","response":"range_response_count:1 size:861"} {"level":"info","ts":"2026-06-09T22:24:40.343939Z","caller":"traceutil/trace.go:171","msg":"trace[166560958] transaction","detail":"{read_only:false; number_of_response:0; response_revision:16; }","duration":"241.16488ms","start":"2026-06-09T22:24:40.102758Z","end":"2026-06-09T22:24:40.343923Z","steps":["trace[166560958] 'process raft request' (duration: 240.394341ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.344115Z","caller":"traceutil/trace.go:171","msg":"trace[1845343884] range","detail":"{range_begin:/registry/certificatesigningrequests/csr-dm227; range_end:; response_count:1; response_revision:19; }","duration":"242.388982ms","start":"2026-06-09T22:24:40.101714Z","end":"2026-06-09T22:24:40.344103Z","steps":["trace[1845343884] 'agreement among raft nodes before linearized reading' (duration: 242.250158ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.343249Z","caller":"traceutil/trace.go:171","msg":"trace[714172534] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"241.92446ms","start":"2026-06-09T22:24:40.101296Z","end":"2026-06-09T22:24:40.34322Z","steps":["trace[714172534] 'process raft request' (duration: 169.065202ms)","trace[714172534] 'compare' (duration: 72.561411ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:40.509617Z","caller":"traceutil/trace.go:171","msg":"trace[1837855805] transaction","detail":"{read_only:false; response_revision:21; number_of_response:1; }","duration":"157.787332ms","start":"2026-06-09T22:24:40.351802Z","end":"2026-06-09T22:24:40.509589Z","steps":["trace[1837855805] 'process raft request' (duration: 118.418631ms)","trace[1837855805] 'compare' (duration: 39.034403ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:40.509694Z","caller":"traceutil/trace.go:171","msg":"trace[1563177240] transaction","detail":"{read_only:false; response_revision:22; number_of_response:1; }","duration":"157.600888ms","start":"2026-06-09T22:24:40.352057Z","end":"2026-06-09T22:24:40.509658Z","steps":["trace[1563177240] 'process raft request' (duration: 157.459275ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.509787Z","caller":"traceutil/trace.go:171","msg":"trace[2053906876] linearizableReadLoop","detail":"{readStateIndex:25; appliedIndex:23; }","duration":"157.732711ms","start":"2026-06-09T22:24:40.352039Z","end":"2026-06-09T22:24:40.509772Z","steps":["trace[2053906876] 'read index received' (duration: 46.477446ms)","trace[2053906876] 'applied index is now lower than readState.Index' (duration: 111.254135ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:40.510058Z","caller":"traceutil/trace.go:171","msg":"trace[140325055] transaction","detail":"{read_only:false; response_revision:27; number_of_response:1; }","duration":"157.497265ms","start":"2026-06-09T22:24:40.35254Z","end":"2026-06-09T22:24:40.510038Z","steps":["trace[140325055] 'process raft request' (duration: 157.174667ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.510082Z","caller":"traceutil/trace.go:171","msg":"trace[394229405] transaction","detail":"{read_only:false; response_revision:24; number_of_response:1; }","duration":"157.940746ms","start":"2026-06-09T22:24:40.35213Z","end":"2026-06-09T22:24:40.510071Z","steps":["trace[394229405] 'process raft request' (duration: 157.501955ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.510209Z","caller":"traceutil/trace.go:171","msg":"trace[1554486468] transaction","detail":"{read_only:false; response_revision:25; number_of_response:1; }","duration":"157.988287ms","start":"2026-06-09T22:24:40.35221Z","end":"2026-06-09T22:24:40.510198Z","steps":["trace[1554486468] 'process raft request' (duration: 157.455664ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.510224Z","caller":"traceutil/trace.go:171","msg":"trace[426160738] transaction","detail":"{read_only:false; response_revision:28; number_of_response:1; }","duration":"157.067945ms","start":"2026-06-09T22:24:40.353139Z","end":"2026-06-09T22:24:40.510207Z","steps":["trace[426160738] 'process raft request' (duration: 156.601144ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.510255Z","caller":"traceutil/trace.go:171","msg":"trace[183314732] transaction","detail":"{read_only:false; response_revision:26; number_of_response:1; }","duration":"157.904365ms","start":"2026-06-09T22:24:40.352337Z","end":"2026-06-09T22:24:40.510242Z","steps":["trace[183314732] 'process raft request' (duration: 157.355462ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.510339Z","caller":"traceutil/trace.go:171","msg":"trace[1460787258] transaction","detail":"{read_only:false; response_revision:23; number_of_response:1; }","duration":"158.210073ms","start":"2026-06-09T22:24:40.352118Z","end":"2026-06-09T22:24:40.510328Z","steps":["trace[1460787258] 'process raft request' (duration: 157.475236ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:40.510459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.400798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T22:24:40.510496Z","caller":"traceutil/trace.go:171","msg":"trace[2063883520] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:28; }","duration":"158.465819ms","start":"2026-06-09T22:24:40.352019Z","end":"2026-06-09T22:24:40.510484Z","steps":["trace[2063883520] 'agreement among raft nodes before linearized reading' (duration: 158.291995ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:40.510521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.241893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-public/\" range_end:\"/registry/resourcequotas/kube-public0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T22:24:40.51057Z","caller":"traceutil/trace.go:171","msg":"trace[1978238962] range","detail":"{range_begin:/registry/resourcequotas/kube-public/; range_end:/registry/resourcequotas/kube-public0; response_count:0; response_revision:28; }","duration":"158.304336ms","start":"2026-06-09T22:24:40.352241Z","end":"2026-06-09T22:24:40.510545Z","steps":["trace[1978238962] 'agreement among raft nodes before linearized reading' (duration: 158.231754ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:40.510667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.472615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-node-lease\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T22:24:40.510716Z","caller":"traceutil/trace.go:171","msg":"trace[1731953896] range","detail":"{range_begin:/registry/namespaces/kube-node-lease; range_end:; response_count:0; response_revision:28; }","duration":"110.557567ms","start":"2026-06-09T22:24:40.400144Z","end":"2026-06-09T22:24:40.510702Z","steps":["trace[1731953896] 'agreement among raft nodes before linearized reading' (duration: 110.467015ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:40.933081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.022127ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-06-09T22:24:40.933285Z","caller":"traceutil/trace.go:171","msg":"trace[368610649] linearizableReadLoop","detail":"{readStateIndex:55; appliedIndex:53; }","duration":"140.458276ms","start":"2026-06-09T22:24:40.792811Z","end":"2026-06-09T22:24:40.933269Z","steps":["trace[368610649] 'read index received' (duration: 63.395495ms)","trace[368610649] 'applied index is now lower than readState.Index' (duration: 77.061941ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:40.933369Z","caller":"traceutil/trace.go:171","msg":"trace[1734473989] transaction","detail":"{read_only:false; response_revision:50; number_of_response:1; }","duration":"202.060216ms","start":"2026-06-09T22:24:40.731298Z","end":"2026-06-09T22:24:40.933359Z","steps":["trace[1734473989] 'process raft request' (duration: 52.26566ms)","trace[1734473989] 'compare' (duration: 148.891924ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:24:40.933567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.746932ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T22:24:40.933604Z","caller":"traceutil/trace.go:171","msg":"trace[758408348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:51; }","duration":"140.805113ms","start":"2026-06-09T22:24:40.79279Z","end":"2026-06-09T22:24:40.933595Z","steps":["trace[758408348] 'agreement among raft nodes before linearized reading' (duration: 140.66784ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:40.933568Z","caller":"traceutil/trace.go:171","msg":"trace[1421862596] transaction","detail":"{read_only:false; response_revision:51; number_of_response:1; }","duration":"180.233267ms","start":"2026-06-09T22:24:40.753286Z","end":"2026-06-09T22:24:40.933519Z","steps":["trace[1421862596] 'process raft request' (duration: 179.923679ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:40.933732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.094526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T22:24:40.933861Z","caller":"traceutil/trace.go:171","msg":"trace[1504762722] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:51; }","duration":"140.264691ms","start":"2026-06-09T22:24:40.793572Z","end":"2026-06-09T22:24:40.933837Z","steps":["trace[1504762722] 'agreement among raft nodes before linearized reading' (duration: 140.009354ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:41.079035Z","caller":"traceutil/trace.go:171","msg":"trace[1704527943] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"141.815088ms","start":"2026-06-09T22:24:40.937159Z","end":"2026-06-09T22:24:41.078974Z","steps":["trace[1704527943] 'process raft request' (duration: 108.646151ms)","trace[1704527943] 'compare' (duration: 33.040614ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:41.079285Z","caller":"traceutil/trace.go:171","msg":"trace[1894348130] linearizableReadLoop","detail":"{readStateIndex:58; appliedIndex:56; }","duration":"141.4891ms","start":"2026-06-09T22:24:40.937769Z","end":"2026-06-09T22:24:41.079258Z","steps":["trace[1894348130] 'read index received' (duration: 21.733886ms)","trace[1894348130] 'applied index is now lower than readState.Index' (duration: 119.754334ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:41.079347Z","caller":"traceutil/trace.go:171","msg":"trace[1911852366] transaction","detail":"{read_only:false; response_revision:54; number_of_response:1; }","duration":"141.689925ms","start":"2026-06-09T22:24:40.937647Z","end":"2026-06-09T22:24:41.079337Z","steps":["trace[1911852366] 'process raft request' (duration: 141.394478ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:41.079409Z","caller":"traceutil/trace.go:171","msg":"trace[460159260] transaction","detail":"{read_only:false; response_revision:56; number_of_response:1; }","duration":"140.925957ms","start":"2026-06-09T22:24:40.93843Z","end":"2026-06-09T22:24:41.079356Z","steps":["trace[460159260] 'process raft request' (duration: 140.775513ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:41.079483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.123955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-cluster-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T22:24:41.079625Z","caller":"traceutil/trace.go:171","msg":"trace[223114256] range","detail":"{range_begin:/registry/priorityclasses/system-cluster-critical; range_end:; response_count:0; response_revision:56; }","duration":"118.30452ms","start":"2026-06-09T22:24:40.961312Z","end":"2026-06-09T22:24:41.079616Z","steps":["trace[223114256] 'agreement among raft nodes before linearized reading' (duration: 118.118625ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:41.079438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.646275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-06-09T22:24:41.079754Z","caller":"traceutil/trace.go:171","msg":"trace[1946774375] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:56; }","duration":"141.969662ms","start":"2026-06-09T22:24:40.93775Z","end":"2026-06-09T22:24:41.079719Z","steps":["trace[1946774375] 'agreement among raft nodes before linearized reading' (duration: 141.641593ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:41.079299Z","caller":"traceutil/trace.go:171","msg":"trace[618562105] transaction","detail":"{read_only:false; response_revision:55; number_of_response:1; }","duration":"141.340477ms","start":"2026-06-09T22:24:40.937937Z","end":"2026-06-09T22:24:41.079278Z","steps":["trace[618562105] 'process raft request' (duration: 141.237124ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:41.258038Z","caller":"traceutil/trace.go:171","msg":"trace[2088765763] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"145.442509ms","start":"2026-06-09T22:24:41.112574Z","end":"2026-06-09T22:24:41.258017Z","steps":["trace[2088765763] 'process raft request' (duration: 126.412759ms)","trace[2088765763] 'compare' (duration: 18.810855ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:41.258189Z","caller":"traceutil/trace.go:171","msg":"trace[1856826818] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"145.561051ms","start":"2026-06-09T22:24:41.112597Z","end":"2026-06-09T22:24:41.258158Z","steps":["trace[1856826818] 'process raft request' (duration: 145.328136ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:41.258512Z","caller":"traceutil/trace.go:171","msg":"trace[787430902] transaction","detail":"{read_only:false; response_revision:64; number_of_response:1; }","duration":"143.604762ms","start":"2026-06-09T22:24:41.114883Z","end":"2026-06-09T22:24:41.258488Z","steps":["trace[787430902] 'process raft request' (duration: 143.271474ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:41.258489Z","caller":"traceutil/trace.go:171","msg":"trace[1792954346] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"144.932515ms","start":"2026-06-09T22:24:41.113533Z","end":"2026-06-09T22:24:41.258466Z","steps":["trace[1792954346] 'process raft request' (duration: 144.442243ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:24:41.796529Z","caller":"traceutil/trace.go:171","msg":"trace[1059294303] transaction","detail":"{read_only:false; response_revision:131; number_of_response:1; }","duration":"112.559336ms","start":"2026-06-09T22:24:41.683941Z","end":"2026-06-09T22:24:41.796501Z","steps":["trace[1059294303] 'process raft request' (duration: 25.332875ms)","trace[1059294303] 'compare' (duration: 87.090728ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:56.47462Z","caller":"traceutil/trace.go:171","msg":"trace[321351121] linearizableReadLoop","detail":"{readStateIndex:432; appliedIndex:431; }","duration":"100.841778ms","start":"2026-06-09T22:24:56.373754Z","end":"2026-06-09T22:24:56.474596Z","steps":["trace[321351121] 'read index received' (duration: 100.607003ms)","trace[321351121] 'applied index is now lower than readState.Index' (duration: 233.905µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:24:56.474712Z","caller":"traceutil/trace.go:171","msg":"trace[1570179804] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"119.303373ms","start":"2026-06-09T22:24:56.355358Z","end":"2026-06-09T22:24:56.474662Z","steps":["trace[1570179804] 'process raft request' (duration: 119.078537ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:24:56.474804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.030443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T22:24:56.474884Z","caller":"traceutil/trace.go:171","msg":"trace[833315518] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:418; }","duration":"101.153906ms","start":"2026-06-09T22:24:56.373711Z","end":"2026-06-09T22:24:56.474865Z","steps":["trace[833315518] 'agreement among raft nodes before linearized reading' (duration: 100.982752ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:00.621353Z","caller":"traceutil/trace.go:171","msg":"trace[1534377484] transaction","detail":"{read_only:false; response_revision:428; number_of_response:1; }","duration":"108.491536ms","start":"2026-06-09T22:25:00.512842Z","end":"2026-06-09T22:25:00.621334Z","steps":["trace[1534377484] 'process raft request' (duration: 108.370083ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:05.54597Z","caller":"traceutil/trace.go:171","msg":"trace[670469237] linearizableReadLoop","detail":"{readStateIndex:489; appliedIndex:488; }","duration":"179.093779ms","start":"2026-06-09T22:25:05.366849Z","end":"2026-06-09T22:25:05.545943Z","steps":["trace[670469237] 'read index received' (duration: 178.999207ms)","trace[670469237] 'applied index is now lower than readState.Index' (duration: 93.332µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:25:05.546079Z","caller":"traceutil/trace.go:171","msg":"trace[154371284] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"181.56032ms","start":"2026-06-09T22:25:05.364488Z","end":"2026-06-09T22:25:05.546048Z","steps":["trace[154371284] 'process raft request' (duration: 181.282533ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:05.546175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.297764ms","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-06-09T22:25:05.546266Z","caller":"traceutil/trace.go:171","msg":"trace[486054446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:471; }","duration":"179.426648ms","start":"2026-06-09T22:25:05.366819Z","end":"2026-06-09T22:25:05.546245Z","steps":["trace[486054446] 'agreement among raft nodes before linearized reading' (duration: 179.261964ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:15.895602Z","caller":"traceutil/trace.go:171","msg":"trace[1339903883] linearizableReadLoop","detail":"{readStateIndex:548; appliedIndex:547; }","duration":"275.943779ms","start":"2026-06-09T22:25:15.619635Z","end":"2026-06-09T22:25:15.895578Z","steps":["trace[1339903883] 'read index received' (duration: 275.736844ms)","trace[1339903883] 'applied index is now lower than readState.Index' (duration: 206.075µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:25:15.895732Z","caller":"traceutil/trace.go:171","msg":"trace[1078452813] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"292.311083ms","start":"2026-06-09T22:25:15.603406Z","end":"2026-06-09T22:25:15.895717Z","steps":["trace[1078452813] 'process raft request' (duration: 292.036286ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:15.89603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.368079ms","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-06-09T22:25:15.896134Z","caller":"traceutil/trace.go:171","msg":"trace[2116097982] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:528; }","duration":"276.527234ms","start":"2026-06-09T22:25:15.619586Z","end":"2026-06-09T22:25:15.896113Z","steps":["trace[2116097982] 'agreement among raft nodes before linearized reading' (duration: 276.303588ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:18.118435Z","caller":"traceutil/trace.go:171","msg":"trace[1506778578] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"211.635581ms","start":"2026-06-09T22:25:17.906773Z","end":"2026-06-09T22:25:18.118408Z","steps":["trace[1506778578] 'read index received' (duration: 211.492348ms)","trace[1506778578] 'applied index is now lower than readState.Index' (duration: 142.093µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:25:18.118489Z","caller":"traceutil/trace.go:171","msg":"trace[1681820704] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"215.029495ms","start":"2026-06-09T22:25:17.903425Z","end":"2026-06-09T22:25:18.118455Z","steps":["trace[1681820704] 'process raft request' (duration: 214.81267ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:18.118604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.808145ms","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-06-09T22:25:18.118645Z","caller":"traceutil/trace.go:171","msg":"trace[220858197] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"211.896318ms","start":"2026-06-09T22:25:17.906735Z","end":"2026-06-09T22:25:18.118632Z","steps":["trace[220858197] 'agreement among raft nodes before linearized reading' (duration: 211.811296ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:18.118667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.556465ms","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-06-09T22:25:18.118726Z","caller":"traceutil/trace.go:171","msg":"trace[1480919056] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:607; }","duration":"210.651187ms","start":"2026-06-09T22:25:17.908062Z","end":"2026-06-09T22:25:18.118713Z","steps":["trace[1480919056] 'agreement among raft nodes before linearized reading' (duration: 210.522624ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:22.001504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.898561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-gs574\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-06-09T22:25:22.001577Z","caller":"traceutil/trace.go:171","msg":"trace[1314111879] range","detail":"{range_begin:/registry/pods/kube-system/cilium-gs574; range_end:; response_count:1; response_revision:630; }","duration":"157.009944ms","start":"2026-06-09T22:25:21.844551Z","end":"2026-06-09T22:25:22.001561Z","steps":["trace[1314111879] 'range keys from in-memory index tree' (duration: 156.723567ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:22.608931Z","caller":"traceutil/trace.go:171","msg":"trace[531705242] linearizableReadLoop","detail":"{readStateIndex:654; appliedIndex:653; }","duration":"469.653057ms","start":"2026-06-09T22:25:22.139235Z","end":"2026-06-09T22:25:22.608888Z","steps":["trace[531705242] 'read index received' (duration: 453.645472ms)","trace[531705242] 'applied index is now lower than readState.Index' (duration: 16.006445ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:25:22.609287Z","caller":"traceutil/trace.go:171","msg":"trace[678271580] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"491.983688ms","start":"2026-06-09T22:25:22.117271Z","end":"2026-06-09T22:25:22.609255Z","steps":["trace[678271580] 'process raft request' (duration: 475.776069ms)","trace[678271580] 'compare' (duration: 15.054711ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:25:22.609326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"470.084827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-09T22:25:22.609388Z","caller":"traceutil/trace.go:171","msg":"trace[268821633] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"470.181671ms","start":"2026-06-09T22:25:22.139191Z","end":"2026-06-09T22:25:22.609372Z","steps":["trace[268821633] 'agreement among raft nodes before linearized reading' (duration: 470.024827ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:22.609427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:25:22.139173Z","time spent":"470.247222ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T22:25:22.609581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.207731ms","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-06-09T22:25:22.609621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.26296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.65\" ","response":"range_response_count:1 size:133"} {"level":"warn","ts":"2026-06-09T22:25:22.60964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:25:22.117247Z","time spent":"492.104422ms","remote":"127.0.0.1:45452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036265,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:25:22.609655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.160036ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-09T22:25:22.609651Z","caller":"traceutil/trace.go:171","msg":"trace[526443850] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:632; }","duration":"455.302793ms","start":"2026-06-09T22:25:22.154324Z","end":"2026-06-09T22:25:22.609627Z","steps":["trace[526443850] 'agreement among raft nodes before linearized reading' (duration: 455.112599ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:22.609691Z","caller":"traceutil/trace.go:171","msg":"trace[145586464] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"454.220727ms","start":"2026-06-09T22:25:22.15546Z","end":"2026-06-09T22:25:22.60968Z","steps":["trace[145586464] 'agreement among raft nodes before linearized reading' (duration: 454.089823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:22.609701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.97981ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T22:25:22.609705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:25:22.154302Z","time spent":"455.391815ms","remote":"127.0.0.1:45638","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-06-09T22:25:22.60972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:25:22.155453Z","time spent":"454.262327ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T22:25:22.609719Z","caller":"traceutil/trace.go:171","msg":"trace[740132121] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"244.040621ms","start":"2026-06-09T22:25:22.365673Z","end":"2026-06-09T22:25:22.609714Z","steps":["trace[740132121] 'agreement among raft nodes before linearized reading' (duration: 244.012181ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:22.609669Z","caller":"traceutil/trace.go:171","msg":"trace[1597691388] range","detail":"{range_begin:/registry/masterleases/199.19.213.65; range_end:; response_count:1; response_revision:632; }","duration":"418.347952ms","start":"2026-06-09T22:25:22.191305Z","end":"2026-06-09T22:25:22.609653Z","steps":["trace[1597691388] 'agreement among raft nodes before linearized reading' (duration: 418.239759ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:22.609807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:25:22.191278Z","time spent":"418.521095ms","remote":"127.0.0.1:45394","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.65\" "} {"level":"info","ts":"2026-06-09T22:25:22.900632Z","caller":"traceutil/trace.go:171","msg":"trace[1739657466] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"287.989035ms","start":"2026-06-09T22:25:22.612599Z","end":"2026-06-09T22:25:22.900588Z","steps":["trace[1739657466] 'process raft request' (duration: 287.77723ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:22.900854Z","caller":"traceutil/trace.go:171","msg":"trace[840703461] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"285.969685ms","start":"2026-06-09T22:25:22.614867Z","end":"2026-06-09T22:25:22.900837Z","steps":["trace[840703461] 'process raft request' (duration: 285.650907ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:22.900882Z","caller":"traceutil/trace.go:171","msg":"trace[2059117181] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:654; }","duration":"154.839849ms","start":"2026-06-09T22:25:22.746016Z","end":"2026-06-09T22:25:22.900856Z","steps":["trace[2059117181] 'read index received' (duration: 72.190381ms)","trace[2059117181] 'applied index is now lower than readState.Index' (duration: 82.648598ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:25:22.900996Z","caller":"traceutil/trace.go:171","msg":"trace[1762564594] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"285.697979ms","start":"2026-06-09T22:25:22.615288Z","end":"2026-06-09T22:25:22.900986Z","steps":["trace[1762564594] 'process raft request' (duration: 285.419702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:22.901119Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.079596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T22:25:22.901157Z","caller":"traceutil/trace.go:171","msg":"trace[443180053] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:636; }","duration":"155.155098ms","start":"2026-06-09T22:25:22.74599Z","end":"2026-06-09T22:25:22.901145Z","steps":["trace[443180053] 'agreement among raft nodes before linearized reading' (duration: 154.961763ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:22.901234Z","caller":"traceutil/trace.go:171","msg":"trace[635954902] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"231.742048ms","start":"2026-06-09T22:25:22.669482Z","end":"2026-06-09T22:25:22.901224Z","steps":["trace[635954902] 'process raft request' (duration: 231.332137ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:23.864123Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411827763360118,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T22:25:24.364805Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411827763360118,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T22:25:24.484694Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.178696761s","expected-duration":"1s"} {"level":"info","ts":"2026-06-09T22:25:24.492191Z","caller":"traceutil/trace.go:171","msg":"trace[610118211] linearizableReadLoop","detail":"{readStateIndex:662; appliedIndex:661; }","duration":"1.128631915s","start":"2026-06-09T22:25:23.363527Z","end":"2026-06-09T22:25:24.492159Z","steps":["trace[610118211] 'read index received' (duration: 1.121327196s)","trace[610118211] 'applied index is now lower than readState.Index' (duration: 7.303759ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:25:24.492255Z","caller":"traceutil/trace.go:171","msg":"trace[611414776] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"1.188682018s","start":"2026-06-09T22:25:23.30355Z","end":"2026-06-09T22:25:24.492232Z","steps":["trace[611414776] 'process raft request' (duration: 1.181485521s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:24.492295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.128755208s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:25:24.492333Z","caller":"traceutil/trace.go:171","msg":"trace[859630590] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:639; }","duration":"1.12882179s","start":"2026-06-09T22:25:23.363499Z","end":"2026-06-09T22:25:24.492321Z","steps":["trace[859630590] 'agreement among raft nodes before linearized reading' (duration: 1.128737948s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:24.492366Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:25:23.363486Z","time spent":"1.128873162s","remote":"127.0.0.1:45360","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-06-09T22:25:24.492367Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:25:23.303507Z","time spent":"1.188803361s","remote":"127.0.0.1:45452","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036293,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:25:26.264924Z","caller":"traceutil/trace.go:171","msg":"trace[1954104481] transaction","detail":"{read_only:false; response_revision:678; number_of_response:1; }","duration":"199.487322ms","start":"2026-06-09T22:25:26.065387Z","end":"2026-06-09T22:25:26.264875Z","steps":["trace[1954104481] 'process raft request' (duration: 199.338518ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:33.161177Z","caller":"traceutil/trace.go:171","msg":"trace[11555125] transaction","detail":"{read_only:false; response_revision:754; number_of_response:1; }","duration":"190.019359ms","start":"2026-06-09T22:25:32.971093Z","end":"2026-06-09T22:25:33.161112Z","steps":["trace[11555125] 'process raft request' (duration: 107.494893ms)","trace[11555125] 'compare' (duration: 82.356192ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:25:33.161254Z","caller":"traceutil/trace.go:171","msg":"trace[1261512322] linearizableReadLoop","detail":"{readStateIndex:780; appliedIndex:779; }","duration":"151.899427ms","start":"2026-06-09T22:25:33.009306Z","end":"2026-06-09T22:25:33.161205Z","steps":["trace[1261512322] 'read index received' (duration: 69.282039ms)","trace[1261512322] 'applied index is now lower than readState.Index' (duration: 82.609298ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:25:33.161202Z","caller":"traceutil/trace.go:171","msg":"trace[113230688] transaction","detail":"{read_only:false; response_revision:755; number_of_response:1; }","duration":"147.200293ms","start":"2026-06-09T22:25:33.013985Z","end":"2026-06-09T22:25:33.161186Z","steps":["trace[113230688] 'process raft request' (duration: 147.09802ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:33.161624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.755348ms","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-06-09T22:25:33.161696Z","caller":"traceutil/trace.go:171","msg":"trace[952457348] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:755; }","duration":"199.86662ms","start":"2026-06-09T22:25:32.961808Z","end":"2026-06-09T22:25:33.161674Z","steps":["trace[952457348] 'agreement among raft nodes before linearized reading' (duration: 199.541162ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:33.162001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.620181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T22:25:33.162141Z","caller":"traceutil/trace.go:171","msg":"trace[2075845816] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:755; }","duration":"196.892257ms","start":"2026-06-09T22:25:32.965222Z","end":"2026-06-09T22:25:33.162114Z","steps":["trace[2075845816] 'agreement among raft nodes before linearized reading' (duration: 196.103438ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:39.414938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.657634ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-06-09T22:25:39.415004Z","caller":"traceutil/trace.go:171","msg":"trace[595657638] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:798; }","duration":"235.764377ms","start":"2026-06-09T22:25:39.179222Z","end":"2026-06-09T22:25:39.414987Z","steps":["trace[595657638] 'range keys from in-memory index tree' (duration: 235.46444ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:39.415038Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.439667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-09T22:25:39.415039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.255763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ntdc7\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-06-09T22:25:39.415095Z","caller":"traceutil/trace.go:171","msg":"trace[1421508614] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:798; }","duration":"134.536529ms","start":"2026-06-09T22:25:39.28054Z","end":"2026-06-09T22:25:39.415077Z","steps":["trace[1421508614] 'range keys from in-memory index tree' (duration: 134.287673ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:39.415095Z","caller":"traceutil/trace.go:171","msg":"trace[2032100787] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-ntdc7; range_end:; response_count:1; response_revision:798; }","duration":"468.317994ms","start":"2026-06-09T22:25:38.946763Z","end":"2026-06-09T22:25:39.415081Z","steps":["trace[2032100787] 'range keys from in-memory index tree' (duration: 468.13634ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:39.415131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:25:38.946721Z","time spent":"468.401686ms","remote":"127.0.0.1:45548","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-ntdc7\" "} {"level":"warn","ts":"2026-06-09T22:25:39.415193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.249245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-09T22:25:39.415219Z","caller":"traceutil/trace.go:171","msg":"trace[1211430960] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:798; }","duration":"385.308806ms","start":"2026-06-09T22:25:39.029902Z","end":"2026-06-09T22:25:39.415211Z","steps":["trace[1211430960] 'range keys from in-memory index tree' (duration: 385.04894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:25:39.41524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:25:39.029888Z","time spent":"385.346277ms","remote":"127.0.0.1:45638","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-06-09T22:25:39.415246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.612085ms","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-06-09T22:25:39.415316Z","caller":"traceutil/trace.go:171","msg":"trace[1413405088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:798; }","duration":"133.688887ms","start":"2026-06-09T22:25:39.281609Z","end":"2026-06-09T22:25:39.415298Z","steps":["trace[1413405088] 'range keys from in-memory index tree' (duration: 133.447332ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:39.654622Z","caller":"traceutil/trace.go:171","msg":"trace[1437061911] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"231.650386ms","start":"2026-06-09T22:25:39.422934Z","end":"2026-06-09T22:25:39.654584Z","steps":["trace[1437061911] 'process raft request' (duration: 231.602615ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:39.654768Z","caller":"traceutil/trace.go:171","msg":"trace[1075868396] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"234.731011ms","start":"2026-06-09T22:25:39.42Z","end":"2026-06-09T22:25:39.654731Z","steps":["trace[1075868396] 'process raft request' (duration: 234.462775ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:39.654844Z","caller":"traceutil/trace.go:171","msg":"trace[873140970] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"234.761892ms","start":"2026-06-09T22:25:39.420038Z","end":"2026-06-09T22:25:39.6548Z","steps":["trace[873140970] 'process raft request' (duration: 234.462895ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:25:39.655012Z","caller":"traceutil/trace.go:171","msg":"trace[1921956081] transaction","detail":"{read_only:false; response_revision:800; number_of_response:1; }","duration":"235.294396ms","start":"2026-06-09T22:25:39.419689Z","end":"2026-06-09T22:25:39.654983Z","steps":["trace[1921956081] 'process raft request' (duration: 234.63524ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:15.746281Z","caller":"traceutil/trace.go:171","msg":"trace[472336571] transaction","detail":"{read_only:false; response_revision:1017; number_of_response:1; }","duration":"378.738603ms","start":"2026-06-09T22:26:15.36752Z","end":"2026-06-09T22:26:15.746259Z","steps":["trace[472336571] 'process raft request' (duration: 378.180269ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:15.746418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:15.3675Z","time spent":"378.855536ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:26:16.196401Z","caller":"traceutil/trace.go:171","msg":"trace[1603472396] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"373.116983ms","start":"2026-06-09T22:26:15.82326Z","end":"2026-06-09T22:26:16.196377Z","steps":["trace[1603472396] 'process raft request' (duration: 326.873308ms)","trace[1603472396] 'compare' (duration: 46.00139ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:26:16.196487Z","caller":"traceutil/trace.go:171","msg":"trace[107841777] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"372.802847ms","start":"2026-06-09T22:26:15.823663Z","end":"2026-06-09T22:26:16.196466Z","steps":["trace[107841777] 'process raft request' (duration: 372.650023ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:16.196532Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:15.823232Z","time spent":"373.234937ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:26:16.196625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:15.823642Z","time spent":"372.917309ms","remote":"127.0.0.1:45638","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-06-09T22:26:16.196651Z","caller":"traceutil/trace.go:171","msg":"trace[1618245949] linearizableReadLoop","detail":"{readStateIndex:1056; appliedIndex:1054; }","duration":"372.766896ms","start":"2026-06-09T22:26:15.823865Z","end":"2026-06-09T22:26:16.196632Z","steps":["trace[1618245949] 'read index received' (duration: 326.318986ms)","trace[1618245949] 'applied index is now lower than readState.Index' (duration: 46.44657ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:26:16.196838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.728872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-09T22:26:16.196875Z","caller":"traceutil/trace.go:171","msg":"trace[915718241] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1021; }","duration":"250.801883ms","start":"2026-06-09T22:26:15.946064Z","end":"2026-06-09T22:26:16.196866Z","steps":["trace[915718241] 'agreement among raft nodes before linearized reading' (duration: 250.692681ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:16.19687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.003561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T22:26:16.196919Z","caller":"traceutil/trace.go:171","msg":"trace[1633681626] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1021; }","duration":"373.092243ms","start":"2026-06-09T22:26:15.823812Z","end":"2026-06-09T22:26:16.196905Z","steps":["trace[1633681626] 'agreement among raft nodes before linearized reading' (duration: 372.929029ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:16.19697Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:15.823792Z","time spent":"373.166735ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T22:26:37.859487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.837421ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-06-09T22:26:38.297599Z","caller":"traceutil/trace.go:171","msg":"trace[624256903] transaction","detail":"{read_only:false; response_revision:1090; number_of_response:1; }","duration":"314.527043ms","start":"2026-06-09T22:26:37.983032Z","end":"2026-06-09T22:26:38.297559Z","steps":["trace[624256903] 'process raft request' (duration: 314.243395ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:38.297955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:37.982997Z","time spent":"314.841081ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:26:38.480092Z","caller":"traceutil/trace.go:171","msg":"trace[1824345632] transaction","detail":"{read_only:false; response_revision:1092; number_of_response:1; }","duration":"141.534056ms","start":"2026-06-09T22:26:38.338255Z","end":"2026-06-09T22:26:38.479789Z","steps":["trace[1824345632] 'process raft request' (duration: 141.267159ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:38.523838Z","caller":"traceutil/trace.go:171","msg":"trace[1226488466] linearizableReadLoop","detail":"{readStateIndex:1134; appliedIndex:1132; }","duration":"181.496916ms","start":"2026-06-09T22:26:38.342306Z","end":"2026-06-09T22:26:38.523803Z","steps":["trace[1226488466] 'read index received' (duration: 137.225658ms)","trace[1226488466] 'applied index is now lower than readState.Index' (duration: 44.269848ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:26:38.524Z","caller":"traceutil/trace.go:171","msg":"trace[1708301203] transaction","detail":"{read_only:false; response_revision:1093; number_of_response:1; }","duration":"185.720771ms","start":"2026-06-09T22:26:38.338266Z","end":"2026-06-09T22:26:38.523987Z","steps":["trace[1708301203] 'process raft request' (duration: 185.158217ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:38.52418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.335338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:26:38.524271Z","caller":"traceutil/trace.go:171","msg":"trace[1763669925] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1093; }","duration":"157.462191ms","start":"2026-06-09T22:26:38.366786Z","end":"2026-06-09T22:26:38.524249Z","steps":["trace[1763669925] 'agreement among raft nodes before linearized reading' (duration: 157.337708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:38.524475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.210068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T22:26:38.524645Z","caller":"traceutil/trace.go:171","msg":"trace[369379052] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1093; }","duration":"147.436573ms","start":"2026-06-09T22:26:38.377166Z","end":"2026-06-09T22:26:38.524603Z","steps":["trace[369379052] 'agreement among raft nodes before linearized reading' (duration: 147.030002ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:38.525106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.790289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-09T22:26:38.525281Z","caller":"traceutil/trace.go:171","msg":"trace[499505052] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1093; }","duration":"182.861831ms","start":"2026-06-09T22:26:38.34228Z","end":"2026-06-09T22:26:38.525142Z","steps":["trace[499505052] 'agreement among raft nodes before linearized reading' (duration: 181.838375ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:39.364665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"733.80026ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T22:26:39.365087Z","caller":"traceutil/trace.go:171","msg":"trace[447261154] linearizableReadLoop","detail":"{readStateIndex:1136; appliedIndex:1134; }","duration":"515.297196ms","start":"2026-06-09T22:26:38.849749Z","end":"2026-06-09T22:26:39.365046Z","steps":["trace[447261154] 'read index received' (duration: 153.927114ms)","trace[447261154] 'applied index is now lower than readState.Index' (duration: 361.368562ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:26:39.365251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"515.498861ms","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-06-09T22:26:39.365291Z","caller":"traceutil/trace.go:171","msg":"trace[1147528521] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1095; }","duration":"515.546552ms","start":"2026-06-09T22:26:38.849733Z","end":"2026-06-09T22:26:39.36528Z","steps":["trace[1147528521] 'agreement among raft nodes before linearized reading' (duration: 515.408519ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:39.365331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:38.849679Z","time spent":"515.642385ms","remote":"127.0.0.1:45470","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"info","ts":"2026-06-09T22:26:39.365376Z","caller":"traceutil/trace.go:171","msg":"trace[1418332079] transaction","detail":"{read_only:false; response_revision:1095; number_of_response:1; }","duration":"834.294949ms","start":"2026-06-09T22:26:38.531066Z","end":"2026-06-09T22:26:39.365361Z","steps":["trace[1418332079] 'process raft request' (duration: 833.779057ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:39.365688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:38.531043Z","time spent":"834.414303ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:26:39.364942Z","caller":"traceutil/trace.go:171","msg":"trace[1642494597] transaction","detail":"{read_only:false; response_revision:1094; number_of_response:1; }","duration":"835.864049ms","start":"2026-06-09T22:26:38.529033Z","end":"2026-06-09T22:26:39.364897Z","steps":["trace[1642494597] 'process raft request' (duration: 101.701429ms)","trace[1642494597] 'compare' (duration: 732.904808ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:26:39.365988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:38.52901Z","time spent":"836.825053ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:26:39.678336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.288122ms","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-06-09T22:26:39.678464Z","caller":"traceutil/trace.go:171","msg":"trace[118043034] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:1095; }","duration":"204.467526ms","start":"2026-06-09T22:26:39.473972Z","end":"2026-06-09T22:26:39.67844Z","steps":["trace[118043034] 'count revisions from in-memory index tree' (duration: 204.175017ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:40.618026Z","caller":"traceutil/trace.go:171","msg":"trace[1539766438] transaction","detail":"{read_only:false; response_revision:1098; number_of_response:1; }","duration":"128.588626ms","start":"2026-06-09T22:26:40.48939Z","end":"2026-06-09T22:26:40.617978Z","steps":["trace[1539766438] 'process raft request' (duration: 128.460082ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:41.697619Z","caller":"traceutil/trace.go:171","msg":"trace[97782055] transaction","detail":"{read_only:false; response_revision:1100; number_of_response:1; }","duration":"323.050823ms","start":"2026-06-09T22:26:41.374537Z","end":"2026-06-09T22:26:41.697588Z","steps":["trace[97782055] 'process raft request' (duration: 322.861869ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:41.697799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:41.374519Z","time spent":"323.182536ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:26:41.738535Z","caller":"traceutil/trace.go:171","msg":"trace[1958906092] transaction","detail":"{read_only:false; response_revision:1101; number_of_response:1; }","duration":"363.703151ms","start":"2026-06-09T22:26:41.374796Z","end":"2026-06-09T22:26:41.738499Z","steps":["trace[1958906092] 'process raft request' (duration: 362.370888ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:41.738751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:41.374778Z","time spent":"363.858815ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:26:50.530108Z","caller":"traceutil/trace.go:171","msg":"trace[1172647559] transaction","detail":"{read_only:false; response_revision:1130; number_of_response:1; }","duration":"130.34577ms","start":"2026-06-09T22:26:50.399721Z","end":"2026-06-09T22:26:50.530067Z","steps":["trace[1172647559] 'process raft request' (duration: 69.566201ms)","trace[1172647559] 'compare' (duration: 60.655356ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:26:50.790517Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.006923ms","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-06-09T22:26:50.790644Z","caller":"traceutil/trace.go:171","msg":"trace[681728824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1130; }","duration":"123.188667ms","start":"2026-06-09T22:26:50.667437Z","end":"2026-06-09T22:26:50.790626Z","steps":["trace[681728824] 'range keys from in-memory index tree' (duration: 122.695545ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:26:57.140404Z","caller":"traceutil/trace.go:171","msg":"trace[992694212] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1248; }","duration":"237.721503ms","start":"2026-06-09T22:26:56.902645Z","end":"2026-06-09T22:26:57.140366Z","steps":["trace[992694212] 'read index received' (duration: 237.616331ms)","trace[992694212] 'applied index is now lower than readState.Index' (duration: 104.222µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:26:57.14044Z","caller":"traceutil/trace.go:171","msg":"trace[1505590585] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"294.010352ms","start":"2026-06-09T22:26:56.846389Z","end":"2026-06-09T22:26:57.1404Z","steps":["trace[1505590585] 'process raft request' (duration: 293.836708ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:57.140598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.931388ms","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-06-09T22:26:57.140639Z","caller":"traceutil/trace.go:171","msg":"trace[883177912] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1203; }","duration":"237.987489ms","start":"2026-06-09T22:26:56.902639Z","end":"2026-06-09T22:26:57.140627Z","steps":["trace[883177912] 'agreement among raft nodes before linearized reading' (duration: 237.837626ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:58.359924Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411827763361359,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T22:26:58.728263Z","caller":"traceutil/trace.go:171","msg":"trace[423801563] linearizableReadLoop","detail":"{readStateIndex:1252; appliedIndex:1251; }","duration":"868.823666ms","start":"2026-06-09T22:26:57.85942Z","end":"2026-06-09T22:26:58.728243Z","steps":["trace[423801563] 'read index received' (duration: 868.724603ms)","trace[423801563] 'applied index is now lower than readState.Index' (duration: 97.953µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:26:58.728431Z","caller":"traceutil/trace.go:171","msg":"trace[2036319978] transaction","detail":"{read_only:false; response_revision:1205; number_of_response:1; }","duration":"871.714776ms","start":"2026-06-09T22:26:57.856674Z","end":"2026-06-09T22:26:58.728389Z","steps":["trace[2036319978] 'process raft request' (duration: 871.44593ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:58.72852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"869.080392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-09T22:26:58.728563Z","caller":"traceutil/trace.go:171","msg":"trace[2098221615] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1205; }","duration":"869.172724ms","start":"2026-06-09T22:26:57.859381Z","end":"2026-06-09T22:26:58.728553Z","steps":["trace[2098221615] 'agreement among raft nodes before linearized reading' (duration: 868.97728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:58.728601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:57.859362Z","time spent":"869.231185ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T22:26:58.7286Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:57.856657Z","time spent":"871.857509ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:26:58.730537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.569418ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:26:58.730618Z","caller":"traceutil/trace.go:171","msg":"trace[963807242] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1206; }","duration":"364.688281ms","start":"2026-06-09T22:26:58.36591Z","end":"2026-06-09T22:26:58.730599Z","steps":["trace[963807242] 'agreement among raft nodes before linearized reading' (duration: 364.557188ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:58.730658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:26:58.365894Z","time spent":"364.755823ms","remote":"127.0.0.1:45360","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-06-09T22:26:58.731047Z","caller":"traceutil/trace.go:171","msg":"trace[1170623520] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"110.14696ms","start":"2026-06-09T22:26:58.620888Z","end":"2026-06-09T22:26:58.731035Z","steps":["trace[1170623520] 'process raft request' (duration: 109.407741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:26:58.731386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.887889ms","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-06-09T22:26:58.731419Z","caller":"traceutil/trace.go:171","msg":"trace[230119309] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1206; }","duration":"263.957331ms","start":"2026-06-09T22:26:58.467453Z","end":"2026-06-09T22:26:58.73141Z","steps":["trace[230119309] 'agreement among raft nodes before linearized reading' (duration: 263.850888ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:10.992769Z","caller":"traceutil/trace.go:171","msg":"trace[418459649] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"203.817828ms","start":"2026-06-09T22:27:10.788929Z","end":"2026-06-09T22:27:10.992747Z","steps":["trace[418459649] 'process raft request' (duration: 203.675245ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:11.0058Z","caller":"traceutil/trace.go:171","msg":"trace[707116994] transaction","detail":"{read_only:false; response_revision:1247; number_of_response:1; }","duration":"215.672556ms","start":"2026-06-09T22:27:10.790104Z","end":"2026-06-09T22:27:11.005776Z","steps":["trace[707116994] 'process raft request' (duration: 215.598344ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:15.589561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.272611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:27:15.589641Z","caller":"traceutil/trace.go:171","msg":"trace[1940741473] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1283; }","duration":"225.454415ms","start":"2026-06-09T22:27:15.364167Z","end":"2026-06-09T22:27:15.589621Z","steps":["trace[1940741473] 'range keys from in-memory index tree' (duration: 225.080486ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:17.322744Z","caller":"traceutil/trace.go:171","msg":"trace[306675304] linearizableReadLoop","detail":"{readStateIndex:1338; appliedIndex:1337; }","duration":"328.058222ms","start":"2026-06-09T22:27:16.994669Z","end":"2026-06-09T22:27:17.322727Z","steps":["trace[306675304] 'read index received' (duration: 327.95266ms)","trace[306675304] 'applied index is now lower than readState.Index' (duration: 104.882µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:27:17.322788Z","caller":"traceutil/trace.go:171","msg":"trace[1223922527] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"340.285829ms","start":"2026-06-09T22:27:16.982467Z","end":"2026-06-09T22:27:17.322753Z","steps":["trace[1223922527] 'process raft request' (duration: 340.149355ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:17.322855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.174665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:27:17.322883Z","caller":"traceutil/trace.go:171","msg":"trace[1933086793] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:0; response_revision:1288; }","duration":"328.242536ms","start":"2026-06-09T22:27:16.994633Z","end":"2026-06-09T22:27:17.322875Z","steps":["trace[1933086793] 'agreement among raft nodes before linearized reading' (duration: 328.170424ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:17.322903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:16.994623Z","time spent":"328.275567ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":0,"response size":29,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-09T22:27:17.323064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:16.982449Z","time spent":"340.407141ms","remote":"127.0.0.1:45438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":788,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-06-09T22:27:17.823174Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411827763361546,"retry-timeout":"500ms"} {"level":"info","ts":"2026-06-09T22:27:18.186432Z","caller":"traceutil/trace.go:171","msg":"trace[1469448808] transaction","detail":"{read_only:false; response_revision:1289; number_of_response:1; }","duration":"1.109262374s","start":"2026-06-09T22:27:17.077142Z","end":"2026-06-09T22:27:18.186405Z","steps":["trace[1469448808] 'process raft request' (duration: 1.109023558s)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:18.186552Z","caller":"traceutil/trace.go:171","msg":"trace[453010494] transaction","detail":"{read_only:false; response_revision:1290; number_of_response:1; }","duration":"1.028397898s","start":"2026-06-09T22:27:17.158118Z","end":"2026-06-09T22:27:18.186516Z","steps":["trace[453010494] 'process raft request' (duration: 1.028231244s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:18.186595Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:17.077121Z","time spent":"1.109396267s","remote":"127.0.0.1:45546","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5639,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:27:18.186706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:17.158097Z","time spent":"1.028534221s","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:27:18.313738Z","caller":"traceutil/trace.go:171","msg":"trace[1969541969] transaction","detail":"{read_only:false; response_revision:1292; number_of_response:1; }","duration":"986.129148ms","start":"2026-06-09T22:27:17.327591Z","end":"2026-06-09T22:27:18.31372Z","steps":["trace[1969541969] 'process raft request' (duration: 986.015325ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:18.313848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:17.32757Z","time spent":"986.227431ms","remote":"127.0.0.1:45438","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":836,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T22:27:18.313736Z","caller":"traceutil/trace.go:171","msg":"trace[1851426754] transaction","detail":"{read_only:false; response_revision:1291; number_of_response:1; }","duration":"986.137419ms","start":"2026-06-09T22:27:17.327567Z","end":"2026-06-09T22:27:18.313704Z","steps":["trace[1851426754] 'process raft request' (duration: 985.895143ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:18.314004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:17.327542Z","time spent":"986.416026ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-09T22:27:18.38737Z","caller":"traceutil/trace.go:171","msg":"trace[1640322546] linearizableReadLoop","detail":"{readStateIndex:1343; appliedIndex:1340; }","duration":"1.064550695s","start":"2026-06-09T22:27:17.32279Z","end":"2026-06-09T22:27:18.387341Z","steps":["trace[1640322546] 'read index received' (duration: 863.822923ms)","trace[1640322546] 'applied index is now lower than readState.Index' (duration: 200.722352ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:27:18.387554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.32325883s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T22:27:18.387547Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.171420466s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-06-09T22:27:18.38763Z","caller":"traceutil/trace.go:171","msg":"trace[1676644577] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1293; }","duration":"1.171559079s","start":"2026-06-09T22:27:17.216053Z","end":"2026-06-09T22:27:18.387612Z","steps":["trace[1676644577] 'agreement among raft nodes before linearized reading' (duration: 1.171295093s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:18.38768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.361304645s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-06-09T22:27:18.387711Z","caller":"traceutil/trace.go:171","msg":"trace[970448379] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1293; }","duration":"1.361368167s","start":"2026-06-09T22:27:17.026337Z","end":"2026-06-09T22:27:18.387705Z","steps":["trace[970448379] 'agreement among raft nodes before linearized reading' (duration: 1.361262865s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:18.387738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:17.026321Z","time spent":"1.361411188s","remote":"127.0.0.1:45638","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-06-09T22:27:18.387755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.08327668s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-06-09T22:27:18.387786Z","caller":"traceutil/trace.go:171","msg":"trace[1718815141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1293; }","duration":"1.083344682s","start":"2026-06-09T22:27:17.304431Z","end":"2026-06-09T22:27:18.387776Z","steps":["trace[1718815141] 'agreement among raft nodes before linearized reading' (duration: 1.08326869s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:18.387819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:17.304407Z","time spent":"1.083405754s","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T22:27:18.387679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:17.216035Z","time spent":"1.171635241s","remote":"127.0.0.1:45638","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-06-09T22:27:18.387585Z","caller":"traceutil/trace.go:171","msg":"trace[1278824375] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1293; }","duration":"1.323355322s","start":"2026-06-09T22:27:17.064223Z","end":"2026-06-09T22:27:18.387578Z","steps":["trace[1278824375] 'agreement among raft nodes before linearized reading' (duration: 1.32325755s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:18.387971Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:17.064203Z","time spent":"1.323754212s","remote":"127.0.0.1:45504","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-06-09T22:27:18.387977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.375021719s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-06-09T22:27:18.388032Z","caller":"traceutil/trace.go:171","msg":"trace[850391920] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1293; }","duration":"1.375120642s","start":"2026-06-09T22:27:17.012897Z","end":"2026-06-09T22:27:18.388018Z","steps":["trace[850391920] 'agreement among raft nodes before linearized reading' (duration: 1.374974187s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:18.388064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:17.012878Z","time spent":"1.375180714s","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T22:27:18.388165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022316048s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:27:18.388194Z","caller":"traceutil/trace.go:171","msg":"trace[284817409] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1293; }","duration":"1.02238465s","start":"2026-06-09T22:27:17.365803Z","end":"2026-06-09T22:27:18.388188Z","steps":["trace[284817409] 'agreement among raft nodes before linearized reading' (duration: 1.022343199s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:18.388213Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:17.365787Z","time spent":"1.022421381s","remote":"127.0.0.1:45352","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-06-09T22:27:19.134729Z","caller":"traceutil/trace.go:171","msg":"trace[407352159] linearizableReadLoop","detail":"{readStateIndex:1350; appliedIndex:1349; }","duration":"279.895919ms","start":"2026-06-09T22:27:18.85481Z","end":"2026-06-09T22:27:19.134706Z","steps":["trace[407352159] 'read index received' (duration: 279.697094ms)","trace[407352159] 'applied index is now lower than readState.Index' (duration: 198.005µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:27:19.135082Z","caller":"traceutil/trace.go:171","msg":"trace[1493593143] transaction","detail":"{read_only:false; response_revision:1299; number_of_response:1; }","duration":"299.520016ms","start":"2026-06-09T22:27:18.835533Z","end":"2026-06-09T22:27:19.135053Z","steps":["trace[1493593143] 'process raft request' (duration: 299.040235ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:19.135118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.140774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:27:19.135162Z","caller":"traceutil/trace.go:171","msg":"trace[1249962523] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:1299; }","duration":"213.234286ms","start":"2026-06-09T22:27:18.921916Z","end":"2026-06-09T22:27:19.13515Z","steps":["trace[1249962523] 'agreement among raft nodes before linearized reading' (duration: 213.154205ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:19.135479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.680697ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T22:27:19.135508Z","caller":"traceutil/trace.go:171","msg":"trace[1086360376] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1299; }","duration":"280.744249ms","start":"2026-06-09T22:27:18.854755Z","end":"2026-06-09T22:27:19.135499Z","steps":["trace[1086360376] 'agreement among raft nodes before linearized reading' (duration: 280.643576ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:19.351484Z","caller":"traceutil/trace.go:171","msg":"trace[2036176523] transaction","detail":"{read_only:false; response_revision:1300; number_of_response:1; }","duration":"212.216442ms","start":"2026-06-09T22:27:19.139231Z","end":"2026-06-09T22:27:19.351447Z","steps":["trace[2036176523] 'process raft request' (duration: 200.60499ms)","trace[2036176523] 'compare' (duration: 11.468328ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:27:28.867096Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411827763361682,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T22:27:29.368184Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411827763361682,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T22:27:29.868884Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411827763361682,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T22:27:30.366616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000130927s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-06-09T22:27:30.366709Z","caller":"traceutil/trace.go:171","msg":"trace[610214647] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000266381s","start":"2026-06-09T22:27:28.36642Z","end":"2026-06-09T22:27:30.366687Z","steps":["trace[610214647] 'agreement among raft nodes before linearized reading' (duration: 2.000152098s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:30.366758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:28.366405Z","time spent":"2.000341463s","remote":"127.0.0.1:45360","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T22:27:30.369646Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":9602411827763361682,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-06-09T22:27:30.520475Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.50545204s","expected-duration":"1s"} {"level":"info","ts":"2026-06-09T22:27:30.521048Z","caller":"traceutil/trace.go:171","msg":"trace[1234144404] transaction","detail":"{read_only:false; response_revision:1363; number_of_response:1; }","duration":"2.506108847s","start":"2026-06-09T22:27:28.014914Z","end":"2026-06-09T22:27:30.521023Z","steps":["trace[1234144404] 'process raft request' (duration: 2.505929923s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:30.521363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:28.014893Z","time spent":"2.506229789s","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:27:30.525331Z","caller":"traceutil/trace.go:171","msg":"trace[487141064] linearizableReadLoop","detail":"{readStateIndex:1418; appliedIndex:1415; }","duration":"2.158859479s","start":"2026-06-09T22:27:28.366452Z","end":"2026-06-09T22:27:30.525312Z","steps":["trace[487141064] 'read index received' (duration: 2.154399011s)","trace[487141064] 'applied index is now lower than readState.Index' (duration: 4.459758ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:27:30.52539Z","caller":"traceutil/trace.go:171","msg":"trace[1469201300] transaction","detail":"{read_only:false; response_revision:1364; number_of_response:1; }","duration":"2.138889932s","start":"2026-06-09T22:27:28.386451Z","end":"2026-06-09T22:27:30.52534Z","steps":["trace[1469201300] 'process raft request' (duration: 2.138711508s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:30.525558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.0730125s","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-06-09T22:27:30.525644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"635.1466ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:27:30.525674Z","caller":"traceutil/trace.go:171","msg":"trace[437525558] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:1365; }","duration":"635.232213ms","start":"2026-06-09T22:27:29.890431Z","end":"2026-06-09T22:27:30.525663Z","steps":["trace[437525558] 'agreement among raft nodes before linearized reading' (duration: 635.174492ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:30.525706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:29.890409Z","time spent":"635.289705ms","remote":"127.0.0.1:45710","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":29,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-06-09T22:27:30.525724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.925342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:27:30.525865Z","caller":"traceutil/trace.go:171","msg":"trace[1009153461] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1365; }","duration":"158.113326ms","start":"2026-06-09T22:27:30.367724Z","end":"2026-06-09T22:27:30.525837Z","steps":["trace[1009153461] 'agreement among raft nodes before linearized reading' (duration: 157.86893ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:30.525952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.007016364s","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-06-09T22:27:30.526039Z","caller":"traceutil/trace.go:171","msg":"trace[1507846663] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1365; }","duration":"2.007154598s","start":"2026-06-09T22:27:28.518867Z","end":"2026-06-09T22:27:30.526021Z","steps":["trace[1507846663] 'agreement among raft nodes before linearized reading' (duration: 2.006897812s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:30.526076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.898214588s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-09T22:27:30.5261Z","caller":"traceutil/trace.go:171","msg":"trace[1524731514] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1365; }","duration":"1.898273899s","start":"2026-06-09T22:27:28.62782Z","end":"2026-06-09T22:27:30.526094Z","steps":["trace[1524731514] 'agreement among raft nodes before linearized reading' (duration: 1.898209898s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:30.526096Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:28.51885Z","time spent":"2.007231799s","remote":"127.0.0.1:45638","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-06-09T22:27:30.526126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:28.6278Z","time spent":"1.89832202s","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-06-09T22:27:30.526276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.006587875s","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-06-09T22:27:30.526323Z","caller":"traceutil/trace.go:171","msg":"trace[220853542] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1365; }","duration":"2.006667267s","start":"2026-06-09T22:27:28.519641Z","end":"2026-06-09T22:27:30.526308Z","steps":["trace[220853542] 'agreement among raft nodes before linearized reading' (duration: 2.006561994s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:30.52637Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:28.519622Z","time spent":"2.006737859s","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T22:27:30.526423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.012082279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-09T22:27:30.526471Z","caller":"traceutil/trace.go:171","msg":"trace[350704912] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1365; }","duration":"2.01215276s","start":"2026-06-09T22:27:28.514312Z","end":"2026-06-09T22:27:30.526465Z","steps":["trace[350704912] 'agreement among raft nodes before linearized reading' (duration: 2.012063458s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:30.526495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:28.5143Z","time spent":"2.012190151s","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-06-09T22:27:30.525818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.695057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-09T22:27:30.526577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.041824402s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-06-09T22:27:30.526576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"984.781567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-06-09T22:27:30.526598Z","caller":"traceutil/trace.go:171","msg":"trace[1703347656] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1365; }","duration":"2.041879102s","start":"2026-06-09T22:27:28.484713Z","end":"2026-06-09T22:27:30.526593Z","steps":["trace[1703347656] 'agreement among raft nodes before linearized reading' (duration: 2.041828341s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:30.526622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:28.484695Z","time spent":"2.041923344s","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":498,"request content":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" "} {"level":"info","ts":"2026-06-09T22:27:30.526593Z","caller":"traceutil/trace.go:171","msg":"trace[26595692] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:1365; }","duration":"135.527437ms","start":"2026-06-09T22:27:30.391044Z","end":"2026-06-09T22:27:30.526572Z","steps":["trace[26595692] 'agreement among raft nodes before linearized reading' (duration: 134.680516ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:30.526635Z","caller":"traceutil/trace.go:171","msg":"trace[1961253089] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1365; }","duration":"984.876629ms","start":"2026-06-09T22:27:29.541741Z","end":"2026-06-09T22:27:30.526617Z","steps":["trace[1961253089] 'agreement among raft nodes before linearized reading' (duration: 984.688724ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:30.526688Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:29.541721Z","time spent":"984.95493ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":550,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "} {"level":"warn","ts":"2026-06-09T22:27:30.525589Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:28.386427Z","time spent":"2.139019875s","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:27:30.525425Z","caller":"traceutil/trace.go:171","msg":"trace[1612551672] transaction","detail":"{read_only:false; response_revision:1365; number_of_response:1; }","duration":"1.363484219s","start":"2026-06-09T22:27:29.161933Z","end":"2026-06-09T22:27:30.525417Z","steps":["trace[1612551672] 'process raft request' (duration: 1.363348096s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:30.527168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:29.161907Z","time spent":"1.365181691s","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:27:30.527227Z","caller":"traceutil/trace.go:171","msg":"trace[595006220] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1365; }","duration":"2.073099232s","start":"2026-06-09T22:27:28.452483Z","end":"2026-06-09T22:27:30.525582Z","steps":["trace[595006220] 'agreement among raft nodes before linearized reading' (duration: 2.072963159s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:30.527228Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.135648427s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-06-09T22:27:30.527275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:28.452462Z","time spent":"2.074801804s","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-09T22:27:30.527281Z","caller":"traceutil/trace.go:171","msg":"trace[2090156305] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1365; }","duration":"1.13574278s","start":"2026-06-09T22:27:29.391526Z","end":"2026-06-09T22:27:30.527269Z","steps":["trace[2090156305] 'agreement among raft nodes before linearized reading' (duration: 1.134002517s)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:30.527314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:29.391505Z","time spent":"1.135802042s","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-06-09T22:27:42.775989Z","caller":"traceutil/trace.go:171","msg":"trace[312314386] transaction","detail":"{read_only:false; response_revision:1424; number_of_response:1; }","duration":"130.894374ms","start":"2026-06-09T22:27:42.645064Z","end":"2026-06-09T22:27:42.775959Z","steps":["trace[312314386] 'process raft request' (duration: 72.3604ms)","trace[312314386] 'compare' (duration: 58.255758ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:27:42.958027Z","caller":"traceutil/trace.go:171","msg":"trace[984129042] transaction","detail":"{read_only:false; response_revision:1425; number_of_response:1; }","duration":"311.908128ms","start":"2026-06-09T22:27:42.646066Z","end":"2026-06-09T22:27:42.957974Z","steps":["trace[984129042] 'process raft request' (duration: 249.606462ms)","trace[984129042] 'compare' (duration: 62.05152ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:27:42.95806Z","caller":"traceutil/trace.go:171","msg":"trace[2106173633] transaction","detail":"{read_only:false; response_revision:1426; number_of_response:1; }","duration":"311.756924ms","start":"2026-06-09T22:27:42.646281Z","end":"2026-06-09T22:27:42.958038Z","steps":["trace[2106173633] 'process raft request' (duration: 311.56744ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:42.958078Z","caller":"traceutil/trace.go:171","msg":"trace[393201842] transaction","detail":"{read_only:false; response_revision:1427; number_of_response:1; }","duration":"311.641761ms","start":"2026-06-09T22:27:42.646419Z","end":"2026-06-09T22:27:42.95806Z","steps":["trace[393201842] 'process raft request' (duration: 311.465936ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:42.958176Z","caller":"traceutil/trace.go:171","msg":"trace[1918675959] transaction","detail":"{read_only:false; response_revision:1428; number_of_response:1; }","duration":"311.554709ms","start":"2026-06-09T22:27:42.646524Z","end":"2026-06-09T22:27:42.958078Z","steps":["trace[1918675959] 'process raft request' (duration: 311.391435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:42.958232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:42.646251Z","time spent":"311.860166ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:27:42.958271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:42.646511Z","time spent":"311.722753ms","remote":"127.0.0.1:45638","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-06-09T22:27:42.958169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:42.646043Z","time spent":"312.04998ms","remote":"127.0.0.1:45638","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-06-09T22:27:42.958054Z","caller":"traceutil/trace.go:171","msg":"trace[331531312] transaction","detail":"{read_only:false; response_revision:1429; number_of_response:1; }","duration":"310.664957ms","start":"2026-06-09T22:27:42.647377Z","end":"2026-06-09T22:27:42.958042Z","steps":["trace[331531312] 'process raft request' (duration: 310.562955ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:42.958572Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:42.64737Z","time spent":"311.129279ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:27:42.958338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:42.646408Z","time spent":"311.704423ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:27:45.093668Z","caller":"traceutil/trace.go:171","msg":"trace[1901183468] linearizableReadLoop","detail":"{readStateIndex:1487; appliedIndex:1486; }","duration":"374.05902ms","start":"2026-06-09T22:27:44.719588Z","end":"2026-06-09T22:27:45.093647Z","steps":["trace[1901183468] 'read index received' (duration: 373.918606ms)","trace[1901183468] 'applied index is now lower than readState.Index' (duration: 139.374µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:27:45.09374Z","caller":"traceutil/trace.go:171","msg":"trace[1034707725] transaction","detail":"{read_only:false; response_revision:1431; number_of_response:1; }","duration":"421.226078ms","start":"2026-06-09T22:27:44.672483Z","end":"2026-06-09T22:27:45.093709Z","steps":["trace[1034707725] 'process raft request' (duration: 421.025382ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:45.093854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.188832ms","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-06-09T22:27:45.093884Z","caller":"traceutil/trace.go:171","msg":"trace[49571925] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1431; }","duration":"131.235983ms","start":"2026-06-09T22:27:44.96264Z","end":"2026-06-09T22:27:45.093876Z","steps":["trace[49571925] 'agreement among raft nodes before linearized reading' (duration: 131.13659ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:45.093896Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:44.672454Z","time spent":"421.355751ms","remote":"127.0.0.1:45638","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-06-09T22:27:45.093941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.680294ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"warn","ts":"2026-06-09T22:27:45.093963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.706915ms","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-06-09T22:27:45.093988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.457188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-06-09T22:27:45.093995Z","caller":"traceutil/trace.go:171","msg":"trace[8319635] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1431; }","duration":"131.778356ms","start":"2026-06-09T22:27:44.962202Z","end":"2026-06-09T22:27:45.093981Z","steps":["trace[8319635] 'agreement among raft nodes before linearized reading' (duration: 131.638713ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:45.094011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.999588ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-09T22:27:45.094036Z","caller":"traceutil/trace.go:171","msg":"trace[203772142] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1431; }","duration":"131.53079ms","start":"2026-06-09T22:27:44.962492Z","end":"2026-06-09T22:27:45.094023Z","steps":["trace[203772142] 'agreement among raft nodes before linearized reading' (duration: 131.415127ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:45.093822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.207174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:27:45.094074Z","caller":"traceutil/trace.go:171","msg":"trace[546824045] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1431; }","duration":"131.079839ms","start":"2026-06-09T22:27:44.96298Z","end":"2026-06-09T22:27:45.094059Z","steps":["trace[546824045] 'agreement among raft nodes before linearized reading' (duration: 130.943686ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:45.094095Z","caller":"traceutil/trace.go:171","msg":"trace[4237181] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:1431; }","duration":"374.522931ms","start":"2026-06-09T22:27:44.719558Z","end":"2026-06-09T22:27:45.094081Z","steps":["trace[4237181] 'agreement among raft nodes before linearized reading' (duration: 374.196113ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:45.094015Z","caller":"traceutil/trace.go:171","msg":"trace[1441755468] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1431; }","duration":"131.797537ms","start":"2026-06-09T22:27:44.962203Z","end":"2026-06-09T22:27:45.094Z","steps":["trace[1441755468] 'agreement among raft nodes before linearized reading' (duration: 131.665434ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:45.094153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:44.719545Z","time spent":"374.577093ms","remote":"127.0.0.1:45582","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true "} {"level":"warn","ts":"2026-06-09T22:27:45.094907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.715682ms","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-06-09T22:27:45.09495Z","caller":"traceutil/trace.go:171","msg":"trace[1575975410] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1431; }","duration":"314.832868ms","start":"2026-06-09T22:27:44.780105Z","end":"2026-06-09T22:27:45.094938Z","steps":["trace[1575975410] 'agreement among raft nodes before linearized reading' (duration: 313.65606ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:45.094986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:44.780091Z","time spent":"314.88259ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-06-09T22:27:45.355729Z","caller":"traceutil/trace.go:171","msg":"trace[156542520] transaction","detail":"{read_only:false; response_revision:1432; number_of_response:1; }","duration":"256.412876ms","start":"2026-06-09T22:27:45.099299Z","end":"2026-06-09T22:27:45.355712Z","steps":["trace[156542520] 'process raft request' (duration: 213.465942ms)","trace[156542520] 'compare' (duration: 42.874433ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:27:45.751968Z","caller":"traceutil/trace.go:171","msg":"trace[163190180] transaction","detail":"{read_only:false; response_revision:1433; number_of_response:1; }","duration":"652.317028ms","start":"2026-06-09T22:27:45.099615Z","end":"2026-06-09T22:27:45.751932Z","steps":["trace[163190180] 'process raft request' (duration: 596.383098ms)","trace[163190180] 'compare' (duration: 55.623112ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:27:45.752065Z","caller":"traceutil/trace.go:171","msg":"trace[495593608] transaction","detail":"{read_only:false; response_revision:1435; number_of_response:1; }","duration":"652.262096ms","start":"2026-06-09T22:27:45.099758Z","end":"2026-06-09T22:27:45.75202Z","steps":["trace[495593608] 'process raft request' (duration: 652.175694ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:45.752153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:45.099588Z","time spent":"652.474881ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:27:45.752199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:45.09974Z","time spent":"652.38402ms","remote":"127.0.0.1:45638","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-06-09T22:27:45.752475Z","caller":"traceutil/trace.go:171","msg":"trace[875563937] linearizableReadLoop","detail":"{readStateIndex:1493; appliedIndex:1488; }","duration":"388.907281ms","start":"2026-06-09T22:27:45.363551Z","end":"2026-06-09T22:27:45.752458Z","steps":["trace[875563937] 'read index received' (duration: 332.345985ms)","trace[875563937] 'applied index is now lower than readState.Index' (duration: 56.560096ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:27:45.752578Z","caller":"traceutil/trace.go:171","msg":"trace[1397618917] transaction","detail":"{read_only:false; response_revision:1436; number_of_response:1; }","duration":"652.764158ms","start":"2026-06-09T22:27:45.0998Z","end":"2026-06-09T22:27:45.752564Z","steps":["trace[1397618917] 'process raft request' (duration: 652.179244ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:45.752659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:45.099793Z","time spent":"652.818701ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:27:45.752685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.132356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T22:27:45.752886Z","caller":"traceutil/trace.go:171","msg":"trace[565926736] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1437; }","duration":"389.218508ms","start":"2026-06-09T22:27:45.363505Z","end":"2026-06-09T22:27:45.752724Z","steps":["trace[565926736] 'agreement among raft nodes before linearized reading' (duration: 389.123606ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:45.752909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.194043ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:535"} {"level":"info","ts":"2026-06-09T22:27:45.752908Z","caller":"traceutil/trace.go:171","msg":"trace[1663083553] transaction","detail":"{read_only:false; response_revision:1434; number_of_response:1; }","duration":"653.153918ms","start":"2026-06-09T22:27:45.09973Z","end":"2026-06-09T22:27:45.752884Z","steps":["trace[1663083553] 'process raft request' (duration: 652.127294ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:45.752887Z","caller":"traceutil/trace.go:171","msg":"trace[1511103770] transaction","detail":"{read_only:false; response_revision:1437; number_of_response:1; }","duration":"651.637111ms","start":"2026-06-09T22:27:45.101215Z","end":"2026-06-09T22:27:45.752852Z","steps":["trace[1511103770] 'process raft request' (duration: 651.14481ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:45.752945Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:45.36349Z","time spent":"389.441613ms","remote":"127.0.0.1:45360","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-06-09T22:27:45.752953Z","caller":"traceutil/trace.go:171","msg":"trace[718214054] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1437; }","duration":"208.259875ms","start":"2026-06-09T22:27:45.544678Z","end":"2026-06-09T22:27:45.752938Z","steps":["trace[718214054] 'agreement among raft nodes before linearized reading' (duration: 208.089541ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:45.752985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.249611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd-csi-ceph-com\" ","response":"range_response_count:1 size:474"} {"level":"warn","ts":"2026-06-09T22:27:45.753011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:45.099718Z","time spent":"653.24107ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":517,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:27:45.753044Z","caller":"traceutil/trace.go:171","msg":"trace[1925249827] range","detail":"{range_begin:/registry/leases/kube-system/rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1437; }","duration":"186.327773ms","start":"2026-06-09T22:27:45.5667Z","end":"2026-06-09T22:27:45.753028Z","steps":["trace[1925249827] 'agreement among raft nodes before linearized reading' (duration: 186.146958ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:45.753078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:45.101204Z","time spent":"651.769105ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:27:45.753263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.319677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-06-09T22:27:45.753388Z","caller":"traceutil/trace.go:171","msg":"trace[921415706] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:1437; }","duration":"187.494911ms","start":"2026-06-09T22:27:45.565867Z","end":"2026-06-09T22:27:45.753362Z","steps":["trace[921415706] 'agreement among raft nodes before linearized reading' (duration: 187.100761ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:46.158534Z","caller":"traceutil/trace.go:171","msg":"trace[713771976] transaction","detail":"{read_only:false; response_revision:1438; number_of_response:1; }","duration":"399.654893ms","start":"2026-06-09T22:27:45.758853Z","end":"2026-06-09T22:27:46.158508Z","steps":["trace[713771976] 'process raft request' (duration: 326.636766ms)","trace[713771976] 'compare' (duration: 72.865053ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:27:46.158677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:45.758807Z","time spent":"399.794056ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T22:27:46.159092Z","caller":"traceutil/trace.go:171","msg":"trace[830605376] transaction","detail":"{read_only:false; response_revision:1439; number_of_response:1; }","duration":"400.195335ms","start":"2026-06-09T22:27:45.758874Z","end":"2026-06-09T22:27:46.159069Z","steps":["trace[830605376] 'process raft request' (duration: 399.596381ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:27:46.159274Z","caller":"traceutil/trace.go:171","msg":"trace[1531425783] linearizableReadLoop","detail":"{readStateIndex:1496; appliedIndex:1493; }","duration":"204.967226ms","start":"2026-06-09T22:27:45.954287Z","end":"2026-06-09T22:27:46.159255Z","steps":["trace[1531425783] 'read index received' (duration: 131.222822ms)","trace[1531425783] 'applied index is now lower than readState.Index' (duration: 73.743194ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:27:46.15942Z","caller":"traceutil/trace.go:171","msg":"trace[1276317836] transaction","detail":"{read_only:false; response_revision:1440; number_of_response:1; }","duration":"400.444991ms","start":"2026-06-09T22:27:45.758957Z","end":"2026-06-09T22:27:46.159402Z","steps":["trace[1276317836] 'process raft request' (duration: 400.030561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:27:46.159349Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:45.758853Z","time spent":"400.37585ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":511,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:27:46.159574Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:27:45.758922Z","time spent":"400.546274ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":459,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:27:46.159712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.420527ms","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-06-09T22:27:46.159988Z","caller":"traceutil/trace.go:171","msg":"trace[574655711] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:1440; }","duration":"205.757025ms","start":"2026-06-09T22:27:45.954204Z","end":"2026-06-09T22:27:46.159962Z","steps":["trace[574655711] 'agreement among raft nodes before linearized reading' (duration: 205.205301ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:28:00.100909Z","caller":"traceutil/trace.go:171","msg":"trace[1960299406] transaction","detail":"{read_only:false; response_revision:1502; number_of_response:1; }","duration":"210.561992ms","start":"2026-06-09T22:27:59.890316Z","end":"2026-06-09T22:28:00.100878Z","steps":["trace[1960299406] 'process raft request' (duration: 210.430679ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:28:00.101137Z","caller":"traceutil/trace.go:171","msg":"trace[67736125] transaction","detail":"{read_only:false; response_revision:1501; number_of_response:1; }","duration":"210.836049ms","start":"2026-06-09T22:27:59.890283Z","end":"2026-06-09T22:28:00.101119Z","steps":["trace[67736125] 'process raft request' (duration: 190.773882ms)","trace[67736125] 'compare' (duration: 19.568815ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:28:46.407506Z","caller":"traceutil/trace.go:171","msg":"trace[595988729] transaction","detail":"{read_only:false; response_revision:1712; number_of_response:1; }","duration":"120.209704ms","start":"2026-06-09T22:28:46.287262Z","end":"2026-06-09T22:28:46.407472Z","steps":["trace[595988729] 'process raft request' (duration: 112.996506ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:28:51.966687Z","caller":"traceutil/trace.go:171","msg":"trace[206669805] transaction","detail":"{read_only:false; response_revision:1738; number_of_response:1; }","duration":"215.071053ms","start":"2026-06-09T22:28:51.751586Z","end":"2026-06-09T22:28:51.966658Z","steps":["trace[206669805] 'process raft request' (duration: 124.071722ms)","trace[206669805] 'compare' (duration: 90.884058ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:28:52.395343Z","caller":"traceutil/trace.go:171","msg":"trace[1906359724] transaction","detail":"{read_only:false; response_revision:1740; number_of_response:1; }","duration":"173.938575ms","start":"2026-06-09T22:28:52.221385Z","end":"2026-06-09T22:28:52.395323Z","steps":["trace[1906359724] 'process raft request' (duration: 173.779581ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:28:52.575418Z","caller":"traceutil/trace.go:171","msg":"trace[1420601845] linearizableReadLoop","detail":"{readStateIndex:1812; appliedIndex:1811; }","duration":"151.465988ms","start":"2026-06-09T22:28:52.423936Z","end":"2026-06-09T22:28:52.575402Z","steps":["trace[1420601845] 'read index received' (duration: 62.449216ms)","trace[1420601845] 'applied index is now lower than readState.Index' (duration: 89.015842ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:28:52.575523Z","caller":"traceutil/trace.go:171","msg":"trace[373648206] transaction","detail":"{read_only:false; response_revision:1743; number_of_response:1; }","duration":"105.52498ms","start":"2026-06-09T22:28:52.469977Z","end":"2026-06-09T22:28:52.575502Z","steps":["trace[373648206] 'process raft request' (duration: 105.397607ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:28:52.57543Z","caller":"traceutil/trace.go:171","msg":"trace[1889589332] transaction","detail":"{read_only:false; response_revision:1742; number_of_response:1; }","duration":"174.479748ms","start":"2026-06-09T22:28:52.400911Z","end":"2026-06-09T22:28:52.57539Z","steps":["trace[1889589332] 'process raft request' (duration: 85.458485ms)","trace[1889589332] 'compare' (duration: 88.888769ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T22:28:52.5757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.712574ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T22:28:52.575721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.943644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:421"} {"level":"info","ts":"2026-06-09T22:28:52.575804Z","caller":"traceutil/trace.go:171","msg":"trace[610221958] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1743; }","duration":"151.035906ms","start":"2026-06-09T22:28:52.424752Z","end":"2026-06-09T22:28:52.575788Z","steps":["trace[610221958] 'agreement among raft nodes before linearized reading' (duration: 150.906213ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:28:52.576415Z","caller":"traceutil/trace.go:171","msg":"trace[1669595508] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1743; }","duration":"152.496034ms","start":"2026-06-09T22:28:52.423902Z","end":"2026-06-09T22:28:52.576398Z","steps":["trace[1669595508] 'agreement among raft nodes before linearized reading' (duration: 151.621582ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:28:52.710276Z","caller":"traceutil/trace.go:171","msg":"trace[470727530] transaction","detail":"{read_only:false; response_revision:1744; number_of_response:1; }","duration":"129.917685ms","start":"2026-06-09T22:28:52.580333Z","end":"2026-06-09T22:28:52.710251Z","steps":["trace[470727530] 'process raft request' (duration: 124.185343ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:28:52.710451Z","caller":"traceutil/trace.go:171","msg":"trace[803671889] transaction","detail":"{read_only:false; response_revision:1745; number_of_response:1; }","duration":"129.829983ms","start":"2026-06-09T22:28:52.580608Z","end":"2026-06-09T22:28:52.710438Z","steps":["trace[803671889] 'process raft request' (duration: 129.259739ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:28:57.33152Z","caller":"traceutil/trace.go:171","msg":"trace[741574995] transaction","detail":"{read_only:false; response_revision:1765; number_of_response:1; }","duration":"179.465739ms","start":"2026-06-09T22:28:57.152031Z","end":"2026-06-09T22:28:57.331496Z","steps":["trace[741574995] 'process raft request' (duration: 179.334746ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:30:38.802079Z","caller":"traceutil/trace.go:171","msg":"trace[414132989] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"115.522844ms","start":"2026-06-09T22:30:38.686523Z","end":"2026-06-09T22:30:38.802046Z","steps":["trace[414132989] 'process raft request' (duration: 115.319419ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:31:16.10184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.147276ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T22:31:16.1019Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.313823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-06-09T22:31:16.10197Z","caller":"traceutil/trace.go:171","msg":"trace[703133825] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2415; }","duration":"374.295721ms","start":"2026-06-09T22:31:15.727656Z","end":"2026-06-09T22:31:16.101952Z","steps":["trace[703133825] 'range keys from in-memory index tree' (duration: 374.121155ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:31:16.101998Z","caller":"traceutil/trace.go:171","msg":"trace[447642180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2415; }","duration":"203.463506ms","start":"2026-06-09T22:31:15.898512Z","end":"2026-06-09T22:31:16.101976Z","steps":["trace[447642180] 'range keys from in-memory index tree' (duration: 203.142107ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:31:16.102222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.234509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-06-09T22:31:16.102302Z","caller":"traceutil/trace.go:171","msg":"trace[70068048] transaction","detail":"{read_only:false; response_revision:2416; number_of_response:1; }","duration":"153.780572ms","start":"2026-06-09T22:31:15.948491Z","end":"2026-06-09T22:31:16.102271Z","steps":["trace[70068048] 'process raft request' (duration: 153.006722ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:31:16.102358Z","caller":"traceutil/trace.go:171","msg":"trace[955314978] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2415; }","duration":"345.366612ms","start":"2026-06-09T22:31:15.756938Z","end":"2026-06-09T22:31:16.102305Z","steps":["trace[955314978] 'range keys from in-memory index tree' (duration: 345.084745ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:31:16.102411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:31:15.75692Z","time spent":"345.474715ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":556,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-06-09T22:31:16.152855Z","caller":"traceutil/trace.go:171","msg":"trace[627758445] transaction","detail":"{read_only:false; response_revision:2417; number_of_response:1; }","duration":"122.518025ms","start":"2026-06-09T22:31:16.03031Z","end":"2026-06-09T22:31:16.152828Z","steps":["trace[627758445] 'process raft request' (duration: 122.267318ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:31:23.650793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.077811ms","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-06-09T22:31:23.650897Z","caller":"traceutil/trace.go:171","msg":"trace[1688317712] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2458; }","duration":"138.236655ms","start":"2026-06-09T22:31:23.51264Z","end":"2026-06-09T22:31:23.650877Z","steps":["trace[1688317712] 'range keys from in-memory index tree' (duration: 137.834265ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T22:31:24.54682Z","caller":"traceutil/trace.go:171","msg":"trace[1856249261] linearizableReadLoop","detail":"{readStateIndex:2562; appliedIndex:2561; }","duration":"251.51099ms","start":"2026-06-09T22:31:24.295288Z","end":"2026-06-09T22:31:24.546799Z","steps":["trace[1856249261] 'read index received' (duration: 251.373407ms)","trace[1856249261] 'applied index is now lower than readState.Index' (duration: 136.383µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T22:31:24.54692Z","caller":"traceutil/trace.go:171","msg":"trace[1245223153] transaction","detail":"{read_only:false; response_revision:2461; number_of_response:1; }","duration":"354.000866ms","start":"2026-06-09T22:31:24.192912Z","end":"2026-06-09T22:31:24.546913Z","steps":["trace[1245223153] 'process raft request' (duration: 353.77153ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:31:24.547004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T22:31:24.192891Z","time spent":"354.047006ms","remote":"127.0.0.1:45638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T22:31:24.54723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.939071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-06-09T22:31:24.547313Z","caller":"traceutil/trace.go:171","msg":"trace[252070473] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2461; }","duration":"240.056304ms","start":"2026-06-09T22:31:24.30724Z","end":"2026-06-09T22:31:24.547296Z","steps":["trace[252070473] 'agreement among raft nodes before linearized reading' (duration: 239.747076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T22:31:24.547576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.995591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:531"} {"level":"info","ts":"2026-06-09T22:31:24.547725Z","caller":"traceutil/trace.go:171","msg":"trace[857053800] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2461; }","duration":"252.454493ms","start":"2026-06-09T22:31:24.295241Z","end":"2026-06-09T22:31:24.547695Z","steps":["trace[857053800] 'agreement among raft nodes before linearized reading' (duration: 251.750926ms)"],"step_count":1}