{"level":"warn","ts":"2026-07-14T23:02:42.125381Z","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-14T23:02:42.125493Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.112:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.112:2380","--initial-cluster=instance=https://199.19.213.112:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.112:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.112: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-14T23:02:42.125576Z","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-14T23:02:42.125587Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.112:2380"]} {"level":"info","ts":"2026-07-14T23:02:42.125608Z","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-14T23:02:42.126416Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.112:2379"]} {"level":"info","ts":"2026-07-14T23:02:42.126517Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.112:2380"],"listen-peer-urls":["https://199.19.213.112:2380"],"advertise-client-urls":["https://199.19.213.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.112: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-14T23:02:42.132622Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.836936ms"} {"level":"info","ts":"2026-07-14T23:02:42.147711Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"197930edc5411b2e","cluster-id":"8165613f50a6d530"} {"level":"info","ts":"2026-07-14T23:02:42.14783Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T23:02:42.147862Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became follower at term 0"} {"level":"info","ts":"2026-07-14T23:02:42.147874Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 197930edc5411b2e [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T23:02:42.147892Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became follower at term 1"} {"level":"info","ts":"2026-07-14T23:02:42.147935Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e switched to configuration voters=(1835552120904948526)"} {"level":"warn","ts":"2026-07-14T23:02:42.157885Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T23:02:42.159403Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T23:02:42.160763Z","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-14T23:02:42.163051Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"197930edc5411b2e","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T23:02:42.163173Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"197930edc5411b2e","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T23:02:42.163342Z","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-14T23:02:42.163451Z","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-14T23:02:42.163475Z","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-14T23:02:42.164524Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e switched to configuration voters=(1835552120904948526)"} {"level":"info","ts":"2026-07-14T23:02:42.165108Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8165613f50a6d530","local-member-id":"197930edc5411b2e","added-peer-id":"197930edc5411b2e","added-peer-peer-urls":["https://199.19.213.112:2380"]} {"level":"info","ts":"2026-07-14T23:02:42.16702Z","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-14T23:02:42.167216Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"197930edc5411b2e","initial-advertise-peer-urls":["https://199.19.213.112:2380"],"listen-peer-urls":["https://199.19.213.112:2380"],"advertise-client-urls":["https://199.19.213.112:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.112:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T23:02:42.167247Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T23:02:42.167315Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.112:2380"} {"level":"info","ts":"2026-07-14T23:02:42.167331Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.112:2380"} {"level":"info","ts":"2026-07-14T23:02:42.348793Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T23:02:42.348873Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T23:02:42.348908Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e received MsgPreVoteResp from 197930edc5411b2e at term 1"} {"level":"info","ts":"2026-07-14T23:02:42.348929Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became candidate at term 2"} {"level":"info","ts":"2026-07-14T23:02:42.348961Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e received MsgVoteResp from 197930edc5411b2e at term 2"} {"level":"info","ts":"2026-07-14T23:02:42.348984Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"197930edc5411b2e became leader at term 2"} {"level":"info","ts":"2026-07-14T23:02:42.349003Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 197930edc5411b2e elected leader 197930edc5411b2e at term 2"} {"level":"info","ts":"2026-07-14T23:02:42.350217Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"197930edc5411b2e","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.112:2379]}","request-path":"/0/members/197930edc5411b2e/attributes","cluster-id":"8165613f50a6d530","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T23:02:42.350216Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T23:02:42.350335Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T23:02:42.350747Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T23:02:42.35078Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T23:02:42.350717Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:02:42.352755Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8165613f50a6d530","local-member-id":"197930edc5411b2e","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:02:42.352819Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:02:42.352836Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T23:02:42.353681Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.112:2379"} {"level":"info","ts":"2026-07-14T23:02:42.353911Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T23:02:45.119602Z","caller":"traceutil/trace.go:171","msg":"trace[1951605285] linearizableReadLoop","detail":"{readStateIndex:76; appliedIndex:75; }","duration":"250.642981ms","start":"2026-07-14T23:02:44.868936Z","end":"2026-07-14T23:02:45.119579Z","steps":["trace[1951605285] 'read index received' (duration: 250.465852ms)","trace[1951605285] 'applied index is now lower than readState.Index' (duration: 176.429µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:02:45.119669Z","caller":"traceutil/trace.go:171","msg":"trace[1541592734] transaction","detail":"{read_only:false; response_revision:72; number_of_response:1; }","duration":"252.59842ms","start":"2026-07-14T23:02:44.867029Z","end":"2026-07-14T23:02:45.119628Z","steps":["trace[1541592734] 'process raft request' (duration: 252.43311ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:45.119755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.800501ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-14T23:02:45.119825Z","caller":"traceutil/trace.go:171","msg":"trace[608025998] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:72; }","duration":"250.931331ms","start":"2026-07-14T23:02:44.868885Z","end":"2026-07-14T23:02:45.119817Z","steps":["trace[608025998] 'agreement among raft nodes before linearized reading' (duration: 250.799611ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:45.119755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.568822ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-14T23:02:45.119857Z","caller":"traceutil/trace.go:171","msg":"trace[1026567396] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:72; }","duration":"150.712762ms","start":"2026-07-14T23:02:44.96913Z","end":"2026-07-14T23:02:45.119843Z","steps":["trace[1026567396] 'agreement among raft nodes before linearized reading' (duration: 150.593692ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:48.061533Z","caller":"traceutil/trace.go:171","msg":"trace[1348266506] transaction","detail":"{read_only:false; response_revision:254; number_of_response:1; }","duration":"340.194703ms","start":"2026-07-14T23:02:47.721284Z","end":"2026-07-14T23:02:48.061479Z","steps":["trace[1348266506] 'process raft request' (duration: 293.098922ms)","trace[1348266506] 'compare' (duration: 46.786741ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:02:48.062036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:47.721258Z","time spent":"340.365473ms","remote":"127.0.0.1:48080","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":302,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T23:02:48.063537Z","caller":"traceutil/trace.go:171","msg":"trace[593266392] linearizableReadLoop","detail":"{readStateIndex:264; appliedIndex:262; }","duration":"296.384255ms","start":"2026-07-14T23:02:47.767138Z","end":"2026-07-14T23:02:48.063522Z","steps":["trace[593266392] 'read index received' (duration: 247.259164ms)","trace[593266392] 'applied index is now lower than readState.Index' (duration: 49.124091ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:02:48.063583Z","caller":"traceutil/trace.go:171","msg":"trace[686676051] transaction","detail":"{read_only:false; response_revision:255; number_of_response:1; }","duration":"341.731393ms","start":"2026-07-14T23:02:47.721823Z","end":"2026-07-14T23:02:48.063554Z","steps":["trace[686676051] 'process raft request' (duration: 341.464254ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:48.063943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:47.721808Z","time spent":"342.023294ms","remote":"127.0.0.1:47936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:02:48.063705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.527844ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:02:48.064147Z","caller":"traceutil/trace.go:171","msg":"trace[33122418] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:255; }","duration":"297.053035ms","start":"2026-07-14T23:02:47.767065Z","end":"2026-07-14T23:02:48.064118Z","steps":["trace[33122418] 'agreement among raft nodes before linearized reading' (duration: 296.512774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:49.510945Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958678134937544326,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:02:50.011944Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958678134937544326,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:02:50.125233Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.313826417s","expected-duration":"1s"} {"level":"info","ts":"2026-07-14T23:02:50.125727Z","caller":"traceutil/trace.go:171","msg":"trace[1000175416] transaction","detail":"{read_only:false; response_revision:272; number_of_response:1; }","duration":"1.314395687s","start":"2026-07-14T23:02:48.811284Z","end":"2026-07-14T23:02:50.12568Z","steps":["trace[1000175416] 'process raft request' (duration: 1.314143397s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:50.125872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:48.811252Z","time spent":"1.314544317s","remote":"127.0.0.1:47936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":203,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T23:02:50.125873Z","caller":"traceutil/trace.go:171","msg":"trace[305011326] linearizableReadLoop","detail":"{readStateIndex:285; appliedIndex:284; }","duration":"1.115238174s","start":"2026-07-14T23:02:49.010345Z","end":"2026-07-14T23:02:50.125583Z","steps":["trace[305011326] 'read index received' (duration: 1.115013474s)","trace[305011326] 'applied index is now lower than readState.Index' (duration: 223.73µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:02:50.126061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.303646ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T23:02:50.126127Z","caller":"traceutil/trace.go:171","msg":"trace[229190075] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:272; }","duration":"828.420857ms","start":"2026-07-14T23:02:49.29769Z","end":"2026-07-14T23:02:50.126111Z","steps":["trace[229190075] 'agreement among raft nodes before linearized reading' (duration: 828.289046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:50.126116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115734035s","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-14T23:02:50.126248Z","caller":"traceutil/trace.go:171","msg":"trace[1167663046] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:272; }","duration":"1.115929095s","start":"2026-07-14T23:02:49.010289Z","end":"2026-07-14T23:02:50.126218Z","steps":["trace[1167663046] 'agreement among raft nodes before linearized reading' (duration: 1.115527124s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:50.126175Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:49.297652Z","time spent":"828.510717ms","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T23:02:50.126315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:49.010245Z","time spent":"1.116059485s","remote":"127.0.0.1:48016","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-14T23:02:50.126293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.468469ms","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-14T23:02:50.126387Z","caller":"traceutil/trace.go:171","msg":"trace[383618827] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:272; }","duration":"457.615149ms","start":"2026-07-14T23:02:49.668748Z","end":"2026-07-14T23:02:50.126363Z","steps":["trace[383618827] 'agreement among raft nodes before linearized reading' (duration: 457.431149ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:50.126496Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:49.668724Z","time spent":"457.761968ms","remote":"127.0.0.1:48016","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-14T23:02:50.632703Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958678134937544330,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:02:51.133119Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958678134937544330,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:02:51.633288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958678134937544330,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:02:52.133544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958678134937544330,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-14T23:02:52.404197Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.272257065s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-14T23:02:54.978035Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.57300648s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:02:54.978418Z","caller":"traceutil/trace.go:171","msg":"trace[1281649296] transaction","detail":"{read_only:false; response_revision:273; number_of_response:1; }","duration":"4.846509715s","start":"2026-07-14T23:02:50.131843Z","end":"2026-07-14T23:02:54.978353Z","steps":["trace[1281649296] 'process raft request' (duration: 2.272800365s)","trace[1281649296] 'compare' (duration: 2.57176015s)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:02:54.978623Z","caller":"traceutil/trace.go:171","msg":"trace[246308465] transaction","detail":"{read_only:false; response_revision:276; number_of_response:1; }","duration":"541.885211ms","start":"2026-07-14T23:02:54.436623Z","end":"2026-07-14T23:02:54.978508Z","steps":["trace[246308465] 'process raft request' (duration: 541.826421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:54.978666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:50.131824Z","time spent":"4.846717395s","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:02:54.978818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:54.436597Z","time spent":"542.16368ms","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:02:54.978955Z","caller":"traceutil/trace.go:171","msg":"trace[665630575] transaction","detail":"{read_only:false; response_revision:274; number_of_response:1; }","duration":"4.846931345s","start":"2026-07-14T23:02:50.131999Z","end":"2026-07-14T23:02:54.97893Z","steps":["trace[665630575] 'process raft request' (duration: 4.846322655s)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:54.979072Z","caller":"traceutil/trace.go:171","msg":"trace[1226589050] transaction","detail":"{read_only:false; response_revision:275; number_of_response:1; }","duration":"4.846937195s","start":"2026-07-14T23:02:50.132115Z","end":"2026-07-14T23:02:54.979053Z","steps":["trace[1226589050] 'process raft request' (duration: 4.846284895s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:54.97917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:50.132099Z","time spent":"4.847020065s","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:02:54.979167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:50.131969Z","time spent":"4.847097335s","remote":"127.0.0.1:48016","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-14T23:02:54.979369Z","caller":"traceutil/trace.go:171","msg":"trace[210424305] linearizableReadLoop","detail":"{readStateIndex:288; appliedIndex:285; }","duration":"4.847382655s","start":"2026-07-14T23:02:50.131974Z","end":"2026-07-14T23:02:54.979357Z","steps":["trace[210424305] 'read index received' (duration: 2.272601705s)","trace[210424305] 'applied index is now lower than readState.Index' (duration: 2.57477984s)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:02:54.979483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.752282239s","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-14T23:02:54.979516Z","caller":"traceutil/trace.go:171","msg":"trace[1919764519] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:276; }","duration":"4.752394039s","start":"2026-07-14T23:02:50.227112Z","end":"2026-07-14T23:02:54.979506Z","steps":["trace[1919764519] 'agreement among raft nodes before linearized reading' (duration: 4.752329649s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:54.979544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:50.227086Z","time spent":"4.752452509s","remote":"127.0.0.1:48016","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-14T23:02:54.979554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.847550545s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-07-14T23:02:54.979642Z","caller":"traceutil/trace.go:171","msg":"trace[1337306895] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:276; }","duration":"4.847676785s","start":"2026-07-14T23:02:50.131936Z","end":"2026-07-14T23:02:54.979613Z","steps":["trace[1337306895] 'agreement among raft nodes before linearized reading' (duration: 4.847482085s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:54.979686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:50.13191Z","time spent":"4.847767145s","remote":"127.0.0.1:47836","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":1,"response size":374,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-07-14T23:02:54.979781Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.688658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-07-14T23:02:54.979857Z","caller":"traceutil/trace.go:171","msg":"trace[2012703753] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:276; }","duration":"146.798668ms","start":"2026-07-14T23:02:54.833039Z","end":"2026-07-14T23:02:54.979837Z","steps":["trace[2012703753] 'agreement among raft nodes before linearized reading' (duration: 146.589848ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:56.153654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"382.774577ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.112\" ","response":"range_response_count:1 size:135"} {"level":"warn","ts":"2026-07-14T23:02:56.15369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"961.905238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-07-14T23:02:56.153781Z","caller":"traceutil/trace.go:171","msg":"trace[422929765] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:288; }","duration":"962.072798ms","start":"2026-07-14T23:02:55.191688Z","end":"2026-07-14T23:02:56.153761Z","steps":["trace[422929765] 'range keys from in-memory index tree' (duration: 961.772638ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:56.153831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:55.191647Z","time spent":"962.174258ms","remote":"127.0.0.1:47936","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" "} {"level":"info","ts":"2026-07-14T23:02:56.153725Z","caller":"traceutil/trace.go:171","msg":"trace[2000698723] range","detail":"{range_begin:/registry/masterleases/199.19.213.112; range_end:; response_count:1; response_revision:288; }","duration":"382.919607ms","start":"2026-07-14T23:02:55.770789Z","end":"2026-07-14T23:02:56.153708Z","steps":["trace[2000698723] 'range keys from in-memory index tree' (duration: 382.657887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:56.15391Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"795.352163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-07-14T23:02:56.15399Z","caller":"traceutil/trace.go:171","msg":"trace[2130038406] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:288; }","duration":"795.417653ms","start":"2026-07-14T23:02:55.358515Z","end":"2026-07-14T23:02:56.153932Z","steps":["trace[2130038406] 'range keys from in-memory index tree' (duration: 795.211353ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:56.153987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:55.770768Z","time spent":"383.194947ms","remote":"127.0.0.1:47752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.112\" "} {"level":"warn","ts":"2026-07-14T23:02:56.154022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:02:55.358496Z","time spent":"795.517873ms","remote":"127.0.0.1:47918","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"info","ts":"2026-07-14T23:02:56.399247Z","caller":"traceutil/trace.go:171","msg":"trace[1713388201] linearizableReadLoop","detail":"{readStateIndex:302; appliedIndex:301; }","duration":"241.896597ms","start":"2026-07-14T23:02:56.157321Z","end":"2026-07-14T23:02:56.399218Z","steps":["trace[1713388201] 'read index received' (duration: 241.755307ms)","trace[1713388201] 'applied index is now lower than readState.Index' (duration: 140.05µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:02:56.399644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.274987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/generic-garbage-collector\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-07-14T23:02:56.399762Z","caller":"traceutil/trace.go:171","msg":"trace[814553751] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/generic-garbage-collector; range_end:; response_count:1; response_revision:288; }","duration":"242.441487ms","start":"2026-07-14T23:02:56.15729Z","end":"2026-07-14T23:02:56.399731Z","steps":["trace[814553751] 'agreement among raft nodes before linearized reading' (duration: 242.179467ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:02:56.430931Z","caller":"traceutil/trace.go:171","msg":"trace[1392234691] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"266.990967ms","start":"2026-07-14T23:02:56.163915Z","end":"2026-07-14T23:02:56.430906Z","steps":["trace[1392234691] 'process raft request' (duration: 266.754236ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:02:56.431083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.61985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/endpoint-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:02:56.431376Z","caller":"traceutil/trace.go:171","msg":"trace[882695652] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/endpoint-controller; range_end:; response_count:0; response_revision:289; }","duration":"273.94209ms","start":"2026-07-14T23:02:56.157378Z","end":"2026-07-14T23:02:56.43132Z","steps":["trace[882695652] 'agreement among raft nodes before linearized reading' (duration: 273.58158ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:00.176226Z","caller":"traceutil/trace.go:171","msg":"trace[67149623] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"195.57414ms","start":"2026-07-14T23:02:59.980624Z","end":"2026-07-14T23:03:00.176199Z","steps":["trace[67149623] 'process raft request' (duration: 174.675185ms)","trace[67149623] 'compare' (duration: 20.679244ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:11.224758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.862695ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:03:11.225001Z","caller":"traceutil/trace.go:171","msg":"trace[222190788] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"114.990872ms","start":"2026-07-14T23:03:11.109973Z","end":"2026-07-14T23:03:11.224963Z","steps":["trace[222190788] 'process raft request' (duration: 12.836427ms)","trace[222190788] 'compare' (duration: 101.677545ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:03:11.22536Z","caller":"traceutil/trace.go:171","msg":"trace[1927016961] transaction","detail":"{read_only:false; response_revision:401; number_of_response:1; }","duration":"115.301413ms","start":"2026-07-14T23:03:11.110048Z","end":"2026-07-14T23:03:11.225349Z","steps":["trace[1927016961] 'process raft request' (duration: 115.115802ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:11.225458Z","caller":"traceutil/trace.go:171","msg":"trace[2030857880] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"112.494199ms","start":"2026-07-14T23:03:11.112946Z","end":"2026-07-14T23:03:11.22544Z","steps":["trace[2030857880] 'process raft request' (duration: 112.444409ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:11.225526Z","caller":"traceutil/trace.go:171","msg":"trace[542892918] transaction","detail":"{read_only:false; response_revision:400; number_of_response:1; }","duration":"115.506053ms","start":"2026-07-14T23:03:11.110013Z","end":"2026-07-14T23:03:11.225519Z","steps":["trace[542892918] 'process raft request' (duration: 114.855292ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:11.225504Z","caller":"traceutil/trace.go:171","msg":"trace[2073642644] transaction","detail":"{read_only:false; response_revision:404; number_of_response:1; }","duration":"114.583602ms","start":"2026-07-14T23:03:11.110882Z","end":"2026-07-14T23:03:11.225466Z","steps":["trace[2073642644] 'process raft request' (duration: 114.429412ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:11.225705Z","caller":"traceutil/trace.go:171","msg":"trace[1327613685] transaction","detail":"{read_only:false; response_revision:405; number_of_response:1; }","duration":"112.94085ms","start":"2026-07-14T23:03:11.112748Z","end":"2026-07-14T23:03:11.225689Z","steps":["trace[1327613685] 'process raft request' (duration: 112.605999ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:11.225933Z","caller":"traceutil/trace.go:171","msg":"trace[2004145825] transaction","detail":"{read_only:false; response_revision:402; number_of_response:1; }","duration":"115.703964ms","start":"2026-07-14T23:03:11.110215Z","end":"2026-07-14T23:03:11.225919Z","steps":["trace[2004145825] 'process raft request' (duration: 115.018183ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:11.226096Z","caller":"traceutil/trace.go:171","msg":"trace[421552737] transaction","detail":"{read_only:false; response_revision:403; number_of_response:1; }","duration":"115.305354ms","start":"2026-07-14T23:03:11.110773Z","end":"2026-07-14T23:03:11.226078Z","steps":["trace[421552737] 'process raft request' (duration: 114.507483ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:11.369021Z","caller":"traceutil/trace.go:171","msg":"trace[2054658039] transaction","detail":"{read_only:false; response_revision:412; number_of_response:1; }","duration":"101.290894ms","start":"2026-07-14T23:03:11.267704Z","end":"2026-07-14T23:03:11.368995Z","steps":["trace[2054658039] 'process raft request' (duration: 82.027358ms)","trace[2054658039] 'compare' (duration: 19.164376ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:03:19.456649Z","caller":"traceutil/trace.go:171","msg":"trace[555742100] transaction","detail":"{read_only:false; response_revision:445; number_of_response:1; }","duration":"109.046879ms","start":"2026-07-14T23:03:19.347582Z","end":"2026-07-14T23:03:19.456629Z","steps":["trace[555742100] 'process raft request' (duration: 101.510646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:23.009327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.770571ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:03:23.009435Z","caller":"traceutil/trace.go:171","msg":"trace[1289655640] linearizableReadLoop","detail":"{readStateIndex:479; appliedIndex:478; }","duration":"240.573662ms","start":"2026-07-14T23:03:22.768845Z","end":"2026-07-14T23:03:23.009419Z","steps":["trace[1289655640] 'read index received' (duration: 112.575101ms)","trace[1289655640] 'applied index is now lower than readState.Index' (duration: 127.997451ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:23.009525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.687692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:03:23.009561Z","caller":"traceutil/trace.go:171","msg":"trace[980732718] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:459; }","duration":"240.767452ms","start":"2026-07-14T23:03:22.768782Z","end":"2026-07-14T23:03:23.009549Z","steps":["trace[980732718] 'agreement among raft nodes before linearized reading' (duration: 240.682082ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:23.009692Z","caller":"traceutil/trace.go:171","msg":"trace[257302567] transaction","detail":"{read_only:false; response_revision:459; number_of_response:1; }","duration":"432.134571ms","start":"2026-07-14T23:03:22.577392Z","end":"2026-07-14T23:03:23.009526Z","steps":["trace[257302567] 'process raft request' (duration: 304.03022ms)","trace[257302567] 'compare' (duration: 127.602391ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:23.010176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:22.57738Z","time spent":"432.709462ms","remote":"127.0.0.1:48086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:03:23.436584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.491235ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:03:23.43669Z","caller":"traceutil/trace.go:171","msg":"trace[271354373] transaction","detail":"{read_only:false; response_revision:464; number_of_response:1; }","duration":"334.880773ms","start":"2026-07-14T23:03:23.101792Z","end":"2026-07-14T23:03:23.436672Z","steps":["trace[271354373] 'process raft request' (duration: 124.209009ms)","trace[271354373] 'compare' (duration: 210.334304ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:23.436745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:23.101768Z","time spent":"334.954283ms","remote":"127.0.0.1:47918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15015,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T23:03:23.438499Z","caller":"traceutil/trace.go:171","msg":"trace[138410233] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"333.863211ms","start":"2026-07-14T23:03:23.104611Z","end":"2026-07-14T23:03:23.438474Z","steps":["trace[138410233] 'process raft request' (duration: 333.762171ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:23.438534Z","caller":"traceutil/trace.go:171","msg":"trace[357256868] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"334.186461ms","start":"2026-07-14T23:03:23.104303Z","end":"2026-07-14T23:03:23.438489Z","steps":["trace[357256868] 'process raft request' (duration: 333.955421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:23.438592Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:23.104584Z","time spent":"333.975221ms","remote":"127.0.0.1:47802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":712,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:03:23.438669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:23.104285Z","time spent":"334.320851ms","remote":"127.0.0.1:47918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3576,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T23:03:23.439087Z","caller":"traceutil/trace.go:171","msg":"trace[88271407] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"334.335523ms","start":"2026-07-14T23:03:23.104739Z","end":"2026-07-14T23:03:23.439075Z","steps":["trace[88271407] 'process raft request' (duration: 333.679561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:23.4392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:23.104733Z","time spent":"334.390953ms","remote":"127.0.0.1:48212","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4320,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:03:23.440262Z","caller":"traceutil/trace.go:171","msg":"trace[1660997675] linearizableReadLoop","detail":"{readStateIndex:488; appliedIndex:483; }","duration":"261.985204ms","start":"2026-07-14T23:03:23.178256Z","end":"2026-07-14T23:03:23.440241Z","steps":["trace[1660997675] 'read index received' (duration: 47.782793ms)","trace[1660997675] 'applied index is now lower than readState.Index' (duration: 214.201591ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:23.440579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.320945ms","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-14T23:03:23.443373Z","caller":"traceutil/trace.go:171","msg":"trace[1048935038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:468; }","duration":"265.11194ms","start":"2026-07-14T23:03:23.178214Z","end":"2026-07-14T23:03:23.443326Z","steps":["trace[1048935038] 'agreement among raft nodes before linearized reading' (duration: 262.251165ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:23.440871Z","caller":"traceutil/trace.go:171","msg":"trace[1911206910] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"272.106293ms","start":"2026-07-14T23:03:23.16875Z","end":"2026-07-14T23:03:23.440856Z","steps":["trace[1911206910] 'process raft request' (duration: 271.239261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:23.479474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.492128ms","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-14T23:03:23.479524Z","caller":"traceutil/trace.go:171","msg":"trace[1723514741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:475; }","duration":"123.582679ms","start":"2026-07-14T23:03:23.355929Z","end":"2026-07-14T23:03:23.479512Z","steps":["trace[1723514741] 'agreement among raft nodes before linearized reading' (duration: 123.493118ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:25.431663Z","caller":"traceutil/trace.go:171","msg":"trace[259250] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"329.980774ms","start":"2026-07-14T23:03:25.10166Z","end":"2026-07-14T23:03:25.431641Z","steps":["trace[259250] 'process raft request' (duration: 280.829786ms)","trace[259250] 'compare' (duration: 48.776628ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:03:25.431741Z","caller":"traceutil/trace.go:171","msg":"trace[1178846923] transaction","detail":"{read_only:false; number_of_response:1; response_revision:510; }","duration":"323.38168ms","start":"2026-07-14T23:03:25.108336Z","end":"2026-07-14T23:03:25.431718Z","steps":["trace[1178846923] 'process raft request' (duration: 323.10181ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:25.43179Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:25.101636Z","time spent":"330.087034ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:03:25.432256Z","caller":"traceutil/trace.go:171","msg":"trace[1427024416] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"315.367394ms","start":"2026-07-14T23:03:25.116878Z","end":"2026-07-14T23:03:25.432246Z","steps":["trace[1427024416] 'process raft request' (duration: 314.662453ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:25.432699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:25.116854Z","time spent":"315.761775ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:03:25.433578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:25.108317Z","time spent":"323.47631ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7375,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:03:25.440248Z","caller":"traceutil/trace.go:171","msg":"trace[774910976] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"299.349302ms","start":"2026-07-14T23:03:25.140867Z","end":"2026-07-14T23:03:25.440216Z","steps":["trace[774910976] 'process raft request' (duration: 299.137532ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:25.44074Z","caller":"traceutil/trace.go:171","msg":"trace[1479419664] linearizableReadLoop","detail":"{readStateIndex:537; appliedIndex:533; }","duration":"316.382726ms","start":"2026-07-14T23:03:25.124282Z","end":"2026-07-14T23:03:25.440665Z","steps":["trace[1479419664] 'read index received' (duration: 258.21996ms)","trace[1479419664] 'applied index is now lower than readState.Index' (duration: 58.157566ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:03:25.440943Z","caller":"traceutil/trace.go:171","msg":"trace[809586506] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"321.800446ms","start":"2026-07-14T23:03:25.119118Z","end":"2026-07-14T23:03:25.440918Z","steps":["trace[809586506] 'process raft request' (duration: 312.660798ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:25.441047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:25.119085Z","time spent":"321.897837ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:03:25.441425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.538221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:30408"} {"level":"info","ts":"2026-07-14T23:03:25.441479Z","caller":"traceutil/trace.go:171","msg":"trace[1265667382] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:513; }","duration":"328.662231ms","start":"2026-07-14T23:03:25.112802Z","end":"2026-07-14T23:03:25.441464Z","steps":["trace[1265667382] 'agreement among raft nodes before linearized reading' (duration: 328.461031ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:25.441518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:25.11279Z","time spent":"328.710281ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":30431,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/tcproutes.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-07-14T23:03:25.441695Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.985425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:995"} {"level":"info","ts":"2026-07-14T23:03:25.441736Z","caller":"traceutil/trace.go:171","msg":"trace[329756973] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:513; }","duration":"316.043655ms","start":"2026-07-14T23:03:25.125672Z","end":"2026-07-14T23:03:25.441716Z","steps":["trace[329756973] 'agreement among raft nodes before linearized reading' (duration: 315.970205ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:25.441764Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:25.125658Z","time spent":"316.097185ms","remote":"127.0.0.1:48278","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1018,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/v1.gateway.networking.k8s.io\" "} {"level":"warn","ts":"2026-07-14T23:03:26.973771Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.469564057s","expected-duration":"1s"} {"level":"info","ts":"2026-07-14T23:03:27.025718Z","caller":"traceutil/trace.go:171","msg":"trace[1462722679] linearizableReadLoop","detail":"{readStateIndex:540; appliedIndex:539; }","duration":"1.539861041s","start":"2026-07-14T23:03:25.485835Z","end":"2026-07-14T23:03:27.025696Z","steps":["trace[1462722679] 'read index received' (duration: 18.562638ms)","trace[1462722679] 'applied index is now lower than readState.Index' (duration: 1.521297423s)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:03:27.025825Z","caller":"traceutil/trace.go:171","msg":"trace[568724554] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"1.523000007s","start":"2026-07-14T23:03:25.502815Z","end":"2026-07-14T23:03:27.025815Z","steps":["trace[568724554] 'process raft request' (duration: 1.522811727s)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:27.025791Z","caller":"traceutil/trace.go:171","msg":"trace[996147030] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"1.573213998s","start":"2026-07-14T23:03:25.452546Z","end":"2026-07-14T23:03:27.02576Z","steps":["trace[996147030] 'process raft request' (duration: 1.521430451s)","trace[996147030] 'compare' (duration: 51.194946ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:27.025915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:25.502795Z","time spent":"1.523063137s","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:03:27.026053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.540206623s","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-14T23:03:27.025968Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:25.452496Z","time spent":"1.573375788s","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9125,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:03:27.026114Z","caller":"traceutil/trace.go:171","msg":"trace[1255324392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:516; }","duration":"1.540290013s","start":"2026-07-14T23:03:25.485809Z","end":"2026-07-14T23:03:27.026099Z","steps":["trace[1255324392] 'agreement among raft nodes before linearized reading' (duration: 1.540087502s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:27.026174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:25.485796Z","time spent":"1.540367833s","remote":"127.0.0.1:48016","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-14T23:03:27.250081Z","caller":"traceutil/trace.go:171","msg":"trace[572497685] linearizableReadLoop","detail":"{readStateIndex:543; appliedIndex:541; }","duration":"224.269411ms","start":"2026-07-14T23:03:27.025787Z","end":"2026-07-14T23:03:27.250057Z","steps":["trace[572497685] 'read index received' (duration: 161.98078ms)","trace[572497685] 'applied index is now lower than readState.Index' (duration: 62.285311ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:27.250311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.93845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:03:27.250372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.482173175s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:03:27.250395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.733765262s","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-14T23:03:27.250412Z","caller":"traceutil/trace.go:171","msg":"trace[362215882] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:518; }","duration":"448.062421ms","start":"2026-07-14T23:03:26.802327Z","end":"2026-07-14T23:03:27.250389Z","steps":["trace[362215882] 'agreement among raft nodes before linearized reading' (duration: 447.83735ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:27.250466Z","caller":"traceutil/trace.go:171","msg":"trace[1423637032] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:518; }","duration":"1.733913682s","start":"2026-07-14T23:03:25.516539Z","end":"2026-07-14T23:03:27.250452Z","steps":["trace[1423637032] 'agreement among raft nodes before linearized reading' (duration: 1.733649791s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:27.250423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.217201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:03:27.250503Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:26.802305Z","time spent":"448.186471ms","remote":"127.0.0.1:34398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"info","ts":"2026-07-14T23:03:27.250497Z","caller":"traceutil/trace.go:171","msg":"trace[1747718488] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:518; }","duration":"1.482312245s","start":"2026-07-14T23:03:25.768099Z","end":"2026-07-14T23:03:27.250411Z","steps":["trace[1747718488] 'agreement among raft nodes before linearized reading' (duration: 1.482184015s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:27.250518Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:25.516494Z","time spent":"1.734011012s","remote":"127.0.0.1:48016","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-14T23:03:27.250533Z","caller":"traceutil/trace.go:171","msg":"trace[562480318] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:518; }","duration":"448.393811ms","start":"2026-07-14T23:03:26.802119Z","end":"2026-07-14T23:03:27.250513Z","steps":["trace[562480318] 'agreement among raft nodes before linearized reading' (duration: 448.197691ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:27.250533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.478325787s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.112\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-14T23:03:27.25054Z","caller":"traceutil/trace.go:171","msg":"trace[1980431110] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"1.732798859s","start":"2026-07-14T23:03:25.517713Z","end":"2026-07-14T23:03:27.250512Z","steps":["trace[1980431110] 'process raft request' (duration: 1.670228907s)","trace[1980431110] 'compare' (duration: 61.89223ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:27.250563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:25.768078Z","time spent":"1.482468955s","remote":"127.0.0.1:47736","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-14T23:03:27.250587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:26.802105Z","time spent":"448.470061ms","remote":"127.0.0.1:34398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:10000 "} {"level":"info","ts":"2026-07-14T23:03:27.250593Z","caller":"traceutil/trace.go:171","msg":"trace[1226324069] range","detail":"{range_begin:/registry/masterleases/199.19.213.112; range_end:; response_count:1; response_revision:518; }","duration":"1.478383677s","start":"2026-07-14T23:03:25.772191Z","end":"2026-07-14T23:03:27.250574Z","steps":["trace[1226324069] 'agreement among raft nodes before linearized reading' (duration: 1.478232517s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:27.250627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:25.772141Z","time spent":"1.478478578s","remote":"127.0.0.1:47752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.112\" "} {"level":"warn","ts":"2026-07-14T23:03:27.250665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:25.517694Z","time spent":"1.732898179s","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":150480,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:03:27.25073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.78457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:03:27.25076Z","caller":"traceutil/trace.go:171","msg":"trace[155955363] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:518; }","duration":"447.82511ms","start":"2026-07-14T23:03:26.802926Z","end":"2026-07-14T23:03:27.250751Z","steps":["trace[155955363] 'agreement among raft nodes before linearized reading' (duration: 447.77958ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:27.250783Z","caller":"traceutil/trace.go:171","msg":"trace[951091873] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"1.541002324s","start":"2026-07-14T23:03:25.709771Z","end":"2026-07-14T23:03:27.250773Z","steps":["trace[951091873] 'process raft request' (duration: 1.540216893s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:27.250788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:26.80292Z","time spent":"447.85986ms","remote":"127.0.0.1:34398","response type":"/etcdserverpb.KV/Range","request count":0,"request size":111,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" limit:500 "} {"level":"warn","ts":"2026-07-14T23:03:27.250807Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.712022357s","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-14T23:03:27.250834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:25.709731Z","time spent":"1.541074134s","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:03:27.25085Z","caller":"traceutil/trace.go:171","msg":"trace[477127849] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:518; }","duration":"1.712065137s","start":"2026-07-14T23:03:25.538772Z","end":"2026-07-14T23:03:27.250837Z","steps":["trace[477127849] 'agreement among raft nodes before linearized reading' (duration: 1.711941597s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:27.250886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:25.538746Z","time spent":"1.712131567s","remote":"127.0.0.1:48016","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-14T23:03:27.251889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.714434003s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T23:03:27.251924Z","caller":"traceutil/trace.go:171","msg":"trace[1228773015] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:518; }","duration":"1.714509563s","start":"2026-07-14T23:03:25.537405Z","end":"2026-07-14T23:03:27.251915Z","steps":["trace[1228773015] 'agreement among raft nodes before linearized reading' (duration: 1.71346878s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:27.251952Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:25.537385Z","time spent":"1.714558743s","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-14T23:03:27.300846Z","caller":"traceutil/trace.go:171","msg":"trace[1862139011] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"268.095553ms","start":"2026-07-14T23:03:27.032727Z","end":"2026-07-14T23:03:27.300822Z","steps":["trace[1862139011] 'process raft request' (duration: 267.905792ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:27.30086Z","caller":"traceutil/trace.go:171","msg":"trace[1845357430] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"267.891132ms","start":"2026-07-14T23:03:27.032953Z","end":"2026-07-14T23:03:27.300844Z","steps":["trace[1845357430] 'process raft request' (duration: 267.738831ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:27.301078Z","caller":"traceutil/trace.go:171","msg":"trace[2102512767] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"268.870304ms","start":"2026-07-14T23:03:27.032181Z","end":"2026-07-14T23:03:27.301051Z","steps":["trace[2102512767] 'process raft request' (duration: 268.310983ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:27.301171Z","caller":"traceutil/trace.go:171","msg":"trace[812758926] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"262.60982ms","start":"2026-07-14T23:03:27.03855Z","end":"2026-07-14T23:03:27.30116Z","steps":["trace[812758926] 'process raft request' (duration: 262.198279ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:27.301575Z","caller":"traceutil/trace.go:171","msg":"trace[1228298174] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"194.356617ms","start":"2026-07-14T23:03:27.107206Z","end":"2026-07-14T23:03:27.301563Z","steps":["trace[1228298174] 'process raft request' (duration: 193.898646ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:28.345478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"823.385681ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:03:28.345782Z","caller":"traceutil/trace.go:171","msg":"trace[847502875] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"1.0283054s","start":"2026-07-14T23:03:27.317456Z","end":"2026-07-14T23:03:28.345761Z","steps":["trace[847502875] 'process raft request' (duration: 1.0282011s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:28.345887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:27.317428Z","time spent":"1.02841601s","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:03:28.346069Z","caller":"traceutil/trace.go:171","msg":"trace[2049049547] transaction","detail":"{read_only:false; response_revision:527; number_of_response:1; }","duration":"1.028629611s","start":"2026-07-14T23:03:27.317411Z","end":"2026-07-14T23:03:28.346041Z","steps":["trace[2049049547] 'process raft request' (duration: 204.600359ms)","trace[2049049547] 'compare' (duration: 823.263351ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:28.346216Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:27.317387Z","time spent":"1.028740011s","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31102,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:03:28.622463Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.098055659s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-14T23:03:28.90217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.451338ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:03:28.902438Z","caller":"traceutil/trace.go:171","msg":"trace[1730440892] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"1.567995946s","start":"2026-07-14T23:03:27.334419Z","end":"2026-07-14T23:03:28.902415Z","steps":["trace[1730440892] 'process raft request' (duration: 1.288228047s)","trace[1730440892] 'compare' (duration: 279.332647ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:28.902553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:27.334405Z","time spent":"1.568089626s","remote":"127.0.0.1:47752","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-14T23:03:28.902587Z","caller":"traceutil/trace.go:171","msg":"trace[1832631902] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"1.543285684s","start":"2026-07-14T23:03:27.359274Z","end":"2026-07-14T23:03:28.90256Z","steps":["trace[1832631902] 'process raft request' (duration: 1.543129714s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:28.902712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:27.359188Z","time spent":"1.543465114s","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T23:03:28.902797Z","caller":"traceutil/trace.go:171","msg":"trace[2089255200] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"1.099120702s","start":"2026-07-14T23:03:27.80366Z","end":"2026-07-14T23:03:28.902781Z","steps":["trace[2089255200] 'process raft request' (duration: 1.098824132s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:28.902972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:27.803595Z","time spent":"1.099343892s","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:03:28.903021Z","caller":"traceutil/trace.go:171","msg":"trace[403878745] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"539.033623ms","start":"2026-07-14T23:03:28.363977Z","end":"2026-07-14T23:03:28.903011Z","steps":["trace[403878745] 'process raft request' (duration: 538.660923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:28.903075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:28.363953Z","time spent":"539.093004ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:03:28.903236Z","caller":"traceutil/trace.go:171","msg":"trace[669375188] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"534.196842ms","start":"2026-07-14T23:03:28.369032Z","end":"2026-07-14T23:03:28.903229Z","steps":["trace[669375188] 'process raft request' (duration: 533.901611ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:28.903256Z","caller":"traceutil/trace.go:171","msg":"trace[847536613] linearizableReadLoop","detail":"{readStateIndex:559; appliedIndex:554; }","duration":"134.677478ms","start":"2026-07-14T23:03:28.76856Z","end":"2026-07-14T23:03:28.903237Z","steps":["trace[847536613] 'read index received' (duration: 130.6864ms)","trace[847536613] 'applied index is now lower than readState.Index' (duration: 3.989238ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:28.903279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:28.369009Z","time spent":"534.248352ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31111,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:03:28.903354Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.811408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:03:28.903405Z","caller":"traceutil/trace.go:171","msg":"trace[865512413] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:533; }","duration":"134.903028ms","start":"2026-07-14T23:03:28.768489Z","end":"2026-07-14T23:03:28.903392Z","steps":["trace[865512413] 'agreement among raft nodes before linearized reading' (duration: 134.810908ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:29.646252Z","caller":"traceutil/trace.go:171","msg":"trace[1007838425] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"432.864573ms","start":"2026-07-14T23:03:29.213357Z","end":"2026-07-14T23:03:29.646222Z","steps":["trace[1007838425] 'process raft request' (duration: 413.329991ms)","trace[1007838425] 'compare' (duration: 18.71594ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:29.646423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:29.213332Z","time spent":"433.001773ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:03:29.646505Z","caller":"traceutil/trace.go:171","msg":"trace[546399001] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"407.717829ms","start":"2026-07-14T23:03:29.238754Z","end":"2026-07-14T23:03:29.646472Z","steps":["trace[546399001] 'process raft request' (duration: 407.520458ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:29.646731Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:29.238725Z","time spent":"407.864649ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T23:03:29.646852Z","caller":"traceutil/trace.go:171","msg":"trace[1024068087] linearizableReadLoop","detail":"{readStateIndex:578; appliedIndex:576; }","duration":"340.741283ms","start":"2026-07-14T23:03:29.306092Z","end":"2026-07-14T23:03:29.646833Z","steps":["trace[1024068087] 'read index received' (duration: 320.67715ms)","trace[1024068087] 'applied index is now lower than readState.Index' (duration: 20.063563ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:03:29.646892Z","caller":"traceutil/trace.go:171","msg":"trace[1019387071] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"325.718059ms","start":"2026-07-14T23:03:29.321145Z","end":"2026-07-14T23:03:29.646863Z","steps":["trace[1019387071] 'process raft request' (duration: 325.500219ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:29.647023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.557746ms","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-14T23:03:29.647018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:29.320865Z","time spent":"326.093751ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":569094,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T23:03:29.647046Z","caller":"traceutil/trace.go:171","msg":"trace[1795797364] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:553; }","duration":"309.620326ms","start":"2026-07-14T23:03:29.337417Z","end":"2026-07-14T23:03:29.647037Z","steps":["trace[1795797364] 'agreement among raft nodes before linearized reading' (duration: 309.513216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:29.647079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:29.3374Z","time spent":"309.665256ms","remote":"127.0.0.1:48016","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-14T23:03:29.647097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.022964ms","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-14T23:03:29.647158Z","caller":"traceutil/trace.go:171","msg":"trace[989030812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:553; }","duration":"309.094124ms","start":"2026-07-14T23:03:29.338043Z","end":"2026-07-14T23:03:29.647137Z","steps":["trace[989030812] 'agreement among raft nodes before linearized reading' (duration: 308.908784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:29.64717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.659525ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-14T23:03:29.647194Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:29.338035Z","time spent":"309.151995ms","remote":"127.0.0.1:48016","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-14T23:03:29.647233Z","caller":"traceutil/trace.go:171","msg":"trace[2126671034] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:553; }","duration":"309.749937ms","start":"2026-07-14T23:03:29.337466Z","end":"2026-07-14T23:03:29.647216Z","steps":["trace[2126671034] 'agreement among raft nodes before linearized reading' (duration: 309.522426ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:29.64727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:29.337461Z","time spent":"309.801217ms","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-14T23:03:29.647465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.366355ms","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-14T23:03:29.647511Z","caller":"traceutil/trace.go:171","msg":"trace[597689995] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:553; }","duration":"341.441395ms","start":"2026-07-14T23:03:29.306055Z","end":"2026-07-14T23:03:29.647496Z","steps":["trace[597689995] 'agreement among raft nodes before linearized reading' (duration: 340.866704ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:29.647541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:29.306038Z","time spent":"341.495755ms","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-14T23:03:35.50165Z","caller":"traceutil/trace.go:171","msg":"trace[81317421] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"301.213378ms","start":"2026-07-14T23:03:35.200316Z","end":"2026-07-14T23:03:35.50153Z","steps":["trace[81317421] 'process raft request' (duration: 300.421886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:35.502932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:35.200289Z","time spent":"302.510111ms","remote":"127.0.0.1:47820","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:03:36.196184Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":1958678134937544963,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T23:03:36.2717Z","caller":"traceutil/trace.go:171","msg":"trace[1904303578] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"752.350997ms","start":"2026-07-14T23:03:35.519322Z","end":"2026-07-14T23:03:36.271673Z","steps":["trace[1904303578] 'process raft request' (duration: 752.094037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:36.271829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:35.519299Z","time spent":"752.457167ms","remote":"127.0.0.1:47918","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:03:37.769847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000277256s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-07-14T23:03:37.769973Z","caller":"traceutil/trace.go:171","msg":"trace[615013052] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000451747s","start":"2026-07-14T23:03:35.769494Z","end":"2026-07-14T23:03:37.769945Z","steps":["trace[615013052] 'agreement among raft nodes before linearized reading' (duration: 2.000290516s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:37.770036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:35.769482Z","time spent":"2.000541567s","remote":"127.0.0.1:47722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/07/14 23:03:37 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-07-14T23:03:38.847286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.272279662s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:03:38.847444Z","caller":"traceutil/trace.go:171","msg":"trace[31965779] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"3.282218403s","start":"2026-07-14T23:03:35.565187Z","end":"2026-07-14T23:03:38.847405Z","steps":["trace[31965779] 'process raft request' (duration: 1.009737391s)","trace[31965779] 'compare' (duration: 2.272104421s)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:03:38.847496Z","caller":"traceutil/trace.go:171","msg":"trace[1285398754] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"1.309766012s","start":"2026-07-14T23:03:37.537713Z","end":"2026-07-14T23:03:38.847479Z","steps":["trace[1285398754] 'process raft request' (duration: 1.309700822s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:38.847586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:35.565168Z","time spent":"3.282348563s","remote":"127.0.0.1:47976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T23:03:38.847626Z","caller":"traceutil/trace.go:171","msg":"trace[2121810068] linearizableReadLoop","detail":"{readStateIndex:630; appliedIndex:628; }","duration":"3.152085409s","start":"2026-07-14T23:03:35.695531Z","end":"2026-07-14T23:03:38.847617Z","steps":["trace[2121810068] 'read index received' (duration: 576.028801ms)","trace[2121810068] 'applied index is now lower than readState.Index' (duration: 2.576055588s)"],"step_count":2} {"level":"warn","ts":"2026-07-14T23:03:38.847584Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:37.537682Z","time spent":"1.309854902s","remote":"127.0.0.1:48016","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-14T23:03:38.847912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.152376509s","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-14T23:03:38.847943Z","caller":"traceutil/trace.go:171","msg":"trace[586557884] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:604; }","duration":"3.152441689s","start":"2026-07-14T23:03:35.695492Z","end":"2026-07-14T23:03:38.847934Z","steps":["trace[586557884] 'agreement among raft nodes before linearized reading' (duration: 3.152304909s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:38.847974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:35.695471Z","time spent":"3.152496999s","remote":"127.0.0.1:48016","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-14T23:03:39.112769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.2055ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T23:03:39.113018Z","caller":"traceutil/trace.go:171","msg":"trace[1869948965] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"1.339632978s","start":"2026-07-14T23:03:37.773306Z","end":"2026-07-14T23:03:39.112939Z","steps":["trace[1869948965] 'process raft request' (duration: 1.234151398s)","trace[1869948965] 'compare' (duration: 104.762769ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:03:39.113051Z","caller":"traceutil/trace.go:171","msg":"trace[951509321] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"265.373963ms","start":"2026-07-14T23:03:38.84765Z","end":"2026-07-14T23:03:39.113024Z","steps":["trace[951509321] 'read index received' (duration: 159.962974ms)","trace[951509321] 'applied index is now lower than readState.Index' (duration: 105.407409ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:03:39.113122Z","caller":"traceutil/trace.go:171","msg":"trace[2066688554] transaction","detail":"{read_only:false; response_revision:607; number_of_response:1; }","duration":"196.898141ms","start":"2026-07-14T23:03:38.916204Z","end":"2026-07-14T23:03:39.113102Z","steps":["trace[2066688554] 'process raft request' (duration: 196.82648ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:39.113265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.984209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T23:03:39.113212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:37.77327Z","time spent":"1.33984479s","remote":"127.0.0.1:47802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":759,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T23:03:39.11331Z","caller":"traceutil/trace.go:171","msg":"trace[204195774] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"295.09812ms","start":"2026-07-14T23:03:38.818201Z","end":"2026-07-14T23:03:39.113299Z","steps":["trace[204195774] 'agreement among raft nodes before linearized reading' (duration: 294.962909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:39.113587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.33592851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:03:39.113627Z","caller":"traceutil/trace.go:171","msg":"trace[1167736219] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:607; }","duration":"1.33599597s","start":"2026-07-14T23:03:37.777621Z","end":"2026-07-14T23:03:39.113617Z","steps":["trace[1167736219] 'agreement among raft nodes before linearized reading' (duration: 1.33594118s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:39.113654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:37.77761Z","time spent":"1.33603701s","remote":"127.0.0.1:47736","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-14T23:03:39.113718Z","caller":"traceutil/trace.go:171","msg":"trace[527833059] transaction","detail":"{read_only:false; response_revision:606; number_of_response:1; }","duration":"260.220881ms","start":"2026-07-14T23:03:38.853462Z","end":"2026-07-14T23:03:39.113683Z","steps":["trace[527833059] 'process raft request' (duration: 259.464819ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:39.113869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.341121079s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.112\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-14T23:03:39.113905Z","caller":"traceutil/trace.go:171","msg":"trace[248580533] range","detail":"{range_begin:/registry/masterleases/199.19.213.112; range_end:; response_count:1; response_revision:607; }","duration":"3.341176399s","start":"2026-07-14T23:03:35.77272Z","end":"2026-07-14T23:03:39.113897Z","steps":["trace[248580533] 'agreement among raft nodes before linearized reading' (duration: 3.341046199s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:39.113938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:35.772707Z","time spent":"3.341224419s","remote":"127.0.0.1:47752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.112\" "} {"level":"warn","ts":"2026-07-14T23:03:39.113938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.482349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/job-controller\" ","response":"range_response_count:1 size:193"} {"level":"info","ts":"2026-07-14T23:03:39.113983Z","caller":"traceutil/trace.go:171","msg":"trace[385548867] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/job-controller; range_end:; response_count:1; response_revision:607; }","duration":"259.594259ms","start":"2026-07-14T23:03:38.854374Z","end":"2026-07-14T23:03:39.113968Z","steps":["trace[385548867] 'agreement among raft nodes before linearized reading' (duration: 259.454589ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:39.114206Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.412698781s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-07-14T23:03:39.11424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.418653305s","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-14T23:03:39.114264Z","caller":"traceutil/trace.go:171","msg":"trace[1750743413] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:607; }","duration":"3.412791751s","start":"2026-07-14T23:03:35.701456Z","end":"2026-07-14T23:03:39.114248Z","steps":["trace[1750743413] 'agreement among raft nodes before linearized reading' (duration: 3.412573461s)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:39.114275Z","caller":"traceutil/trace.go:171","msg":"trace[262698459] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:607; }","duration":"3.418714115s","start":"2026-07-14T23:03:35.69555Z","end":"2026-07-14T23:03:39.114264Z","steps":["trace[262698459] 'agreement among raft nodes before linearized reading' (duration: 3.418575025s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:39.114314Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:35.701439Z","time spent":"3.412849641s","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T23:03:39.114305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:35.695538Z","time spent":"3.418760196s","remote":"127.0.0.1:48016","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-14T23:03:39.114467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.413019132s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T23:03:39.114606Z","caller":"traceutil/trace.go:171","msg":"trace[1434124333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:607; }","duration":"3.413180543s","start":"2026-07-14T23:03:35.701378Z","end":"2026-07-14T23:03:39.114558Z","steps":["trace[1434124333] 'agreement among raft nodes before linearized reading' (duration: 3.412627881s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:39.114663Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:35.701368Z","time spent":"3.413285123s","remote":"127.0.0.1:48016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-14T23:03:41.035173Z","caller":"traceutil/trace.go:171","msg":"trace[821499301] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"264.393028ms","start":"2026-07-14T23:03:40.770754Z","end":"2026-07-14T23:03:41.035147Z","steps":["trace[821499301] 'read index received' (duration: 264.370118ms)","trace[821499301] 'applied index is now lower than readState.Index' (duration: 21.79µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:03:41.035283Z","caller":"traceutil/trace.go:171","msg":"trace[1347308879] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"389.042ms","start":"2026-07-14T23:03:40.64622Z","end":"2026-07-14T23:03:41.035262Z","steps":["trace[1347308879] 'process raft request' (duration: 388.7924ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:41.035469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.71054ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:03:41.035529Z","caller":"traceutil/trace.go:171","msg":"trace[996077046] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:641; }","duration":"264.82591ms","start":"2026-07-14T23:03:40.770688Z","end":"2026-07-14T23:03:41.035514Z","steps":["trace[996077046] 'agreement among raft nodes before linearized reading' (duration: 264.640438ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:41.035619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:40.64619Z","time spent":"389.233282ms","remote":"127.0.0.1:47772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3104,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T23:03:41.040912Z","caller":"traceutil/trace.go:171","msg":"trace[631700535] transaction","detail":"{read_only:false; response_revision:642; number_of_response:1; }","duration":"193.485944ms","start":"2026-07-14T23:03:40.847392Z","end":"2026-07-14T23:03:41.040878Z","steps":["trace[631700535] 'process raft request' (duration: 193.319504ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:44.068075Z","caller":"traceutil/trace.go:171","msg":"trace[902134776] linearizableReadLoop","detail":"{readStateIndex:712; appliedIndex:711; }","duration":"222.832394ms","start":"2026-07-14T23:03:43.845217Z","end":"2026-07-14T23:03:44.068049Z","steps":["trace[902134776] 'read index received' (duration: 222.659533ms)","trace[902134776] 'applied index is now lower than readState.Index' (duration: 172.011µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:03:44.068155Z","caller":"traceutil/trace.go:171","msg":"trace[783441274] transaction","detail":"{read_only:false; response_revision:683; number_of_response:1; }","duration":"413.716518ms","start":"2026-07-14T23:03:43.654402Z","end":"2026-07-14T23:03:44.068118Z","steps":["trace[783441274] 'process raft request' (duration: 413.535577ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:44.068333Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:43.654378Z","time spent":"413.870369ms","remote":"127.0.0.1:47802","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":716,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-14T23:03:44.068389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.148375ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io\" ","response":"range_response_count:1 size:10377"} {"level":"info","ts":"2026-07-14T23:03:44.068463Z","caller":"traceutil/trace.go:171","msg":"trace[1278021171] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliuml2announcementpolicies.cilium.io; range_end:; response_count:1; response_revision:683; }","duration":"223.280935ms","start":"2026-07-14T23:03:43.845163Z","end":"2026-07-14T23:03:44.068444Z","steps":["trace[1278021171] 'agreement among raft nodes before linearized reading' (duration: 222.997094ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:49.386765Z","caller":"traceutil/trace.go:171","msg":"trace[1869994564] transaction","detail":"{read_only:false; response_revision:730; number_of_response:1; }","duration":"201.23116ms","start":"2026-07-14T23:03:49.185511Z","end":"2026-07-14T23:03:49.386742Z","steps":["trace[1869994564] 'process raft request' (duration: 201.06455ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:49.387104Z","caller":"traceutil/trace.go:171","msg":"trace[1224316738] transaction","detail":"{read_only:false; response_revision:731; number_of_response:1; }","duration":"201.367751ms","start":"2026-07-14T23:03:49.185724Z","end":"2026-07-14T23:03:49.387092Z","steps":["trace[1224316738] 'process raft request' (duration: 200.983369ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:49.387317Z","caller":"traceutil/trace.go:171","msg":"trace[745371739] transaction","detail":"{read_only:false; response_revision:733; number_of_response:1; }","duration":"168.902028ms","start":"2026-07-14T23:03:49.218392Z","end":"2026-07-14T23:03:49.387294Z","steps":["trace[745371739] 'process raft request' (duration: 168.701897ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:49.387412Z","caller":"traceutil/trace.go:171","msg":"trace[1109007465] transaction","detail":"{read_only:false; response_revision:732; number_of_response:1; }","duration":"199.762627ms","start":"2026-07-14T23:03:49.18763Z","end":"2026-07-14T23:03:49.387393Z","steps":["trace[1109007465] 'process raft request' (duration: 199.315805ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:03:51.352631Z","caller":"traceutil/trace.go:171","msg":"trace[1367376979] transaction","detail":"{read_only:false; response_revision:747; number_of_response:1; }","duration":"106.525423ms","start":"2026-07-14T23:03:51.246083Z","end":"2026-07-14T23:03:51.352608Z","steps":["trace[1367376979] 'process raft request' (duration: 106.303863ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:55.286952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"980.281829ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-14T23:03:55.287198Z","caller":"traceutil/trace.go:171","msg":"trace[153377037] linearizableReadLoop","detail":"{readStateIndex:797; appliedIndex:796; }","duration":"765.387678ms","start":"2026-07-14T23:03:54.521794Z","end":"2026-07-14T23:03:55.287182Z","steps":["trace[153377037] 'read index received' (duration: 38.73µs)","trace[153377037] 'applied index is now lower than readState.Index' (duration: 765.347858ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:03:55.287489Z","caller":"traceutil/trace.go:171","msg":"trace[141923406] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"708.234805ms","start":"2026-07-14T23:03:54.579187Z","end":"2026-07-14T23:03:55.287422Z","steps":["trace[141923406] 'process raft request' (duration: 707.875833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:55.288263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"765.573309ms","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-14T23:03:55.288412Z","caller":"traceutil/trace.go:171","msg":"trace[1799277351] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:764; }","duration":"766.620271ms","start":"2026-07-14T23:03:54.521756Z","end":"2026-07-14T23:03:55.288376Z","steps":["trace[1799277351] 'agreement among raft nodes before linearized reading' (duration: 765.527978ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:55.288624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:54.521737Z","time spent":"766.738031ms","remote":"127.0.0.1:48016","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-14T23:03:55.288012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:54.579161Z","time spent":"708.384235ms","remote":"127.0.0.1:47976","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T23:03:55.301473Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.266289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:03:55.301547Z","caller":"traceutil/trace.go:171","msg":"trace[1353457713] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:765; }","duration":"376.38793ms","start":"2026-07-14T23:03:54.925141Z","end":"2026-07-14T23:03:55.301529Z","steps":["trace[1353457713] 'agreement among raft nodes before linearized reading' (duration: 362.263427ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:03:55.301649Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:03:54.925118Z","time spent":"376.51457ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-14T23:03:56.419649Z","caller":"traceutil/trace.go:171","msg":"trace[563905524] transaction","detail":"{read_only:false; response_revision:778; number_of_response:1; }","duration":"112.044235ms","start":"2026-07-14T23:03:56.307582Z","end":"2026-07-14T23:03:56.419626Z","steps":["trace[563905524] 'process raft request' (duration: 111.892715ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:08.981853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.138575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:04:08.981937Z","caller":"traceutil/trace.go:171","msg":"trace[128606786] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:937; }","duration":"164.266836ms","start":"2026-07-14T23:04:08.817653Z","end":"2026-07-14T23:04:08.981919Z","steps":["trace[128606786] 'range keys from in-memory index tree' (duration: 164.077725ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:08.982174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.154356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:04:08.982352Z","caller":"traceutil/trace.go:171","msg":"trace[1513198626] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:937; }","duration":"182.662757ms","start":"2026-07-14T23:04:08.799674Z","end":"2026-07-14T23:04:08.982337Z","steps":["trace[1513198626] 'count revisions from in-memory index tree' (duration: 182.064456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:35.729087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.505562ms","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-14T23:04:35.729181Z","caller":"traceutil/trace.go:171","msg":"trace[582611864] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1039; }","duration":"114.655154ms","start":"2026-07-14T23:04:35.614508Z","end":"2026-07-14T23:04:35.729163Z","steps":["trace[582611864] 'range keys from in-memory index tree' (duration: 114.304622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:35.729269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.639661ms","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-14T23:04:35.729347Z","caller":"traceutil/trace.go:171","msg":"trace[625042012] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1039; }","duration":"103.768701ms","start":"2026-07-14T23:04:35.625561Z","end":"2026-07-14T23:04:35.72933Z","steps":["trace[625042012] 'range keys from in-memory index tree' (duration: 103.494471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:35.72958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.192127ms","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-14T23:04:35.729682Z","caller":"traceutil/trace.go:171","msg":"trace[827410414] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1039; }","duration":"123.335447ms","start":"2026-07-14T23:04:35.606323Z","end":"2026-07-14T23:04:35.729658Z","steps":["trace[827410414] 'range keys from in-memory index tree' (duration: 123.044057ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:04:35.799603Z","caller":"traceutil/trace.go:171","msg":"trace[2000329535] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"163.120982ms","start":"2026-07-14T23:04:35.636462Z","end":"2026-07-14T23:04:35.799583Z","steps":["trace[2000329535] 'process raft request' (duration: 163.008151ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:04:35.929657Z","caller":"traceutil/trace.go:171","msg":"trace[860215323] transaction","detail":"{read_only:false; response_revision:1041; number_of_response:1; }","duration":"196.403741ms","start":"2026-07-14T23:04:35.733222Z","end":"2026-07-14T23:04:35.929626Z","steps":["trace[860215323] 'process raft request' (duration: 178.293151ms)","trace[860215323] 'compare' (duration: 17.869978ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:04:35.929882Z","caller":"traceutil/trace.go:171","msg":"trace[1841304552] transaction","detail":"{read_only:false; response_revision:1042; number_of_response:1; }","duration":"196.257469ms","start":"2026-07-14T23:04:35.733595Z","end":"2026-07-14T23:04:35.929852Z","steps":["trace[1841304552] 'process raft request' (duration: 195.976728ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:04:35.929906Z","caller":"traceutil/trace.go:171","msg":"trace[310884934] transaction","detail":"{read_only:false; response_revision:1043; number_of_response:1; }","duration":"195.949278ms","start":"2026-07-14T23:04:35.733935Z","end":"2026-07-14T23:04:35.929884Z","steps":["trace[310884934] 'process raft request' (duration: 195.839108ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:04:35.930011Z","caller":"traceutil/trace.go:171","msg":"trace[206069445] linearizableReadLoop","detail":"{readStateIndex:1088; appliedIndex:1084; }","duration":"173.622091ms","start":"2026-07-14T23:04:35.756367Z","end":"2026-07-14T23:04:35.929989Z","steps":["trace[206069445] 'read index received' (duration: 43.206167ms)","trace[206069445] 'applied index is now lower than readState.Index' (duration: 130.414814ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T23:04:35.930059Z","caller":"traceutil/trace.go:171","msg":"trace[1447034361] transaction","detail":"{read_only:false; response_revision:1044; number_of_response:1; }","duration":"195.586787ms","start":"2026-07-14T23:04:35.734461Z","end":"2026-07-14T23:04:35.930048Z","steps":["trace[1447034361] 'process raft request' (duration: 195.385887ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:35.930174Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.792692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T23:04:35.930209Z","caller":"traceutil/trace.go:171","msg":"trace[306653246] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1044; }","duration":"173.871723ms","start":"2026-07-14T23:04:35.756326Z","end":"2026-07-14T23:04:35.930198Z","steps":["trace[306653246] 'agreement among raft nodes before linearized reading' (duration: 173.782003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:35.930234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.458705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T23:04:35.930271Z","caller":"traceutil/trace.go:171","msg":"trace[2071459443] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1044; }","duration":"161.538195ms","start":"2026-07-14T23:04:35.768721Z","end":"2026-07-14T23:04:35.93026Z","steps":["trace[2071459443] 'agreement among raft nodes before linearized reading' (duration: 161.463885ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:04:35.930465Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.762649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.112\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-14T23:04:35.930487Z","caller":"traceutil/trace.go:171","msg":"trace[1438543172] range","detail":"{range_begin:/registry/masterleases/199.19.213.112; range_end:; response_count:1; response_revision:1044; }","duration":"154.818239ms","start":"2026-07-14T23:04:35.775662Z","end":"2026-07-14T23:04:35.930481Z","steps":["trace[1438543172] 'agreement among raft nodes before linearized reading' (duration: 154.726978ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T23:05:50.345106Z","caller":"traceutil/trace.go:171","msg":"trace[980385976] transaction","detail":"{read_only:false; response_revision:1320; number_of_response:1; }","duration":"339.676091ms","start":"2026-07-14T23:05:50.00541Z","end":"2026-07-14T23:05:50.345086Z","steps":["trace[980385976] 'process raft request' (duration: 339.567401ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T23:05:50.345223Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T23:05:50.005378Z","time spent":"339.784411ms","remote":"127.0.0.1:48016","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-14T23:05:50.385402Z","caller":"traceutil/trace.go:171","msg":"trace[1401857383] transaction","detail":"{read_only:false; response_revision:1321; number_of_response:1; }","duration":"273.264182ms","start":"2026-07-14T23:05:50.112101Z","end":"2026-07-14T23:05:50.385366Z","steps":["trace[1401857383] 'process raft request' (duration: 243.831279ms)","trace[1401857383] 'compare' (duration: 29.265392ms)"],"step_count":2}