{"level":"warn","ts":"2026-07-01T00:50:28.637715Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T00:50:28.637846Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.115:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.115:2380","--initial-cluster=instance=https://199.204.45.115:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.115:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.115: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-07-01T00:50:28.638188Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-01T00:50:28.638209Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-07-01T00:50:28.638236Z","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-07-01T00:50:28.63905Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"]} {"level":"info","ts":"2026-07-01T00:50:28.6392Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.115: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-07-01T00:50:28.648367Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.805701ms"} {"level":"info","ts":"2026-07-01T00:50:28.657935Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e3aaa5c123e150e0","cluster-id":"61ec519698823541"} {"level":"info","ts":"2026-07-01T00:50:28.658008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=()"} {"level":"info","ts":"2026-07-01T00:50:28.65803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 0"} {"level":"info","ts":"2026-07-01T00:50:28.658042Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e3aaa5c123e150e0 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-01T00:50:28.658056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became follower at term 1"} {"level":"info","ts":"2026-07-01T00:50:28.658108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"warn","ts":"2026-07-01T00:50:28.667599Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-01T00:50:28.670378Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-01T00:50:28.67199Z","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-07-01T00:50:28.675373Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e3aaa5c123e150e0","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-01T00:50:28.677213Z","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-07-01T00:50:28.677299Z","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-07-01T00:50:28.677315Z","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-07-01T00:50:28.67775Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e3aaa5c123e150e0","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-01T00:50:28.679008Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 switched to configuration voters=(16405106841599693024)"} {"level":"info","ts":"2026-07-01T00:50:28.68277Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","added-peer-id":"e3aaa5c123e150e0","added-peer-peer-urls":["https://199.204.45.115:2380"]} {"level":"info","ts":"2026-07-01T00:50:28.683404Z","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-07-01T00:50:28.683518Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-07-01T00:50:28.683557Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.115:2380"} {"level":"info","ts":"2026-07-01T00:50:28.683744Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e3aaa5c123e150e0","initial-advertise-peer-urls":["https://199.204.45.115:2380"],"listen-peer-urls":["https://199.204.45.115:2380"],"advertise-client-urls":["https://199.204.45.115:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.115:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-01T00:50:28.683782Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-01T00:50:29.459133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 is starting a new election at term 1"} {"level":"info","ts":"2026-07-01T00:50:29.459201Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-01T00:50:29.459239Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgPreVoteResp from e3aaa5c123e150e0 at term 1"} {"level":"info","ts":"2026-07-01T00:50:29.459261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became candidate at term 2"} {"level":"info","ts":"2026-07-01T00:50:29.459272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 received MsgVoteResp from e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-07-01T00:50:29.459288Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e3aaa5c123e150e0 became leader at term 2"} {"level":"info","ts":"2026-07-01T00:50:29.459309Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e3aaa5c123e150e0 elected leader e3aaa5c123e150e0 at term 2"} {"level":"info","ts":"2026-07-01T00:50:29.718152Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e3aaa5c123e150e0","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.115:2379]}","request-path":"/0/members/e3aaa5c123e150e0/attributes","cluster-id":"61ec519698823541","publish-timeout":"7s"} {"level":"info","ts":"2026-07-01T00:50:29.718166Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:50:29.718382Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T00:50:29.718507Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-01T00:50:29.718534Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-01T00:50:29.718598Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-01T00:50:29.722541Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-01T00:50:29.725602Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.115:2379"} {"level":"info","ts":"2026-07-01T00:50:29.762852Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"61ec519698823541","local-member-id":"e3aaa5c123e150e0","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:50:29.763077Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-01T00:50:29.763131Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-07-01T00:50:30.051241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.632469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:50:30.051341Z","caller":"traceutil/trace.go:171","msg":"trace[737662955] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"188.737522ms","start":"2026-07-01T00:50:29.862575Z","end":"2026-07-01T00:50:30.051312Z","steps":["trace[737662955] 'count revisions from in-memory index tree' (duration: 188.382053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:50:30.051422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.53494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:50:30.051499Z","caller":"traceutil/trace.go:171","msg":"trace[1335434489] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"107.644893ms","start":"2026-07-01T00:50:29.943838Z","end":"2026-07-01T00:50:30.051483Z","steps":["trace[1335434489] 'count revisions from in-memory index tree' (duration: 107.461138ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:50:30.051671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.724875ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:50:30.051699Z","caller":"traceutil/trace.go:171","msg":"trace[99112884] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:0; response_revision:1; }","duration":"107.785627ms","start":"2026-07-01T00:50:29.943905Z","end":"2026-07-01T00:50:30.05169Z","steps":["trace[99112884] 'range keys from in-memory index tree' (duration: 107.660503ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:50:30.051876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.410906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-01T00:50:30.051224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.875986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:50:30.052015Z","caller":"traceutil/trace.go:171","msg":"trace[986680358] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"177.57249ms","start":"2026-07-01T00:50:29.874426Z","end":"2026-07-01T00:50:30.051998Z","steps":["trace[986680358] 'count revisions from in-memory index tree' (duration: 177.365055ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:50:30.052077Z","caller":"traceutil/trace.go:171","msg":"trace[1405934910] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"189.745308ms","start":"2026-07-01T00:50:29.862294Z","end":"2026-07-01T00:50:30.052039Z","steps":["trace[1405934910] 'range keys from in-memory index tree' (duration: 188.381393ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:50:30.052176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.617411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-01T00:50:30.051181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.572898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-01T00:50:30.052246Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.646628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:50:30.052272Z","caller":"traceutil/trace.go:171","msg":"trace[994184059] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"165.6856ms","start":"2026-07-01T00:50:29.886579Z","end":"2026-07-01T00:50:30.052265Z","steps":["trace[994184059] 'range keys from in-memory index tree' (duration: 165.596727ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:50:30.052265Z","caller":"traceutil/trace.go:171","msg":"trace[28838193] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"119.701347ms","start":"2026-07-01T00:50:29.932539Z","end":"2026-07-01T00:50:30.052241Z","steps":["trace[28838193] 'range keys from in-memory index tree' (duration: 118.457746ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:50:30.052208Z","caller":"traceutil/trace.go:171","msg":"trace[29340574] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"177.679232ms","start":"2026-07-01T00:50:29.874519Z","end":"2026-07-01T00:50:30.052198Z","steps":["trace[29340574] 'range keys from in-memory index tree' (duration: 177.503729ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:50:30.052857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.500896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-01T00:50:30.052861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.243202ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:50:30.052904Z","caller":"traceutil/trace.go:171","msg":"trace[841196270] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:1; }","duration":"120.300923ms","start":"2026-07-01T00:50:29.93259Z","end":"2026-07-01T00:50:30.052891Z","steps":["trace[841196270] 'count revisions from in-memory index tree' (duration: 120.203001ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:50:30.052914Z","caller":"traceutil/trace.go:171","msg":"trace[747353803] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"144.586238ms","start":"2026-07-01T00:50:29.9083Z","end":"2026-07-01T00:50:30.052886Z","steps":["trace[747353803] 'count revisions from in-memory index tree' (duration: 144.457435ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:50:30.052923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.328639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:50:30.052968Z","caller":"traceutil/trace.go:171","msg":"trace[1406194377] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"155.424441ms","start":"2026-07-01T00:50:29.897531Z","end":"2026-07-01T00:50:30.052955Z","steps":["trace[1406194377] 'range keys from in-memory index tree' (duration: 155.276938ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:50:30.052994Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.834691ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:50:30.053036Z","caller":"traceutil/trace.go:171","msg":"trace[855936285] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"132.886703ms","start":"2026-07-01T00:50:29.920138Z","end":"2026-07-01T00:50:30.053025Z","steps":["trace[855936285] 'range keys from in-memory index tree' (duration: 132.79793ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:50:30.053121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.240817ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:50:30.053154Z","caller":"traceutil/trace.go:171","msg":"trace[1818592814] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"155.281918ms","start":"2026-07-01T00:50:29.897862Z","end":"2026-07-01T00:50:30.053144Z","steps":["trace[1818592814] 'count revisions from in-memory index tree' (duration: 155.055192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:50:30.053234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.953274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-01T00:50:30.053162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.001729ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/\" range_end:\"/registry/persistentvolumes0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:50:30.053271Z","caller":"traceutil/trace.go:171","msg":"trace[1700239707] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:1; }","duration":"133.016166ms","start":"2026-07-01T00:50:29.920244Z","end":"2026-07-01T00:50:30.05326Z","steps":["trace[1700239707] 'count revisions from in-memory index tree' (duration: 132.892853ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:50:30.053283Z","caller":"traceutil/trace.go:171","msg":"trace[1645812950] range","detail":"{range_begin:/registry/persistentvolumes/; range_end:/registry/persistentvolumes0; response_count:0; response_revision:1; }","duration":"145.138722ms","start":"2026-07-01T00:50:29.908131Z","end":"2026-07-01T00:50:30.05327Z","steps":["trace[1645812950] 'range keys from in-memory index tree' (duration: 144.941158ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:50:30.053233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.432139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-01T00:50:30.053382Z","caller":"traceutil/trace.go:171","msg":"trace[1641762123] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"166.637655ms","start":"2026-07-01T00:50:29.886735Z","end":"2026-07-01T00:50:30.053373Z","steps":["trace[1641762123] 'count revisions from in-memory index tree' (duration: 166.326557ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:50:56.042428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.443819ms","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-07-01T00:50:56.042533Z","caller":"traceutil/trace.go:171","msg":"trace[1212529613] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:428; }","duration":"266.609423ms","start":"2026-07-01T00:50:55.7759Z","end":"2026-07-01T00:50:56.042509Z","steps":["trace[1212529613] 'range keys from in-memory index tree' (duration: 266.307005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:50:56.04258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.923627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:50:56.042695Z","caller":"traceutil/trace.go:171","msg":"trace[1607789540] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:428; }","duration":"419.097731ms","start":"2026-07-01T00:50:55.623573Z","end":"2026-07-01T00:50:56.042671Z","steps":["trace[1607789540] 'range keys from in-memory index tree' (duration: 418.822964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:50:56.042675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.778468ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-01T00:50:56.042743Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:50:55.623554Z","time spent":"419.180744ms","remote":"127.0.0.1:37218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T00:50:56.042778Z","caller":"traceutil/trace.go:171","msg":"trace[842223800] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:428; }","duration":"266.890921ms","start":"2026-07-01T00:50:55.775844Z","end":"2026-07-01T00:50:56.042734Z","steps":["trace[842223800] 'range keys from in-memory index tree' (duration: 266.685115ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:50:56.305509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.751928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:50:56.30565Z","caller":"traceutil/trace.go:171","msg":"trace[1834180809] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"257.626765ms","start":"2026-07-01T00:50:56.04801Z","end":"2026-07-01T00:50:56.305637Z","steps":["trace[1834180809] 'process raft request' (duration: 102.282261ms)","trace[1834180809] 'compare' (duration: 154.525853ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:51:01.568585Z","caller":"traceutil/trace.go:171","msg":"trace[959069831] linearizableReadLoop","detail":"{readStateIndex:456; appliedIndex:455; }","duration":"108.038214ms","start":"2026-07-01T00:51:01.460522Z","end":"2026-07-01T00:51:01.56856Z","steps":["trace[959069831] 'read index received' (duration: 107.832078ms)","trace[959069831] 'applied index is now lower than readState.Index' (duration: 205.086µs)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:51:01.568709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.173497ms","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-07-01T00:51:01.568752Z","caller":"traceutil/trace.go:171","msg":"trace[590928516] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:439; }","duration":"108.244789ms","start":"2026-07-01T00:51:01.460484Z","end":"2026-07-01T00:51:01.568729Z","steps":["trace[590928516] 'agreement among raft nodes before linearized reading' (duration: 108.170107ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:01.568752Z","caller":"traceutil/trace.go:171","msg":"trace[1950122767] transaction","detail":"{read_only:false; response_revision:439; number_of_response:1; }","duration":"171.4784ms","start":"2026-07-01T00:51:01.397252Z","end":"2026-07-01T00:51:01.56873Z","steps":["trace[1950122767] 'process raft request' (duration: 171.179852ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:07.318283Z","caller":"traceutil/trace.go:171","msg":"trace[907876056] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"441.609235ms","start":"2026-07-01T00:51:06.876623Z","end":"2026-07-01T00:51:07.318233Z","steps":["trace[907876056] 'process raft request' (duration: 441.42702ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:07.318421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:06.876594Z","time spent":"441.7596ms","remote":"127.0.0.1:37282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:51:07.435466Z","caller":"traceutil/trace.go:171","msg":"trace[1343785341] transaction","detail":"{read_only:false; response_revision:494; number_of_response:1; }","duration":"541.821154ms","start":"2026-07-01T00:51:06.893623Z","end":"2026-07-01T00:51:07.435439Z","steps":["trace[1343785341] 'process raft request' (duration: 541.556857ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:07.435537Z","caller":"traceutil/trace.go:171","msg":"trace[1685402361] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"365.899547ms","start":"2026-07-01T00:51:07.069614Z","end":"2026-07-01T00:51:07.435513Z","steps":["trace[1685402361] 'process raft request' (duration: 365.852585ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:07.435634Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:06.893615Z","time spent":"541.947137ms","remote":"127.0.0.1:37282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:51:07.435777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:07.069595Z","time spent":"366.05229ms","remote":"127.0.0.1:37282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:51:07.435821Z","caller":"traceutil/trace.go:171","msg":"trace[401171574] transaction","detail":"{read_only:false; response_revision:490; number_of_response:1; }","duration":"549.170998ms","start":"2026-07-01T00:51:06.886639Z","end":"2026-07-01T00:51:07.43581Z","steps":["trace[401171574] 'process raft request' (duration: 548.266004ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:07.436051Z","caller":"traceutil/trace.go:171","msg":"trace[1135336574] transaction","detail":"{read_only:false; response_revision:493; number_of_response:1; }","duration":"542.612244ms","start":"2026-07-01T00:51:06.893428Z","end":"2026-07-01T00:51:07.436041Z","steps":["trace[1135336574] 'process raft request' (duration: 541.725191ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:07.43606Z","caller":"traceutil/trace.go:171","msg":"trace[427415153] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"535.475446ms","start":"2026-07-01T00:51:06.900366Z","end":"2026-07-01T00:51:07.435841Z","steps":["trace[427415153] 'process raft request' (duration: 534.87883ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:07.43617Z","caller":"traceutil/trace.go:171","msg":"trace[1307374856] transaction","detail":"{read_only:false; response_revision:492; number_of_response:1; }","duration":"543.9289ms","start":"2026-07-01T00:51:06.892232Z","end":"2026-07-01T00:51:07.436161Z","steps":["trace[1307374856] 'process raft request' (duration: 542.891402ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:07.436171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:06.88661Z","time spent":"549.460645ms","remote":"127.0.0.1:37282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:51:07.436218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:06.892203Z","time spent":"543.995792ms","remote":"127.0.0.1:37282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:51:07.436154Z","caller":"traceutil/trace.go:171","msg":"trace[1812482022] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"521.025994ms","start":"2026-07-01T00:51:06.915044Z","end":"2026-07-01T00:51:07.43607Z","steps":["trace[1812482022] 'process raft request' (duration: 520.261564ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:07.436312Z","caller":"traceutil/trace.go:171","msg":"trace[776210241] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"460.30243ms","start":"2026-07-01T00:51:06.975958Z","end":"2026-07-01T00:51:07.436261Z","steps":["trace[776210241] 'process raft request' (duration: 459.392916ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:07.43582Z","caller":"traceutil/trace.go:171","msg":"trace[630869979] transaction","detail":"{read_only:false; response_revision:495; number_of_response:1; }","duration":"536.76574ms","start":"2026-07-01T00:51:06.899024Z","end":"2026-07-01T00:51:07.435789Z","steps":["trace[630869979] 'process raft request' (duration: 536.188125ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:07.436405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:06.975941Z","time spent":"460.403302ms","remote":"127.0.0.1:37282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:51:07.436423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:06.899013Z","time spent":"537.381307ms","remote":"127.0.0.1:37282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:51:07.435771Z","caller":"traceutil/trace.go:171","msg":"trace[943079788] transaction","detail":"{read_only:false; response_revision:491; number_of_response:1; }","duration":"546.57842ms","start":"2026-07-01T00:51:06.889171Z","end":"2026-07-01T00:51:07.435749Z","steps":["trace[943079788] 'process raft request' (duration: 545.854611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:07.436461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:06.900359Z","time spent":"535.903577ms","remote":"127.0.0.1:37282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:51:07.436497Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:06.915034Z","time spent":"521.304551ms","remote":"127.0.0.1:37282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:51:07.43613Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:06.89342Z","time spent":"542.665496ms","remote":"127.0.0.1:37282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:51:07.43658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:06.889162Z","time spent":"547.37449ms","remote":"127.0.0.1:37282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:51:07.436878Z","caller":"traceutil/trace.go:171","msg":"trace[873889981] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"531.413539ms","start":"2026-07-01T00:51:06.904385Z","end":"2026-07-01T00:51:07.435799Z","steps":["trace[873889981] 'process raft request' (duration: 530.884315ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:07.437058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:06.904376Z","time spent":"532.554029ms","remote":"127.0.0.1:37282","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:51:07.440144Z","caller":"traceutil/trace.go:171","msg":"trace[544932205] transaction","detail":"{read_only:false; number_of_response:1; response_revision:503; }","duration":"110.93267ms","start":"2026-07-01T00:51:07.32919Z","end":"2026-07-01T00:51:07.440122Z","steps":["trace[544932205] 'process raft request' (duration: 110.894759ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:07.44042Z","caller":"traceutil/trace.go:171","msg":"trace[1334039203] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"115.706557ms","start":"2026-07-01T00:51:07.324702Z","end":"2026-07-01T00:51:07.440409Z","steps":["trace[1334039203] 'process raft request' (duration: 115.207184ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:07.440476Z","caller":"traceutil/trace.go:171","msg":"trace[1734192126] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"115.191683ms","start":"2026-07-01T00:51:07.325272Z","end":"2026-07-01T00:51:07.440464Z","steps":["trace[1734192126] 'process raft request' (duration: 114.712841ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:07.440575Z","caller":"traceutil/trace.go:171","msg":"trace[2048856790] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"112.004579ms","start":"2026-07-01T00:51:07.328562Z","end":"2026-07-01T00:51:07.440567Z","steps":["trace[2048856790] 'process raft request' (duration: 111.473325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:14.255755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"409.986899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-ggcnv\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-01T00:51:14.255858Z","caller":"traceutil/trace.go:171","msg":"trace[727290631] range","detail":"{range_begin:/registry/pods/kube-system/cilium-ggcnv; range_end:; response_count:1; response_revision:598; }","duration":"410.141304ms","start":"2026-07-01T00:51:13.845694Z","end":"2026-07-01T00:51:14.255835Z","steps":["trace[727290631] 'range keys from in-memory index tree' (duration: 409.801525ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:14.255912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:13.845671Z","time spent":"410.229936ms","remote":"127.0.0.1:35688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-ggcnv\" "} {"level":"warn","ts":"2026-07-01T00:51:14.255929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.447855ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:51:14.256093Z","caller":"traceutil/trace.go:171","msg":"trace[754896204] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:598; }","duration":"175.6278ms","start":"2026-07-01T00:51:14.080442Z","end":"2026-07-01T00:51:14.25607Z","steps":["trace[754896204] 'range keys from in-memory index tree' (duration: 175.427725ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:14.466905Z","caller":"traceutil/trace.go:171","msg":"trace[1061025430] linearizableReadLoop","detail":"{readStateIndex:623; appliedIndex:622; }","duration":"222.830357ms","start":"2026-07-01T00:51:14.244049Z","end":"2026-07-01T00:51:14.466879Z","steps":["trace[1061025430] 'read index received' (duration: 216.873919ms)","trace[1061025430] 'applied index is now lower than readState.Index' (duration: 5.955138ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:51:14.466993Z","caller":"traceutil/trace.go:171","msg":"trace[1693226274] transaction","detail":"{read_only:false; response_revision:599; number_of_response:1; }","duration":"467.434317ms","start":"2026-07-01T00:51:13.999529Z","end":"2026-07-01T00:51:14.466963Z","steps":["trace[1693226274] 'process raft request' (duration: 460.966736ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:14.467149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:13.999479Z","time spent":"467.592072ms","remote":"127.0.0.1:37334","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:51:14.467186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.128914ms","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-07-01T00:51:14.467257Z","caller":"traceutil/trace.go:171","msg":"trace[1935987798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:599; }","duration":"223.263919ms","start":"2026-07-01T00:51:14.243982Z","end":"2026-07-01T00:51:14.467246Z","steps":["trace[1935987798] 'agreement among raft nodes before linearized reading' (duration: 223.000092ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:14.504321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.593589ms","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-07-01T00:51:14.504411Z","caller":"traceutil/trace.go:171","msg":"trace[1762714184] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:600; }","duration":"122.739693ms","start":"2026-07-01T00:51:14.381646Z","end":"2026-07-01T00:51:14.504386Z","steps":["trace[1762714184] 'agreement among raft nodes before linearized reading' (duration: 122.493486ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:14.50432Z","caller":"traceutil/trace.go:171","msg":"trace[915125590] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"232.79771ms","start":"2026-07-01T00:51:14.271464Z","end":"2026-07-01T00:51:14.504262Z","steps":["trace[915125590] 'process raft request' (duration: 232.509493ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:15.125544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832856800048977,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T00:51:15.626418Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832856800048977,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T00:51:15.830247Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.243481359s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-01T00:51:15.833982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.2468956s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:51:15.83423Z","caller":"traceutil/trace.go:171","msg":"trace[949280949] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"1.324565571s","start":"2026-07-01T00:51:14.509638Z","end":"2026-07-01T00:51:15.834203Z","steps":["trace[949280949] 'process raft request' (duration: 1.324441097s)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:15.83423Z","caller":"traceutil/trace.go:171","msg":"trace[1526402910] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.362598736s","start":"2026-07-01T00:51:14.471586Z","end":"2026-07-01T00:51:15.834185Z","steps":["trace[1526402910] 'process raft request' (duration: 115.357948ms)","trace[1526402910] 'compare' (duration: 1.246525699s)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:51:15.834424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:14.509605Z","time spent":"1.324715135s","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:51:15.834424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:14.471564Z","time spent":"1.36275552s","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:51:15.834606Z","caller":"traceutil/trace.go:171","msg":"trace[213769579] linearizableReadLoop","detail":"{readStateIndex:628; appliedIndex:624; }","duration":"1.210087276s","start":"2026-07-01T00:51:14.62449Z","end":"2026-07-01T00:51:15.834577Z","steps":["trace[213769579] 'read index received' (duration: 1.20604209s)","trace[213769579] 'applied index is now lower than readState.Index' (duration: 4.044036ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:51:15.834782Z","caller":"traceutil/trace.go:171","msg":"trace[1394019289] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"1.310332065s","start":"2026-07-01T00:51:14.524433Z","end":"2026-07-01T00:51:15.834765Z","steps":["trace[1394019289] 'process raft request' (duration: 1.309705279s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:15.834875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.210374974s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:51:15.834959Z","caller":"traceutil/trace.go:171","msg":"trace[1569650532] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:604; }","duration":"1.210503438s","start":"2026-07-01T00:51:14.624427Z","end":"2026-07-01T00:51:15.83493Z","steps":["trace[1569650532] 'agreement among raft nodes before linearized reading' (duration: 1.210343764s)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:15.835008Z","caller":"traceutil/trace.go:171","msg":"trace[2123383496] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"249.274365ms","start":"2026-07-01T00:51:15.585706Z","end":"2026-07-01T00:51:15.834981Z","steps":["trace[2123383496] 'process raft request' (duration: 248.774572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:15.83505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:14.62439Z","time spent":"1.210649292s","remote":"127.0.0.1:37218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T00:51:15.835051Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.101632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-01T00:51:15.835289Z","caller":"traceutil/trace.go:171","msg":"trace[1112363908] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"179.189743ms","start":"2026-07-01T00:51:15.655899Z","end":"2026-07-01T00:51:15.835089Z","steps":["trace[1112363908] 'agreement among raft nodes before linearized reading' (duration: 179.025749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:15.834887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:14.5244Z","time spent":"1.310413408s","remote":"127.0.0.1:35738","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:51:15.835403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.423449ms","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-07-01T00:51:15.835493Z","caller":"traceutil/trace.go:171","msg":"trace[1077405682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:604; }","duration":"168.550682ms","start":"2026-07-01T00:51:15.666921Z","end":"2026-07-01T00:51:15.835471Z","steps":["trace[1077405682] 'agreement among raft nodes before linearized reading' (duration: 168.068269ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:17.349668Z","caller":"traceutil/trace.go:171","msg":"trace[2075101078] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"353.909089ms","start":"2026-07-01T00:51:16.995615Z","end":"2026-07-01T00:51:17.349524Z","steps":["trace[2075101078] 'process raft request' (duration: 353.509669ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:17.349968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:51:16.995594Z","time spent":"354.254479ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-01T00:51:29.66978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.391446ms","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-07-01T00:51:29.669953Z","caller":"traceutil/trace.go:171","msg":"trace[345285906] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:757; }","duration":"216.661744ms","start":"2026-07-01T00:51:29.453272Z","end":"2026-07-01T00:51:29.669933Z","steps":["trace[345285906] 'range keys from in-memory index tree' (duration: 216.12591ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:36.052779Z","caller":"traceutil/trace.go:171","msg":"trace[715895191] linearizableReadLoop","detail":"{readStateIndex:854; appliedIndex:852; }","duration":"103.456173ms","start":"2026-07-01T00:51:35.94929Z","end":"2026-07-01T00:51:36.052746Z","steps":["trace[715895191] 'read index received' (duration: 36.413042ms)","trace[715895191] 'applied index is now lower than readState.Index' (duration: 67.041701ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:51:36.052892Z","caller":"traceutil/trace.go:171","msg":"trace[916524461] transaction","detail":"{read_only:false; response_revision:824; number_of_response:1; }","duration":"105.22654ms","start":"2026-07-01T00:51:35.947639Z","end":"2026-07-01T00:51:36.052866Z","steps":["trace[916524461] 'process raft request' (duration: 105.024675ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:51:36.052907Z","caller":"traceutil/trace.go:171","msg":"trace[1016331538] transaction","detail":"{read_only:false; response_revision:823; number_of_response:1; }","duration":"106.227487ms","start":"2026-07-01T00:51:35.946646Z","end":"2026-07-01T00:51:36.052874Z","steps":["trace[1016331538] 'process raft request' (duration: 39.047702ms)","trace[1016331538] 'compare' (duration: 66.805775ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:51:36.053122Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.801812ms","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-07-01T00:51:36.053192Z","caller":"traceutil/trace.go:171","msg":"trace[599232549] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:824; }","duration":"103.910415ms","start":"2026-07-01T00:51:35.94926Z","end":"2026-07-01T00:51:36.053171Z","steps":["trace[599232549] 'agreement among raft nodes before linearized reading' (duration: 103.68918ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:51:36.10461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.507559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-01T00:51:36.104712Z","caller":"traceutil/trace.go:171","msg":"trace[1651958834] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:825; }","duration":"152.620492ms","start":"2026-07-01T00:51:35.952065Z","end":"2026-07-01T00:51:36.104686Z","steps":["trace[1651958834] 'agreement among raft nodes before linearized reading' (duration: 152.357005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:04.729345Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832856800049728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T00:52:05.229653Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832856800049728,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-01T00:52:05.40943Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.401367132s","expected-duration":"1s"} {"level":"info","ts":"2026-07-01T00:52:05.409763Z","caller":"traceutil/trace.go:171","msg":"trace[670589417] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"1.401823085s","start":"2026-07-01T00:52:04.007922Z","end":"2026-07-01T00:52:05.409745Z","steps":["trace[670589417] 'process raft request' (duration: 1.401688731s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:05.409888Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:04.007885Z","time spent":"1.401931438s","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:52:06.251788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"324.897233ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:52:06.251895Z","caller":"traceutil/trace.go:171","msg":"trace[397212664] linearizableReadLoop","detail":"{readStateIndex:1009; appliedIndex:1007; }","duration":"2.023543374s","start":"2026-07-01T00:52:04.228332Z","end":"2026-07-01T00:52:06.251875Z","steps":["trace[397212664] 'read index received' (duration: 1.18130957s)","trace[397212664] 'applied index is now lower than readState.Index' (duration: 842.232604ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:06.251983Z","caller":"traceutil/trace.go:171","msg":"trace[283272252] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"1.871719276s","start":"2026-07-01T00:52:04.380253Z","end":"2026-07-01T00:52:06.251972Z","steps":["trace[283272252] 'process raft request' (duration: 1.546534195s)","trace[283272252] 'compare' (duration: 324.662927ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:52:06.252041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:04.380221Z","time spent":"1.871787738s","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:52:06.252363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.024029356s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-01T00:52:06.252669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.868254045s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-01T00:52:06.25274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.627844606s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:52:06.252786Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.646092ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:52:06.252792Z","caller":"traceutil/trace.go:171","msg":"trace[1168980588] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:970; }","duration":"1.86842762s","start":"2026-07-01T00:52:04.384336Z","end":"2026-07-01T00:52:06.252763Z","steps":["trace[1168980588] 'agreement among raft nodes before linearized reading' (duration: 1.86806146s)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:06.252842Z","caller":"traceutil/trace.go:171","msg":"trace[1296076075] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:970; }","duration":"121.761535ms","start":"2026-07-01T00:52:06.131065Z","end":"2026-07-01T00:52:06.252827Z","steps":["trace[1296076075] 'agreement among raft nodes before linearized reading' (duration: 121.629201ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:06.252829Z","caller":"traceutil/trace.go:171","msg":"trace[605994322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:970; }","duration":"1.627931768s","start":"2026-07-01T00:52:04.624879Z","end":"2026-07-01T00:52:06.252811Z","steps":["trace[605994322] 'agreement among raft nodes before linearized reading' (duration: 1.627794255s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:06.252858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:04.384322Z","time spent":"1.868520162s","remote":"127.0.0.1:35760","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-07-01T00:52:06.252898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:04.624819Z","time spent":"1.628069462s","remote":"127.0.0.1:37218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T00:52:06.253174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.793672862s","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-07-01T00:52:06.253163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.865494643s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-01T00:52:06.253245Z","caller":"traceutil/trace.go:171","msg":"trace[1946662757] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:970; }","duration":"1.793760154s","start":"2026-07-01T00:52:04.459462Z","end":"2026-07-01T00:52:06.253222Z","steps":["trace[1946662757] 'agreement among raft nodes before linearized reading' (duration: 1.793311032s)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:06.253255Z","caller":"traceutil/trace.go:171","msg":"trace[598146830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:970; }","duration":"1.865635406s","start":"2026-07-01T00:52:04.387597Z","end":"2026-07-01T00:52:06.253233Z","steps":["trace[598146830] 'agreement among raft nodes before linearized reading' (duration: 1.865238416s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:06.253292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:04.459448Z","time spent":"1.793835007s","remote":"127.0.0.1:35760","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-07-01T00:52:06.253279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"912.642677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:52:06.253303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:04.387587Z","time spent":"1.865703548s","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T00:52:06.253343Z","caller":"traceutil/trace.go:171","msg":"trace[580993021] range","detail":"{range_begin:/registry/statefulsets/; range_end:/registry/statefulsets0; response_count:0; response_revision:970; }","duration":"912.76785ms","start":"2026-07-01T00:52:05.340559Z","end":"2026-07-01T00:52:06.253327Z","steps":["trace[580993021] 'agreement among raft nodes before linearized reading' (duration: 912.645907ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:06.253383Z","caller":"traceutil/trace.go:171","msg":"trace[1658495124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:970; }","duration":"2.024111938s","start":"2026-07-01T00:52:04.228275Z","end":"2026-07-01T00:52:06.252387Z","steps":["trace[1658495124] 'agreement among raft nodes before linearized reading' (duration: 2.023963414s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:06.253389Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:05.340539Z","time spent":"912.835682ms","remote":"127.0.0.1:35938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":0,"response size":28,"request content":"key:\"/registry/statefulsets/\" range_end:\"/registry/statefulsets0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:52:06.253503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:04.228243Z","time spent":"2.025247718s","remote":"127.0.0.1:35760","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":"info","ts":"2026-07-01T00:52:13.577443Z","caller":"traceutil/trace.go:171","msg":"trace[1486473991] transaction","detail":"{read_only:false; response_revision:996; number_of_response:1; }","duration":"128.086382ms","start":"2026-07-01T00:52:13.44933Z","end":"2026-07-01T00:52:13.577417Z","steps":["trace[1486473991] 'process raft request' (duration: 127.910687ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:30.818421Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.156968839s","expected-duration":"1s"} {"level":"info","ts":"2026-07-01T00:52:30.818817Z","caller":"traceutil/trace.go:171","msg":"trace[408209208] transaction","detail":"{read_only:false; response_revision:1048; number_of_response:1; }","duration":"1.158034777s","start":"2026-07-01T00:52:29.660753Z","end":"2026-07-01T00:52:30.818788Z","steps":["trace[408209208] 'process raft request' (duration: 1.157862323s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:30.819004Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:29.660734Z","time spent":"1.158175231s","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:52:30.954298Z","caller":"traceutil/trace.go:171","msg":"trace[287839878] linearizableReadLoop","detail":"{readStateIndex:1093; appliedIndex:1091; }","duration":"587.085056ms","start":"2026-07-01T00:52:30.367177Z","end":"2026-07-01T00:52:30.954262Z","steps":["trace[287839878] 'read index received' (duration: 451.526527ms)","trace[287839878] 'applied index is now lower than readState.Index' (duration: 135.543149ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:30.954482Z","caller":"traceutil/trace.go:171","msg":"trace[1709182917] transaction","detail":"{read_only:false; response_revision:1049; number_of_response:1; }","duration":"614.453017ms","start":"2026-07-01T00:52:30.339984Z","end":"2026-07-01T00:52:30.954437Z","steps":["trace[1709182917] 'process raft request' (duration: 524.9617ms)","trace[1709182917] 'compare' (duration: 88.758767ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:52:30.954633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"563.716301ms","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-07-01T00:52:30.954695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:30.339954Z","time spent":"614.622191ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:52:30.954713Z","caller":"traceutil/trace.go:171","msg":"trace[55535425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1049; }","duration":"563.832744ms","start":"2026-07-01T00:52:30.390862Z","end":"2026-07-01T00:52:30.954695Z","steps":["trace[55535425] 'agreement among raft nodes before linearized reading' (duration: 563.591408ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:30.954744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.608588ms","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-07-01T00:52:30.95482Z","caller":"traceutil/trace.go:171","msg":"trace[1787574221] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1049; }","duration":"574.736621ms","start":"2026-07-01T00:52:30.38007Z","end":"2026-07-01T00:52:30.954806Z","steps":["trace[1787574221] 'agreement among raft nodes before linearized reading' (duration: 574.367342ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:30.954634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"587.439076ms","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-07-01T00:52:30.954866Z","caller":"traceutil/trace.go:171","msg":"trace[149404292] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1049; }","duration":"587.736543ms","start":"2026-07-01T00:52:30.367115Z","end":"2026-07-01T00:52:30.954852Z","steps":["trace[149404292] 'agreement among raft nodes before linearized reading' (duration: 587.308192ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:30.954898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:30.367093Z","time spent":"587.799245ms","remote":"127.0.0.1:35760","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-07-01T00:52:30.955149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:30.380056Z","time spent":"575.07937ms","remote":"127.0.0.1:35760","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-07-01T00:52:30.955164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.176119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-01T00:52:30.95523Z","caller":"traceutil/trace.go:171","msg":"trace[1928185175] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1049; }","duration":"565.330614ms","start":"2026-07-01T00:52:30.389886Z","end":"2026-07-01T00:52:30.955217Z","steps":["trace[1928185175] 'agreement among raft nodes before linearized reading' (duration: 564.726978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:30.955263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:30.389857Z","time spent":"565.399255ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T00:52:30.954821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:30.390852Z","time spent":"563.955107ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T00:52:32.946579Z","caller":"traceutil/trace.go:171","msg":"trace[932204705] linearizableReadLoop","detail":"{readStateIndex:1098; appliedIndex:1097; }","duration":"108.693312ms","start":"2026-07-01T00:52:32.83786Z","end":"2026-07-01T00:52:32.946553Z","steps":["trace[932204705] 'read index received' (duration: 108.494196ms)","trace[932204705] 'applied index is now lower than readState.Index' (duration: 197.886µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:32.946721Z","caller":"traceutil/trace.go:171","msg":"trace[577685803] transaction","detail":"{read_only:false; response_revision:1054; number_of_response:1; }","duration":"119.037713ms","start":"2026-07-01T00:52:32.827649Z","end":"2026-07-01T00:52:32.946687Z","steps":["trace[577685803] 'process raft request' (duration: 118.710095ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:32.946926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.041681ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-01T00:52:32.947135Z","caller":"traceutil/trace.go:171","msg":"trace[1558567360] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:1054; }","duration":"109.288287ms","start":"2026-07-01T00:52:32.837809Z","end":"2026-07-01T00:52:32.947097Z","steps":["trace[1558567360] 'agreement among raft nodes before linearized reading' (duration: 108.937658ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:34.635107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"554.056206ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:52:34.635273Z","caller":"traceutil/trace.go:171","msg":"trace[1147734717] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:1054; }","duration":"554.231711ms","start":"2026-07-01T00:52:34.081017Z","end":"2026-07-01T00:52:34.635249Z","steps":["trace[1147734717] 'range keys from in-memory index tree' (duration: 554.040886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:34.635844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.635168119s","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:40"} {"level":"warn","ts":"2026-07-01T00:52:34.636006Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:32.948184Z","time spent":"1.687816335s","remote":"127.0.0.1:37264","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-01T00:52:34.636112Z","caller":"traceutil/trace.go:171","msg":"trace[1865570820] linearizableReadLoop","detail":"{readStateIndex:1100; appliedIndex:1098; }","duration":"1.609286568s","start":"2026-07-01T00:52:33.026807Z","end":"2026-07-01T00:52:34.636094Z","steps":["trace[1865570820] 'read index received' (duration: 161.267716ms)","trace[1865570820] 'applied index is now lower than readState.Index' (duration: 1.448017782s)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:34.636311Z","caller":"traceutil/trace.go:171","msg":"trace[473956455] transaction","detail":"{read_only:false; response_revision:1055; number_of_response:1; }","duration":"1.676118727s","start":"2026-07-01T00:52:32.960181Z","end":"2026-07-01T00:52:34.6363Z","steps":["trace[473956455] 'process raft request' (duration: 1.675758637s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:34.636399Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:32.960162Z","time spent":"1.676175799s","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:52:34.636768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.609959005s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-07-01T00:52:34.63684Z","caller":"traceutil/trace.go:171","msg":"trace[2139718451] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1055; }","duration":"1.610035557s","start":"2026-07-01T00:52:33.026787Z","end":"2026-07-01T00:52:34.636823Z","steps":["trace[2139718451] 'agreement among raft nodes before linearized reading' (duration: 1.609634237s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:34.636891Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:33.026729Z","time spent":"1.610146881s","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T00:52:34.702781Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.294005617s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-01T00:52:34.703262Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.903705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:52:34.70344Z","caller":"traceutil/trace.go:171","msg":"trace[58717592] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:1056; }","duration":"469.017898ms","start":"2026-07-01T00:52:34.234295Z","end":"2026-07-01T00:52:34.703313Z","steps":["trace[58717592] 'agreement among raft nodes before linearized reading' (duration: 468.853174ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:34.703514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.213306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:52:34.70359Z","caller":"traceutil/trace.go:171","msg":"trace[1573821956] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1056; }","duration":"147.271667ms","start":"2026-07-01T00:52:34.556265Z","end":"2026-07-01T00:52:34.703537Z","steps":["trace[1573821956] 'agreement among raft nodes before linearized reading' (duration: 147.219936ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:34.703666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.668683581s","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-07-01T00:52:34.7037Z","caller":"traceutil/trace.go:171","msg":"trace[1158935627] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1056; }","duration":"1.668714072s","start":"2026-07-01T00:52:33.034975Z","end":"2026-07-01T00:52:34.703689Z","steps":["trace[1158935627] 'agreement among raft nodes before linearized reading' (duration: 1.668587169s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:34.703715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.668706842s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-01T00:52:34.703727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:33.034371Z","time spent":"1.669349979s","remote":"127.0.0.1:35760","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-07-01T00:52:34.70377Z","caller":"traceutil/trace.go:171","msg":"trace[177128495] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1056; }","duration":"1.668814025s","start":"2026-07-01T00:52:33.034938Z","end":"2026-07-01T00:52:34.703752Z","steps":["trace[177128495] 'agreement among raft nodes before linearized reading' (duration: 1.668606869s)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:34.703781Z","caller":"traceutil/trace.go:171","msg":"trace[48378065] transaction","detail":"{read_only:false; response_revision:1056; number_of_response:1; }","duration":"1.295117257s","start":"2026-07-01T00:52:33.408655Z","end":"2026-07-01T00:52:34.703772Z","steps":["trace[48378065] 'process raft request' (duration: 1.294346277s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:34.703811Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:33.034924Z","time spent":"1.668879797s","remote":"127.0.0.1:35760","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-07-01T00:52:34.703842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:33.408624Z","time spent":"1.295174908s","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:52:34.703936Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.668848455s","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-07-01T00:52:34.703907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"611.803698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-01T00:52:34.703954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.108661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:52:34.703953Z","caller":"traceutil/trace.go:171","msg":"trace[516955217] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1056; }","duration":"1.668876017s","start":"2026-07-01T00:52:33.035071Z","end":"2026-07-01T00:52:34.703947Z","steps":["trace[516955217] 'agreement among raft nodes before linearized reading' (duration: 1.668822575s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:34.703985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:33.035065Z","time spent":"1.668915528s","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T00:52:34.703977Z","caller":"traceutil/trace.go:171","msg":"trace[1338930552] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1056; }","duration":"611.951471ms","start":"2026-07-01T00:52:34.092009Z","end":"2026-07-01T00:52:34.70396Z","steps":["trace[1338930552] 'agreement among raft nodes before linearized reading' (duration: 611.14261ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:34.703987Z","caller":"traceutil/trace.go:171","msg":"trace[1844539408] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:1056; }","duration":"191.184524ms","start":"2026-07-01T00:52:34.512791Z","end":"2026-07-01T00:52:34.703976Z","steps":["trace[1844539408] 'agreement among raft nodes before linearized reading' (duration: 191.120662ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:34.704021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:34.091985Z","time spent":"612.027003ms","remote":"127.0.0.1:37308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":92,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:52:34.703512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:34.234267Z","time spent":"469.218083ms","remote":"127.0.0.1:35826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:52:35.17164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"357.132262ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:52:35.171847Z","caller":"traceutil/trace.go:171","msg":"trace[1086764108] linearizableReadLoop","detail":"{readStateIndex:1103; appliedIndex:1101; }","duration":"468.70161ms","start":"2026-07-01T00:52:34.703125Z","end":"2026-07-01T00:52:35.171827Z","steps":["trace[1086764108] 'read index received' (duration: 111.26354ms)","trace[1086764108] 'applied index is now lower than readState.Index' (duration: 357.43686ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:35.171968Z","caller":"traceutil/trace.go:171","msg":"trace[605146492] transaction","detail":"{read_only:false; response_revision:1058; number_of_response:1; }","duration":"531.096442ms","start":"2026-07-01T00:52:34.640857Z","end":"2026-07-01T00:52:35.171954Z","steps":["trace[605146492] 'process raft request' (duration: 530.895777ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:35.172038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:34.640843Z","time spent":"531.154864ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:52:35.172189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.290679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:52:35.172239Z","caller":"traceutil/trace.go:171","msg":"trace[928495752] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1058; }","duration":"528.386181ms","start":"2026-07-01T00:52:34.64384Z","end":"2026-07-01T00:52:35.172226Z","steps":["trace[928495752] 'agreement among raft nodes before linearized reading' (duration: 528.210967ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:35.172183Z","caller":"traceutil/trace.go:171","msg":"trace[546688517] transaction","detail":"{read_only:false; response_revision:1057; number_of_response:1; }","duration":"534.815841ms","start":"2026-07-01T00:52:34.637311Z","end":"2026-07-01T00:52:35.172127Z","steps":["trace[546688517] 'process raft request' (duration: 177.099553ms)","trace[546688517] 'compare' (duration: 356.993229ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:52:35.172273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:34.64382Z","time spent":"528.444852ms","remote":"127.0.0.1:35978","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":92,"response size":30,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:52:35.172444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:34.63729Z","time spent":"535.013425ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:52:35.379101Z","caller":"traceutil/trace.go:171","msg":"trace[1726059873] linearizableReadLoop","detail":"{readStateIndex:1106; appliedIndex:1103; }","duration":"207.195295ms","start":"2026-07-01T00:52:35.171882Z","end":"2026-07-01T00:52:35.379077Z","steps":["trace[1726059873] 'read index received' (duration: 129.112349ms)","trace[1726059873] 'applied index is now lower than readState.Index' (duration: 78.081926ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:35.379305Z","caller":"traceutil/trace.go:171","msg":"trace[731048645] transaction","detail":"{read_only:false; response_revision:1060; number_of_response:1; }","duration":"671.411656ms","start":"2026-07-01T00:52:34.707874Z","end":"2026-07-01T00:52:35.379286Z","steps":["trace[731048645] 'process raft request' (duration: 671.113659ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:35.379385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.347327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:52:35.379438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:34.707863Z","time spent":"671.492458ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:52:35.379443Z","caller":"traceutil/trace.go:171","msg":"trace[370225527] transaction","detail":"{read_only:false; response_revision:1061; number_of_response:1; }","duration":"671.15445ms","start":"2026-07-01T00:52:34.708252Z","end":"2026-07-01T00:52:35.379407Z","steps":["trace[370225527] 'process raft request' (duration: 670.78118ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:35.379474Z","caller":"traceutil/trace.go:171","msg":"trace[2017643213] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:1061; }","duration":"262.48484ms","start":"2026-07-01T00:52:35.116972Z","end":"2026-07-01T00:52:35.379457Z","steps":["trace[2017643213] 'agreement among raft nodes before linearized reading' (duration: 262.302485ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:35.379508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"673.06343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:52:35.379592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:34.708243Z","time spent":"671.250792ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:52:35.3796Z","caller":"traceutil/trace.go:171","msg":"trace[750722257] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1061; }","duration":"673.154582ms","start":"2026-07-01T00:52:34.706396Z","end":"2026-07-01T00:52:35.37955Z","steps":["trace[750722257] 'agreement among raft nodes before linearized reading' (duration: 673.05797ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:35.379652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:34.706376Z","time spent":"673.265355ms","remote":"127.0.0.1:37214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T00:52:35.379691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.699996ms","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-07-01T00:52:35.379654Z","caller":"traceutil/trace.go:171","msg":"trace[686507905] transaction","detail":"{read_only:false; response_revision:1059; number_of_response:1; }","duration":"672.085094ms","start":"2026-07-01T00:52:34.707453Z","end":"2026-07-01T00:52:35.379538Z","steps":["trace[686507905] 'process raft request' (duration: 593.577097ms)","trace[686507905] 'compare' (duration: 77.775608ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:52:35.379733Z","caller":"traceutil/trace.go:171","msg":"trace[1206675802] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1061; }","duration":"428.774368ms","start":"2026-07-01T00:52:34.950947Z","end":"2026-07-01T00:52:35.379721Z","steps":["trace[1206675802] 'agreement among raft nodes before linearized reading' (duration: 428.646445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:35.379769Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:34.950922Z","time spent":"428.83502ms","remote":"127.0.0.1:35760","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-07-01T00:52:35.379843Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:34.707438Z","time spent":"672.31565ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:52:35.380221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.772052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-01T00:52:35.380357Z","caller":"traceutil/trace.go:171","msg":"trace[769629708] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1061; }","duration":"204.938355ms","start":"2026-07-01T00:52:35.175404Z","end":"2026-07-01T00:52:35.380342Z","steps":["trace[769629708] 'agreement among raft nodes before linearized reading' (duration: 204.303569ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:52:51.045957Z","caller":"traceutil/trace.go:171","msg":"trace[2103866243] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"335.239176ms","start":"2026-07-01T00:52:50.710698Z","end":"2026-07-01T00:52:51.045937Z","steps":["trace[2103866243] 'process raft request' (duration: 335.091442ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:52:51.0461Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:50.710678Z","time spent":"335.352899ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:52:53.172016Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:52:52.843349Z","time spent":"328.662271ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-01T00:52:53.19026Z","caller":"traceutil/trace.go:171","msg":"trace[459940905] transaction","detail":"{read_only:false; response_revision:1167; number_of_response:1; }","duration":"139.718465ms","start":"2026-07-01T00:52:53.050499Z","end":"2026-07-01T00:52:53.190217Z","steps":["trace[459940905] 'process raft request' (duration: 139.500079ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:05.547105Z","caller":"traceutil/trace.go:171","msg":"trace[79946759] transaction","detail":"{read_only:false; response_revision:1209; number_of_response:1; }","duration":"214.383049ms","start":"2026-07-01T00:53:05.332705Z","end":"2026-07-01T00:53:05.547088Z","steps":["trace[79946759] 'process raft request' (duration: 214.286847ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:08.065039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.772908ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:53:08.065142Z","caller":"traceutil/trace.go:171","msg":"trace[1077169422] linearizableReadLoop","detail":"{readStateIndex:1275; appliedIndex:1274; }","duration":"437.10803ms","start":"2026-07-01T00:53:07.628018Z","end":"2026-07-01T00:53:08.065126Z","steps":["trace[1077169422] 'read index received' (duration: 199.221109ms)","trace[1077169422] 'applied index is now lower than readState.Index' (duration: 237.885651ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:08.065721Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.772333ms","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-07-01T00:53:08.065746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.702925ms","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-07-01T00:53:08.065828Z","caller":"traceutil/trace.go:171","msg":"trace[364843044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1222; }","duration":"437.836499ms","start":"2026-07-01T00:53:07.627972Z","end":"2026-07-01T00:53:08.065809Z","steps":["trace[364843044] 'agreement among raft nodes before linearized reading' (duration: 437.552271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:08.065885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:07.62795Z","time spent":"437.926152ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-01T00:53:08.065772Z","caller":"traceutil/trace.go:171","msg":"trace[122593128] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1222; }","duration":"413.871715ms","start":"2026-07-01T00:53:07.65189Z","end":"2026-07-01T00:53:08.065761Z","steps":["trace[122593128] 'agreement among raft nodes before linearized reading' (duration: 413.639329ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:08.066002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:07.651869Z","time spent":"414.116432ms","remote":"127.0.0.1:35760","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":"info","ts":"2026-07-01T00:53:08.065224Z","caller":"traceutil/trace.go:171","msg":"trace[310330829] transaction","detail":"{read_only:false; response_revision:1222; number_of_response:1; }","duration":"473.846472ms","start":"2026-07-01T00:53:07.591368Z","end":"2026-07-01T00:53:08.065214Z","steps":["trace[310330829] 'process raft request' (duration: 235.832628ms)","trace[310330829] 'compare' (duration: 237.658015ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:08.065704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"437.48726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-01T00:53:08.066105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:07.591348Z","time spent":"474.709404ms","remote":"127.0.0.1:37308","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":784,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-01T00:53:08.066128Z","caller":"traceutil/trace.go:171","msg":"trace[1810798396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1222; }","duration":"437.947462ms","start":"2026-07-01T00:53:07.628163Z","end":"2026-07-01T00:53:08.066111Z","steps":["trace[1810798396] 'agreement among raft nodes before linearized reading' (duration: 437.350026ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:08.066163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:07.628147Z","time spent":"438.009074ms","remote":"127.0.0.1:35760","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-07-01T00:53:10.190734Z","caller":"traceutil/trace.go:171","msg":"trace[1226014056] linearizableReadLoop","detail":"{readStateIndex:1287; appliedIndex:1286; }","duration":"104.668168ms","start":"2026-07-01T00:53:10.086039Z","end":"2026-07-01T00:53:10.190707Z","steps":["trace[1226014056] 'read index received' (duration: 14.013061ms)","trace[1226014056] 'applied index is now lower than readState.Index' (duration: 90.653707ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:10.190831Z","caller":"traceutil/trace.go:171","msg":"trace[737771306] transaction","detail":"{read_only:false; response_revision:1233; number_of_response:1; }","duration":"106.487936ms","start":"2026-07-01T00:53:10.084296Z","end":"2026-07-01T00:53:10.190784Z","steps":["trace[737771306] 'process raft request' (duration: 71.560393ms)","trace[737771306] 'compare' (duration: 34.761999ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:10.190953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.887254ms","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-07-01T00:53:10.191015Z","caller":"traceutil/trace.go:171","msg":"trace[899306595] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1233; }","duration":"104.976786ms","start":"2026-07-01T00:53:10.086024Z","end":"2026-07-01T00:53:10.191001Z","steps":["trace[899306595] 'agreement among raft nodes before linearized reading' (duration: 104.778671ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:11.18838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"621.074755ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:11.188481Z","caller":"traceutil/trace.go:171","msg":"trace[976321697] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:1235; }","duration":"621.24801ms","start":"2026-07-01T00:53:10.567198Z","end":"2026-07-01T00:53:11.188446Z","steps":["trace[976321697] 'count revisions from in-memory index tree' (duration: 621.008004ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:11.188526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:10.567181Z","time spent":"621.333481ms","remote":"127.0.0.1:54994","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-07-01T00:53:11.188427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"564.840998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:11.188633Z","caller":"traceutil/trace.go:171","msg":"trace[2123347826] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1235; }","duration":"565.044613ms","start":"2026-07-01T00:53:10.623544Z","end":"2026-07-01T00:53:11.188588Z","steps":["trace[2123347826] 'range keys from in-memory index tree' (duration: 564.716475ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:11.188766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:10.623524Z","time spent":"565.194947ms","remote":"127.0.0.1:37218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T00:53:11.923514Z","caller":"traceutil/trace.go:171","msg":"trace[1782134328] linearizableReadLoop","detail":"{readStateIndex:1290; appliedIndex:1289; }","duration":"350.560121ms","start":"2026-07-01T00:53:11.57292Z","end":"2026-07-01T00:53:11.92348Z","steps":["trace[1782134328] 'read index received' (duration: 350.357355ms)","trace[1782134328] 'applied index is now lower than readState.Index' (duration: 201.425µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:11.923599Z","caller":"traceutil/trace.go:171","msg":"trace[1037416419] transaction","detail":"{read_only:false; response_revision:1236; number_of_response:1; }","duration":"576.27728ms","start":"2026-07-01T00:53:11.347297Z","end":"2026-07-01T00:53:11.923574Z","steps":["trace[1037416419] 'process raft request' (duration: 575.979172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:11.923842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.89363ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-01T00:53:11.923901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.032441ms","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-07-01T00:53:11.923929Z","caller":"traceutil/trace.go:171","msg":"trace[1211508957] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1236; }","duration":"350.994963ms","start":"2026-07-01T00:53:11.572913Z","end":"2026-07-01T00:53:11.923908Z","steps":["trace[1211508957] 'agreement among raft nodes before linearized reading' (duration: 350.696335ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:11.923957Z","caller":"traceutil/trace.go:171","msg":"trace[1892811562] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1236; }","duration":"332.180685ms","start":"2026-07-01T00:53:11.591756Z","end":"2026-07-01T00:53:11.923937Z","steps":["trace[1892811562] 'agreement among raft nodes before linearized reading' (duration: 331.892967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:11.924033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:11.59173Z","time spent":"332.284968ms","remote":"127.0.0.1:35760","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-07-01T00:53:11.924229Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:11.347273Z","time spent":"576.386483ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:53:11.923966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:11.572855Z","time spent":"351.104675ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-01T00:53:12.69493Z","caller":"traceutil/trace.go:171","msg":"trace[849089524] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"193.669342ms","start":"2026-07-01T00:53:12.501237Z","end":"2026-07-01T00:53:12.694906Z","steps":["trace[849089524] 'read index received' (duration: 178.797058ms)","trace[849089524] 'applied index is now lower than readState.Index' (duration: 14.871224ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:12.694945Z","caller":"traceutil/trace.go:171","msg":"trace[265837885] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"193.739873ms","start":"2026-07-01T00:53:12.50118Z","end":"2026-07-01T00:53:12.694919Z","steps":["trace[265837885] 'process raft request' (duration: 178.954492ms)","trace[265837885] 'compare' (duration: 14.647027ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:12.695106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.841486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:53:12.695168Z","caller":"traceutil/trace.go:171","msg":"trace[572539194] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:1244; }","duration":"193.920748ms","start":"2026-07-01T00:53:12.501231Z","end":"2026-07-01T00:53:12.695152Z","steps":["trace[572539194] 'agreement among raft nodes before linearized reading' (duration: 193.760844ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:13.241152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.857291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5374"} {"level":"info","ts":"2026-07-01T00:53:13.241232Z","caller":"traceutil/trace.go:171","msg":"trace[235731155] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1244; }","duration":"310.987834ms","start":"2026-07-01T00:53:12.930224Z","end":"2026-07-01T00:53:13.241212Z","steps":["trace[235731155] 'range keys from in-memory index tree' (duration: 310.673806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:13.241275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:12.930206Z","time spent":"311.060706ms","remote":"127.0.0.1:35682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5397,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-01T00:53:13.241394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"400.538062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-01T00:53:13.241477Z","caller":"traceutil/trace.go:171","msg":"trace[1765284727] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:1244; }","duration":"400.679837ms","start":"2026-07-01T00:53:12.840779Z","end":"2026-07-01T00:53:13.241459Z","steps":["trace[1765284727] 'range keys from in-memory index tree' (duration: 400.293917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:13.241529Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:12.840757Z","time spent":"400.761438ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"info","ts":"2026-07-01T00:53:13.967003Z","caller":"traceutil/trace.go:171","msg":"trace[2134510586] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"716.128489ms","start":"2026-07-01T00:53:13.250851Z","end":"2026-07-01T00:53:13.96698Z","steps":["trace[2134510586] 'process raft request' (duration: 715.993356ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:13.96718Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:13.250836Z","time spent":"716.254772ms","remote":"127.0.0.1:35682","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5500,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:53:14.017312Z","caller":"traceutil/trace.go:171","msg":"trace[1585498059] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1299; }","duration":"392.09219ms","start":"2026-07-01T00:53:13.625195Z","end":"2026-07-01T00:53:14.017287Z","steps":["trace[1585498059] 'read index received' (duration: 341.591874ms)","trace[1585498059] 'applied index is now lower than readState.Index' (duration: 50.498886ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:14.017338Z","caller":"traceutil/trace.go:171","msg":"trace[881552432] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"647.651618ms","start":"2026-07-01T00:53:13.369642Z","end":"2026-07-01T00:53:14.017294Z","steps":["trace[881552432] 'process raft request' (duration: 614.243054ms)","trace[881552432] 'compare' (duration: 33.191118ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:14.017535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:13.369619Z","time spent":"647.810252ms","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:53:14.01755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.319976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:53:14.017643Z","caller":"traceutil/trace.go:171","msg":"trace[625472113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1247; }","duration":"392.47261ms","start":"2026-07-01T00:53:13.625147Z","end":"2026-07-01T00:53:14.01762Z","steps":["trace[625472113] 'agreement among raft nodes before linearized reading' (duration: 392.295995ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:14.017685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:13.625127Z","time spent":"392.551162ms","remote":"127.0.0.1:37218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-01T00:53:14.204465Z","caller":"traceutil/trace.go:171","msg":"trace[1943658782] linearizableReadLoop","detail":"{readStateIndex:1303; appliedIndex:1302; }","duration":"182.093165ms","start":"2026-07-01T00:53:14.022349Z","end":"2026-07-01T00:53:14.204443Z","steps":["trace[1943658782] 'read index received' (duration: 154.254289ms)","trace[1943658782] 'applied index is now lower than readState.Index' (duration: 27.838126ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:14.204527Z","caller":"traceutil/trace.go:171","msg":"trace[2119482635] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"183.00981ms","start":"2026-07-01T00:53:14.021493Z","end":"2026-07-01T00:53:14.204503Z","steps":["trace[2119482635] 'process raft request' (duration: 155.39884ms)","trace[2119482635] 'compare' (duration: 27.446606ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:53:14.20462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.246479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-07-01T00:53:14.204688Z","caller":"traceutil/trace.go:171","msg":"trace[1141783254] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:1248; }","duration":"182.347632ms","start":"2026-07-01T00:53:14.022322Z","end":"2026-07-01T00:53:14.204669Z","steps":["trace[1141783254] 'agreement among raft nodes before linearized reading' (duration: 182.210569ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:14.207409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.912776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:53:14.207445Z","caller":"traceutil/trace.go:171","msg":"trace[2078808363] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:1250; }","duration":"144.026519ms","start":"2026-07-01T00:53:14.063411Z","end":"2026-07-01T00:53:14.207438Z","steps":["trace[2078808363] 'agreement among raft nodes before linearized reading' (duration: 143.941176ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:14.20744Z","caller":"traceutil/trace.go:171","msg":"trace[1311189259] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"184.972672ms","start":"2026-07-01T00:53:14.022446Z","end":"2026-07-01T00:53:14.207418Z","steps":["trace[1311189259] 'process raft request' (duration: 184.804938ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:14.207393Z","caller":"traceutil/trace.go:171","msg":"trace[602576816] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"184.9138ms","start":"2026-07-01T00:53:14.022464Z","end":"2026-07-01T00:53:14.207378Z","steps":["trace[602576816] 'process raft request' (duration: 184.848998ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:14.420006Z","caller":"traceutil/trace.go:171","msg":"trace[1188269763] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"128.256231ms","start":"2026-07-01T00:53:14.291724Z","end":"2026-07-01T00:53:14.41998Z","steps":["trace[1188269763] 'process raft request' (duration: 43.89157ms)","trace[1188269763] 'compare' (duration: 84.257019ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:15.665497Z","caller":"traceutil/trace.go:171","msg":"trace[1032331946] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"411.723619ms","start":"2026-07-01T00:53:15.253749Z","end":"2026-07-01T00:53:15.665473Z","steps":["trace[1032331946] 'process raft request' (duration: 411.585765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:15.665641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:15.253724Z","time spent":"411.841612ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:53:18.403407Z","caller":"traceutil/trace.go:171","msg":"trace[136254152] linearizableReadLoop","detail":"{readStateIndex:1333; appliedIndex:1332; }","duration":"116.245124ms","start":"2026-07-01T00:53:18.287136Z","end":"2026-07-01T00:53:18.403381Z","steps":["trace[136254152] 'read index received' (duration: 116.200603ms)","trace[136254152] 'applied index is now lower than readState.Index' (duration: 43.361µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:18.403523Z","caller":"traceutil/trace.go:171","msg":"trace[1952657138] transaction","detail":"{read_only:false; response_revision:1277; number_of_response:1; }","duration":"134.529557ms","start":"2026-07-01T00:53:18.268943Z","end":"2026-07-01T00:53:18.403472Z","steps":["trace[1952657138] 'process raft request' (duration: 134.291861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:18.403613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.461769ms","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-07-01T00:53:18.403676Z","caller":"traceutil/trace.go:171","msg":"trace[1837766525] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1277; }","duration":"116.567062ms","start":"2026-07-01T00:53:18.28708Z","end":"2026-07-01T00:53:18.403647Z","steps":["trace[1837766525] 'agreement among raft nodes before linearized reading' (duration: 116.404678ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:20.144944Z","caller":"traceutil/trace.go:171","msg":"trace[2033636936] transaction","detail":"{read_only:false; response_revision:1288; number_of_response:1; }","duration":"102.665205ms","start":"2026-07-01T00:53:20.042248Z","end":"2026-07-01T00:53:20.144913Z","steps":["trace[2033636936] 'process raft request' (duration: 102.48911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:22.740964Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5827832856800050506,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-01T00:53:22.870063Z","caller":"traceutil/trace.go:171","msg":"trace[2113989615] transaction","detail":"{read_only:false; response_revision:1301; number_of_response:1; }","duration":"747.371985ms","start":"2026-07-01T00:53:22.122672Z","end":"2026-07-01T00:53:22.870044Z","steps":["trace[2113989615] 'process raft request' (duration: 747.260492ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:22.870177Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:22.122651Z","time spent":"747.467858ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":457,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:53:23.93707Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.067083159s","expected-duration":"1s"} {"level":"info","ts":"2026-07-01T00:53:23.937292Z","caller":"traceutil/trace.go:171","msg":"trace[249390458] linearizableReadLoop","detail":"{readStateIndex:1358; appliedIndex:1356; }","duration":"1.697276485s","start":"2026-07-01T00:53:22.239998Z","end":"2026-07-01T00:53:23.937275Z","steps":["trace[249390458] 'read index received' (duration: 629.985981ms)","trace[249390458] 'applied index is now lower than readState.Index' (duration: 1.067289744s)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:53:23.937349Z","caller":"traceutil/trace.go:171","msg":"trace[385169538] transaction","detail":"{read_only:false; response_revision:1302; number_of_response:1; }","duration":"1.787985344s","start":"2026-07-01T00:53:22.149356Z","end":"2026-07-01T00:53:23.937341Z","steps":["trace[385169538] 'process raft request' (duration: 1.787816039s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:23.937423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:22.149339Z","time spent":"1.788030856s","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":533,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:53:23.937629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.161311891s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:53:23.937718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.47965322s","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-07-01T00:53:23.937744Z","caller":"traceutil/trace.go:171","msg":"trace[82597124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1302; }","duration":"1.479709791s","start":"2026-07-01T00:53:22.458027Z","end":"2026-07-01T00:53:23.937737Z","steps":["trace[82597124] 'agreement among raft nodes before linearized reading' (duration: 1.479613079s)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:23.937751Z","caller":"traceutil/trace.go:171","msg":"trace[936029072] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1302; }","duration":"1.161505196s","start":"2026-07-01T00:53:22.776222Z","end":"2026-07-01T00:53:23.937727Z","steps":["trace[936029072] 'agreement among raft nodes before linearized reading' (duration: 1.16128285s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:23.93778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.521700052s","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-07-01T00:53:23.937795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.313899487s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-01T00:53:23.937807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:22.776202Z","time spent":"1.161593959s","remote":"127.0.0.1:48090","response type":"/etcdserverpb.KV/Range","request count":0,"request size":88,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true "} {"level":"info","ts":"2026-07-01T00:53:23.937825Z","caller":"traceutil/trace.go:171","msg":"trace[626498404] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1302; }","duration":"1.313954438s","start":"2026-07-01T00:53:22.623865Z","end":"2026-07-01T00:53:23.93782Z","steps":["trace[626498404] 'agreement among raft nodes before linearized reading' (duration: 1.313914147s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:23.937844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:22.62385Z","time spent":"1.313991629s","remote":"127.0.0.1:37218","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-01T00:53:23.937859Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.515726464s","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-07-01T00:53:23.937963Z","caller":"traceutil/trace.go:171","msg":"trace[1513207198] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1302; }","duration":"1.515864688s","start":"2026-07-01T00:53:22.422079Z","end":"2026-07-01T00:53:23.937944Z","steps":["trace[1513207198] 'agreement among raft nodes before linearized reading' (duration: 1.515612291s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:23.938014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:22.422065Z","time spent":"1.515935519s","remote":"127.0.0.1:35760","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-07-01T00:53:23.938004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.698004424s","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-07-01T00:53:23.938032Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.097313029s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.115\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-01T00:53:23.937639Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.695893379s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-07-01T00:53:23.938078Z","caller":"traceutil/trace.go:171","msg":"trace[1724691023] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1302; }","duration":"1.698103097s","start":"2026-07-01T00:53:22.239952Z","end":"2026-07-01T00:53:23.938055Z","steps":["trace[1724691023] 'agreement among raft nodes before linearized reading' (duration: 1.697900191s)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:23.938097Z","caller":"traceutil/trace.go:171","msg":"trace[198149742] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1302; }","duration":"1.696410143s","start":"2026-07-01T00:53:22.241679Z","end":"2026-07-01T00:53:23.938089Z","steps":["trace[198149742] 'agreement among raft nodes before linearized reading' (duration: 1.695737845s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:23.938124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:22.241659Z","time spent":"1.696457783s","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-01T00:53:23.93813Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:22.239939Z","time spent":"1.69817924s","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-01T00:53:23.937762Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:22.458012Z","time spent":"1.479747102s","remote":"127.0.0.1:35760","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-07-01T00:53:23.938192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.022616374s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:53:23.93811Z","caller":"traceutil/trace.go:171","msg":"trace[1220465282] range","detail":"{range_begin:/registry/masterleases/199.204.45.115; range_end:; response_count:1; response_revision:1302; }","duration":"1.097424602s","start":"2026-07-01T00:53:22.840663Z","end":"2026-07-01T00:53:23.938087Z","steps":["trace[1220465282] 'agreement among raft nodes before linearized reading' (duration: 1.097168345s)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:23.938235Z","caller":"traceutil/trace.go:171","msg":"trace[953456752] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:1302; }","duration":"1.022701196s","start":"2026-07-01T00:53:22.915521Z","end":"2026-07-01T00:53:23.938222Z","steps":["trace[953456752] 'agreement among raft nodes before linearized reading' (duration: 1.022619574s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:23.938242Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:22.840639Z","time spent":"1.097593366s","remote":"127.0.0.1:37264","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.204.45.115\" "} {"level":"warn","ts":"2026-07-01T00:53:23.938335Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:22.915492Z","time spent":"1.02282623s","remote":"127.0.0.1:35688","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-07-01T00:53:23.93871Z","caller":"traceutil/trace.go:171","msg":"trace[296346163] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1302; }","duration":"1.521786935s","start":"2026-07-01T00:53:22.416031Z","end":"2026-07-01T00:53:23.937818Z","steps":["trace[296346163] 'agreement among raft nodes before linearized reading' (duration: 1.521661381s)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:23.93878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:53:22.416007Z","time spent":"1.522756061s","remote":"127.0.0.1:35760","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-07-01T00:53:23.938426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.701862ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-01T00:53:23.939999Z","caller":"traceutil/trace.go:171","msg":"trace[588234986] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1302; }","duration":"254.423868ms","start":"2026-07-01T00:53:23.685522Z","end":"2026-07-01T00:53:23.939946Z","steps":["trace[588234986] 'agreement among raft nodes before linearized reading' (duration: 252.134817ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:53:24.065269Z","caller":"traceutil/trace.go:171","msg":"trace[369878365] transaction","detail":"{read_only:false; response_revision:1308; number_of_response:1; }","duration":"116.525762ms","start":"2026-07-01T00:53:23.948688Z","end":"2026-07-01T00:53:24.065214Z","steps":["trace[369878365] 'process raft request' (duration: 116.06399ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:53:57.189975Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.452907ms","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-07-01T00:53:57.190059Z","caller":"traceutil/trace.go:171","msg":"trace[1400447123] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1475; }","duration":"116.592261ms","start":"2026-07-01T00:53:57.073448Z","end":"2026-07-01T00:53:57.190041Z","steps":["trace[1400447123] 'range keys from in-memory index tree' (duration: 116.20671ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:17.766617Z","caller":"traceutil/trace.go:171","msg":"trace[1884924516] linearizableReadLoop","detail":"{readStateIndex:1635; appliedIndex:1634; }","duration":"182.530113ms","start":"2026-07-01T00:54:17.584067Z","end":"2026-07-01T00:54:17.766598Z","steps":["trace[1884924516] 'read index received' (duration: 182.41511ms)","trace[1884924516] 'applied index is now lower than readState.Index' (duration: 113.783µs)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:54:17.76683Z","caller":"traceutil/trace.go:171","msg":"trace[1013379713] transaction","detail":"{read_only:false; response_revision:1568; number_of_response:1; }","duration":"395.008415ms","start":"2026-07-01T00:54:17.37181Z","end":"2026-07-01T00:54:17.766818Z","steps":["trace[1013379713] 'process raft request' (duration: 394.658225ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:17.76706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:54:17.371784Z","time spent":"395.161799ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:54:17.767172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.100158ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:54:17.767225Z","caller":"traceutil/trace.go:171","msg":"trace[1101895497] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:1568; }","duration":"183.19825ms","start":"2026-07-01T00:54:17.584013Z","end":"2026-07-01T00:54:17.767212Z","steps":["trace[1101895497] 'agreement among raft nodes before linearized reading' (duration: 183.080607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:54:17.767202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.555639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:54:17.767321Z","caller":"traceutil/trace.go:171","msg":"trace[270938213] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1568; }","duration":"143.707514ms","start":"2026-07-01T00:54:17.623584Z","end":"2026-07-01T00:54:17.767292Z","steps":["trace[270938213] 'agreement among raft nodes before linearized reading' (duration: 143.521819ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:54:18.382512Z","caller":"traceutil/trace.go:171","msg":"trace[383008559] linearizableReadLoop","detail":"{readStateIndex:1639; appliedIndex:1637; }","duration":"117.261199ms","start":"2026-07-01T00:54:18.265231Z","end":"2026-07-01T00:54:18.382492Z","steps":["trace[383008559] 'read index received' (duration: 16.884746ms)","trace[383008559] 'applied index is now lower than readState.Index' (duration: 100.375483ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:54:18.382698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.451874ms","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-07-01T00:54:18.382778Z","caller":"traceutil/trace.go:171","msg":"trace[1394686375] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1571; }","duration":"117.568057ms","start":"2026-07-01T00:54:18.265196Z","end":"2026-07-01T00:54:18.382764Z","steps":["trace[1394686375] 'agreement among raft nodes before linearized reading' (duration: 117.374622ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:55:36.990793Z","caller":"traceutil/trace.go:171","msg":"trace[897376582] linearizableReadLoop","detail":"{readStateIndex:2017; appliedIndex:2014; }","duration":"116.990274ms","start":"2026-07-01T00:55:36.873784Z","end":"2026-07-01T00:55:36.990774Z","steps":["trace[897376582] 'read index received' (duration: 89.267114ms)","trace[897376582] 'applied index is now lower than readState.Index' (duration: 27.7225ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:55:36.990859Z","caller":"traceutil/trace.go:171","msg":"trace[751944070] transaction","detail":"{read_only:false; response_revision:1933; number_of_response:1; }","duration":"118.904105ms","start":"2026-07-01T00:55:36.871925Z","end":"2026-07-01T00:55:36.990829Z","steps":["trace[751944070] 'process raft request' (duration: 118.727241ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:55:36.99101Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.192109ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-01T00:55:36.991036Z","caller":"traceutil/trace.go:171","msg":"trace[932054131] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1934; }","duration":"117.272642ms","start":"2026-07-01T00:55:36.873756Z","end":"2026-07-01T00:55:36.991029Z","steps":["trace[932054131] 'agreement among raft nodes before linearized reading' (duration: 117.109587ms)"],"step_count":1} {"level":"info","ts":"2026-07-01T00:55:36.991389Z","caller":"traceutil/trace.go:171","msg":"trace[1587340539] transaction","detail":"{read_only:false; response_revision:1934; number_of_response:1; }","duration":"118.462854ms","start":"2026-07-01T00:55:36.872421Z","end":"2026-07-01T00:55:36.990884Z","steps":["trace[1587340539] 'process raft request' (duration: 118.32823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:57:13.829124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.740733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-01T00:57:13.829669Z","caller":"traceutil/trace.go:171","msg":"trace[402964528] transaction","detail":"{read_only:false; response_revision:2403; number_of_response:1; }","duration":"405.538975ms","start":"2026-07-01T00:57:13.424104Z","end":"2026-07-01T00:57:13.829643Z","steps":["trace[402964528] 'process raft request' (duration: 184.186255ms)","trace[402964528] 'compare' (duration: 220.478736ms)"],"step_count":2} {"level":"warn","ts":"2026-07-01T00:57:13.829772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:57:13.424095Z","time spent":"405.630087ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:57:13.829707Z","caller":"traceutil/trace.go:171","msg":"trace[812477905] linearizableReadLoop","detail":"{readStateIndex:2510; appliedIndex:2506; }","duration":"205.222574ms","start":"2026-07-01T00:57:13.624464Z","end":"2026-07-01T00:57:13.829687Z","steps":["trace[812477905] 'read index received' (duration: 47.731µs)","trace[812477905] 'applied index is now lower than readState.Index' (duration: 205.173703ms)"],"step_count":2} {"level":"info","ts":"2026-07-01T00:57:13.830052Z","caller":"traceutil/trace.go:171","msg":"trace[615831800] transaction","detail":"{read_only:false; response_revision:2406; number_of_response:1; }","duration":"404.922569ms","start":"2026-07-01T00:57:13.425122Z","end":"2026-07-01T00:57:13.830044Z","steps":["trace[615831800] 'process raft request' (duration: 404.514248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:57:13.83012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:57:13.425114Z","time spent":"404.97254ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:57:13.829971Z","caller":"traceutil/trace.go:171","msg":"trace[660644858] transaction","detail":"{read_only:false; response_revision:2405; number_of_response:1; }","duration":"405.289998ms","start":"2026-07-01T00:57:13.424669Z","end":"2026-07-01T00:57:13.829959Z","steps":["trace[660644858] 'process raft request' (duration: 404.911198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:57:13.830224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:57:13.424664Z","time spent":"405.535855ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-01T00:57:13.830004Z","caller":"traceutil/trace.go:171","msg":"trace[5675598] transaction","detail":"{read_only:false; response_revision:2404; number_of_response:1; }","duration":"405.37686ms","start":"2026-07-01T00:57:13.424619Z","end":"2026-07-01T00:57:13.829996Z","steps":["trace[5675598] 'process raft request' (duration: 404.831206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-01T00:57:13.830314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-01T00:57:13.424612Z","time spent":"405.678738ms","remote":"127.0.0.1:35760","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-01T00:57:13.830465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.999515ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-01T00:57:13.830609Z","caller":"traceutil/trace.go:171","msg":"trace[1983919050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2406; }","duration":"206.178409ms","start":"2026-07-01T00:57:13.62441Z","end":"2026-07-01T00:57:13.830588Z","steps":["trace[1983919050] 'agreement among raft nodes before linearized reading' (duration: 205.668416ms)"],"step_count":1}