{"level":"warn","ts":"2026-07-21T06:44:17.911364Z","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-21T06:44:17.911483Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.48: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.48:2380","--initial-cluster=instance=https://199.19.213.48:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.48:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.48: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-21T06:44:17.911567Z","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-21T06:44:17.911581Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.48:2380"]} {"level":"info","ts":"2026-07-21T06:44:17.911602Z","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-21T06:44:17.91233Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.48:2379"]} {"level":"info","ts":"2026-07-21T06:44:17.912409Z","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.48:2380"],"listen-peer-urls":["https://199.19.213.48:2380"],"advertise-client-urls":["https://199.19.213.48:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.48:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.48: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-21T06:44:17.914539Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.91598ms"} {"level":"info","ts":"2026-07-21T06:44:17.921627Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"7317567213c7319d","cluster-id":"d22ed1e1757a8527"} {"level":"info","ts":"2026-07-21T06:44:17.921756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d switched to configuration voters=()"} {"level":"info","ts":"2026-07-21T06:44:17.921808Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became follower at term 0"} {"level":"info","ts":"2026-07-21T06:44:17.921882Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 7317567213c7319d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-21T06:44:17.921893Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became follower at term 1"} {"level":"info","ts":"2026-07-21T06:44:17.921941Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d switched to configuration voters=(8293192286784139677)"} {"level":"warn","ts":"2026-07-21T06:44:17.928864Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-21T06:44:17.930683Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-21T06:44:17.932062Z","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-21T06:44:17.935071Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"7317567213c7319d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-21T06:44:17.937337Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"7317567213c7319d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-21T06:44:17.93738Z","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-21T06:44:17.937506Z","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-21T06:44:17.937534Z","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-21T06:44:17.939212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d switched to configuration voters=(8293192286784139677)"} {"level":"info","ts":"2026-07-21T06:44:17.939889Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"d22ed1e1757a8527","local-member-id":"7317567213c7319d","added-peer-id":"7317567213c7319d","added-peer-peer-urls":["https://199.19.213.48:2380"]} {"level":"info","ts":"2026-07-21T06:44:17.942689Z","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-21T06:44:17.944153Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.48:2380"} {"level":"info","ts":"2026-07-21T06:44:17.944228Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.48:2380"} {"level":"info","ts":"2026-07-21T06:44:17.946094Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"7317567213c7319d","initial-advertise-peer-urls":["https://199.19.213.48:2380"],"listen-peer-urls":["https://199.19.213.48:2380"],"advertise-client-urls":["https://199.19.213.48:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.48:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-21T06:44:17.946171Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-21T06:44:18.523684Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d is starting a new election at term 1"} {"level":"info","ts":"2026-07-21T06:44:18.523774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-21T06:44:18.523818Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d received MsgPreVoteResp from 7317567213c7319d at term 1"} {"level":"info","ts":"2026-07-21T06:44:18.52384Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became candidate at term 2"} {"level":"info","ts":"2026-07-21T06:44:18.523851Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d received MsgVoteResp from 7317567213c7319d at term 2"} {"level":"info","ts":"2026-07-21T06:44:18.523869Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"7317567213c7319d became leader at term 2"} {"level":"info","ts":"2026-07-21T06:44:18.523884Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 7317567213c7319d elected leader 7317567213c7319d at term 2"} {"level":"info","ts":"2026-07-21T06:44:18.524752Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:44:18.530008Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"7317567213c7319d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.48:2379]}","request-path":"/0/members/7317567213c7319d/attributes","cluster-id":"d22ed1e1757a8527","publish-timeout":"7s"} {"level":"info","ts":"2026-07-21T06:44:18.530018Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T06:44:18.530266Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"d22ed1e1757a8527","local-member-id":"7317567213c7319d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:44:18.530361Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:44:18.530397Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-21T06:44:18.530422Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-21T06:44:18.533002Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-21T06:44:18.533469Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-21T06:44:18.533535Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-21T06:44:18.543172Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.48:2379"} {"level":"info","ts":"2026-07-21T06:44:41.86989Z","caller":"traceutil/trace.go:171","msg":"trace[1851909307] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"188.041863ms","start":"2026-07-21T06:44:41.681819Z","end":"2026-07-21T06:44:41.869861Z","steps":["trace[1851909307] 'process raft request' (duration: 187.884788ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:42.568067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.791914ms","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-21T06:44:42.56813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"528.844908ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.48\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-07-21T06:44:42.568212Z","caller":"traceutil/trace.go:171","msg":"trace[1093574332] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:388; }","duration":"164.02144ms","start":"2026-07-21T06:44:42.404167Z","end":"2026-07-21T06:44:42.568189Z","steps":["trace[1093574332] 'range keys from in-memory index tree' (duration: 163.64611ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:42.568284Z","caller":"traceutil/trace.go:171","msg":"trace[1416038946] range","detail":"{range_begin:/registry/masterleases/199.19.213.48; range_end:; response_count:1; response_revision:388; }","duration":"529.069164ms","start":"2026-07-21T06:44:42.03917Z","end":"2026-07-21T06:44:42.568239Z","steps":["trace[1416038946] 'range keys from in-memory index tree' (duration: 528.627262ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:42.568345Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:42.039145Z","time spent":"529.186967ms","remote":"127.0.0.1:57722","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.48\" "} {"level":"warn","ts":"2026-07-21T06:44:42.568748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"518.942507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:4374"} {"level":"info","ts":"2026-07-21T06:44:42.568799Z","caller":"traceutil/trace.go:171","msg":"trace[816687860] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:388; }","duration":"519.04409ms","start":"2026-07-21T06:44:42.04974Z","end":"2026-07-21T06:44:42.568784Z","steps":["trace[816687860] 'range keys from in-memory index tree' (duration: 518.798263ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:42.568833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:42.049631Z","time spent":"519.192585ms","remote":"127.0.0.1:57834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":4397,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-21T06:44:48.71051Z","caller":"traceutil/trace.go:171","msg":"trace[1937330533] linearizableReadLoop","detail":"{readStateIndex:449; appliedIndex:448; }","duration":"101.3784ms","start":"2026-07-21T06:44:48.609099Z","end":"2026-07-21T06:44:48.710478Z","steps":["trace[1937330533] 'read index received' (duration: 101.243066ms)","trace[1937330533] 'applied index is now lower than readState.Index' (duration: 133.804µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:44:48.710617Z","caller":"traceutil/trace.go:171","msg":"trace[1640290564] transaction","detail":"{read_only:false; response_revision:433; number_of_response:1; }","duration":"108.026716ms","start":"2026-07-21T06:44:48.602513Z","end":"2026-07-21T06:44:48.710539Z","steps":["trace[1640290564] 'process raft request' (duration: 107.758948ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:48.710718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.580225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-21T06:44:48.710764Z","caller":"traceutil/trace.go:171","msg":"trace[1652476302] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:433; }","duration":"101.666858ms","start":"2026-07-21T06:44:48.609082Z","end":"2026-07-21T06:44:48.710749Z","steps":["trace[1652476302] 'agreement among raft nodes before linearized reading' (duration: 101.526804ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:44:48.825278Z","caller":"traceutil/trace.go:171","msg":"trace[519536606] transaction","detail":"{read_only:false; response_revision:434; number_of_response:1; }","duration":"108.730745ms","start":"2026-07-21T06:44:48.716512Z","end":"2026-07-21T06:44:48.825243Z","steps":["trace[519536606] 'process raft request' (duration: 92.278341ms)","trace[519536606] 'compare' (duration: 16.31935ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:44:57.976576Z","caller":"traceutil/trace.go:171","msg":"trace[1521997435] linearizableReadLoop","detail":"{readStateIndex:506; appliedIndex:505; }","duration":"431.466773ms","start":"2026-07-21T06:44:57.545082Z","end":"2026-07-21T06:44:57.976549Z","steps":["trace[1521997435] 'read index received' (duration: 431.333089ms)","trace[1521997435] 'applied index is now lower than readState.Index' (duration: 132.754µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:44:57.976753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"431.644678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:44:57.976816Z","caller":"traceutil/trace.go:171","msg":"trace[575003152] range","detail":"{range_begin:/registry/secrets/envoy-gateway-system/; range_end:/registry/secrets/envoy-gateway-system0; response_count:0; response_revision:489; }","duration":"431.776341ms","start":"2026-07-21T06:44:57.545024Z","end":"2026-07-21T06:44:57.976801Z","steps":["trace[575003152] 'agreement among raft nodes before linearized reading' (duration: 431.659248ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:57.976854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:57.544998Z","time spent":"431.845584ms","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/envoy-gateway-system/\" range_end:\"/registry/secrets/envoy-gateway-system0\" "} {"level":"info","ts":"2026-07-21T06:44:57.976727Z","caller":"traceutil/trace.go:171","msg":"trace[1394014200] transaction","detail":"{read_only:false; response_revision:489; number_of_response:1; }","duration":"505.942674ms","start":"2026-07-21T06:44:57.47075Z","end":"2026-07-21T06:44:57.976693Z","steps":["trace[1394014200] 'process raft request' (duration: 505.642817ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:57.977725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:57.47071Z","time spent":"506.248093ms","remote":"127.0.0.1:57938","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-21T06:44:57.996527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.121446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:44:57.996637Z","caller":"traceutil/trace.go:171","msg":"trace[395261354] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:489; }","duration":"398.304841ms","start":"2026-07-21T06:44:57.598309Z","end":"2026-07-21T06:44:57.996614Z","steps":["trace[395261354] 'agreement among raft nodes before linearized reading' (duration: 398.107875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:44:57.996682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:44:57.598281Z","time spent":"398.391453ms","remote":"127.0.0.1:57704","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-21T06:45:04.761573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.226555ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:45:04.761768Z","caller":"traceutil/trace.go:171","msg":"trace[1644452488] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"317.569965ms","start":"2026-07-21T06:45:04.44417Z","end":"2026-07-21T06:45:04.76174Z","steps":["trace[1644452488] 'process raft request' (duration: 130.774245ms)","trace[1644452488] 'compare' (duration: 185.998059ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:45:04.761866Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:04.444138Z","time spent":"317.691038ms","remote":"127.0.0.1:58016","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:45:06.496923Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575189065987360569,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:45:07.299125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"446.384057ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:45:07.299264Z","caller":"traceutil/trace.go:171","msg":"trace[2088772235] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"1.309399035s","start":"2026-07-21T06:45:05.989843Z","end":"2026-07-21T06:45:07.299242Z","steps":["trace[2088772235] 'read index received' (duration: 862.716111ms)","trace[2088772235] 'applied index is now lower than readState.Index' (duration: 446.681124ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:45:07.299372Z","caller":"traceutil/trace.go:171","msg":"trace[319711096] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"1.405289043s","start":"2026-07-21T06:45:05.894047Z","end":"2026-07-21T06:45:07.299336Z","steps":["trace[319711096] 'process raft request' (duration: 958.584438ms)","trace[319711096] 'compare' (duration: 445.543914ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:45:07.299471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:05.894023Z","time spent":"1.405403325s","remote":"127.0.0.1:58176","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-21T06:45:07.299587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284632525s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-21T06:45:07.29956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"702.722149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:45:07.299624Z","caller":"traceutil/trace.go:171","msg":"trace[1445545852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:601; }","duration":"1.284718557s","start":"2026-07-21T06:45:06.014892Z","end":"2026-07-21T06:45:07.29961Z","steps":["trace[1445545852] 'agreement among raft nodes before linearized reading' (duration: 1.284477561s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:07.299658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:06.014865Z","time spent":"1.284785799s","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-21T06:45:07.299656Z","caller":"traceutil/trace.go:171","msg":"trace[59905149] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:601; }","duration":"702.856742ms","start":"2026-07-21T06:45:06.596773Z","end":"2026-07-21T06:45:07.29963Z","steps":["trace[59905149] 'agreement among raft nodes before linearized reading' (duration: 702.725429ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:07.299657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"383.45857ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-21T06:45:07.299712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:06.596754Z","time spent":"702.945784ms","remote":"127.0.0.1:57704","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-21T06:45:07.299761Z","caller":"traceutil/trace.go:171","msg":"trace[1387224507] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:601; }","duration":"383.601224ms","start":"2026-07-21T06:45:06.916127Z","end":"2026-07-21T06:45:07.299728Z","steps":["trace[1387224507] 'agreement among raft nodes before linearized reading' (duration: 383.354117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:07.299856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:06.916105Z","time spent":"383.728646ms","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T06:45:07.299903Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.251749978s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-21T06:45:07.299948Z","caller":"traceutil/trace.go:171","msg":"trace[1866784333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:601; }","duration":"1.251790439s","start":"2026-07-21T06:45:06.048142Z","end":"2026-07-21T06:45:07.299933Z","steps":["trace[1866784333] 'agreement among raft nodes before linearized reading' (duration: 1.251672586s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:07.299989Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:06.048108Z","time spent":"1.251870491s","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:45:07.300178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"482.610062ms","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-21T06:45:07.300183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.310346262s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-tmk8v\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-07-21T06:45:07.300235Z","caller":"traceutil/trace.go:171","msg":"trace[200638241] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:601; }","duration":"482.703244ms","start":"2026-07-21T06:45:06.817515Z","end":"2026-07-21T06:45:07.300219Z","steps":["trace[200638241] 'agreement among raft nodes before linearized reading' (duration: 481.906913ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:45:07.300237Z","caller":"traceutil/trace.go:171","msg":"trace[1581511127] range","detail":"{range_begin:/registry/pods/kube-system/cilium-tmk8v; range_end:; response_count:1; response_revision:601; }","duration":"1.310400313s","start":"2026-07-21T06:45:05.989823Z","end":"2026-07-21T06:45:07.300223Z","steps":["trace[1581511127] 'agreement among raft nodes before linearized reading' (duration: 1.30952197s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:07.300267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:06.817494Z","time spent":"482.765545ms","remote":"127.0.0.1:57938","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-21T06:45:07.30027Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:05.989727Z","time spent":"1.310536146s","remote":"127.0.0.1:57848","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-tmk8v\" "} {"level":"warn","ts":"2026-07-21T06:45:23.528288Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575189065987360886,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-21T06:45:23.668425Z","caller":"traceutil/trace.go:171","msg":"trace[19050013] transaction","detail":"{read_only:false; response_revision:767; number_of_response:1; }","duration":"694.517209ms","start":"2026-07-21T06:45:22.973877Z","end":"2026-07-21T06:45:23.668394Z","steps":["trace[19050013] 'process raft request' (duration: 694.330224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.668624Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:22.973839Z","time spent":"694.683473ms","remote":"127.0.0.1:57938","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-21T06:45:23.689192Z","caller":"traceutil/trace.go:171","msg":"trace[2058720223] linearizableReadLoop","detail":"{readStateIndex:796; appliedIndex:794; }","duration":"661.958243ms","start":"2026-07-21T06:45:23.027193Z","end":"2026-07-21T06:45:23.689152Z","steps":["trace[2058720223] 'read index received' (duration: 640.680344ms)","trace[2058720223] 'applied index is now lower than readState.Index' (duration: 21.276379ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:45:23.689399Z","caller":"traceutil/trace.go:171","msg":"trace[78002021] transaction","detail":"{read_only:false; response_revision:768; number_of_response:1; }","duration":"565.157431ms","start":"2026-07-21T06:45:23.124225Z","end":"2026-07-21T06:45:23.689382Z","steps":["trace[78002021] 'process raft request' (duration: 564.75131ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.689606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:23.124188Z","time spent":"565.273163ms","remote":"127.0.0.1:57900","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-21T06:45:23.68971Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.170291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:45:23.689788Z","caller":"traceutil/trace.go:171","msg":"trace[1308375932] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:768; }","duration":"272.323035ms","start":"2026-07-21T06:45:23.417445Z","end":"2026-07-21T06:45:23.689768Z","steps":["trace[1308375932] 'agreement among raft nodes before linearized reading' (duration: 272.13561ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.689808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"298.284128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-21T06:45:23.68986Z","caller":"traceutil/trace.go:171","msg":"trace[119504165] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:768; }","duration":"298.351349ms","start":"2026-07-21T06:45:23.391495Z","end":"2026-07-21T06:45:23.689847Z","steps":["trace[119504165] 'agreement among raft nodes before linearized reading' (duration: 298.217036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.690072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.140061ms","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-21T06:45:23.690191Z","caller":"traceutil/trace.go:171","msg":"trace[1392516688] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:768; }","duration":"299.336486ms","start":"2026-07-21T06:45:23.390828Z","end":"2026-07-21T06:45:23.690164Z","steps":["trace[1392516688] 'agreement among raft nodes before linearized reading' (duration: 298.945295ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.690205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.113901ms","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-21T06:45:23.69027Z","caller":"traceutil/trace.go:171","msg":"trace[286052729] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:768; }","duration":"296.207593ms","start":"2026-07-21T06:45:23.394051Z","end":"2026-07-21T06:45:23.690259Z","steps":["trace[286052729] 'agreement among raft nodes before linearized reading' (duration: 296.026309ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.690288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.396968ms","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-21T06:45:23.690325Z","caller":"traceutil/trace.go:171","msg":"trace[1201931733] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:768; }","duration":"299.48606ms","start":"2026-07-21T06:45:23.390827Z","end":"2026-07-21T06:45:23.690313Z","steps":["trace[1201931733] 'agreement among raft nodes before linearized reading' (duration: 298.592886ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.689638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"662.437936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:45:23.690474Z","caller":"traceutil/trace.go:171","msg":"trace[1770551423] range","detail":"{range_begin:/registry/cronjobs/; range_end:/registry/cronjobs0; response_count:0; response_revision:768; }","duration":"663.287799ms","start":"2026-07-21T06:45:23.027111Z","end":"2026-07-21T06:45:23.690399Z","steps":["trace[1770551423] 'agreement among raft nodes before linearized reading' (duration: 662.457917ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:23.69066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:45:23.027074Z","time spent":"663.463632ms","remote":"127.0.0.1:57912","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/cronjobs/\" range_end:\"/registry/cronjobs0\" count_only:true "} {"level":"info","ts":"2026-07-21T06:45:59.204365Z","caller":"traceutil/trace.go:171","msg":"trace[1509499566] transaction","detail":"{read_only:false; response_revision:983; number_of_response:1; }","duration":"109.200969ms","start":"2026-07-21T06:45:59.094743Z","end":"2026-07-21T06:45:59.203944Z","steps":["trace[1509499566] 'process raft request' (duration: 108.993414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:45:59.471Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.217151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:45:59.471107Z","caller":"traceutil/trace.go:171","msg":"trace[863820711] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:983; }","duration":"122.373946ms","start":"2026-07-21T06:45:59.348712Z","end":"2026-07-21T06:45:59.471086Z","steps":["trace[863820711] 'count revisions from in-memory index tree' (duration: 122.130959ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:00.012846Z","caller":"traceutil/trace.go:171","msg":"trace[735256061] transaction","detail":"{read_only:false; response_revision:984; number_of_response:1; }","duration":"144.248531ms","start":"2026-07-21T06:45:59.868566Z","end":"2026-07-21T06:46:00.012815Z","steps":["trace[735256061] 'process raft request' (duration: 143.786069ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:00.401076Z","caller":"traceutil/trace.go:171","msg":"trace[1795861363] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"384.194235ms","start":"2026-07-21T06:46:00.016849Z","end":"2026-07-21T06:46:00.401043Z","steps":["trace[1795861363] 'process raft request' (duration: 383.99176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:00.401298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:00.016819Z","time spent":"384.35949ms","remote":"127.0.0.1:57938","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-21T06:46:00.40174Z","caller":"traceutil/trace.go:171","msg":"trace[561407072] transaction","detail":"{read_only:false; response_revision:986; number_of_response:1; }","duration":"383.834366ms","start":"2026-07-21T06:46:00.017878Z","end":"2026-07-21T06:46:00.401713Z","steps":["trace[561407072] 'process raft request' (duration: 383.636341ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:00.401938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:00.017858Z","time spent":"383.982269ms","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:46:00.402136Z","caller":"traceutil/trace.go:171","msg":"trace[2104202869] transaction","detail":"{read_only:false; response_revision:988; number_of_response:1; }","duration":"383.903117ms","start":"2026-07-21T06:46:00.018213Z","end":"2026-07-21T06:46:00.402116Z","steps":["trace[2104202869] 'process raft request' (duration: 383.791414ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:00.402157Z","caller":"traceutil/trace.go:171","msg":"trace[1408164554] transaction","detail":"{read_only:false; response_revision:987; number_of_response:1; }","duration":"384.159074ms","start":"2026-07-21T06:46:00.017951Z","end":"2026-07-21T06:46:00.40211Z","steps":["trace[1408164554] 'process raft request' (duration: 383.700622ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:00.40224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:00.018203Z","time spent":"383.98253ms","remote":"127.0.0.1:57938","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-21T06:46:00.402391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:00.017937Z","time spent":"384.350669ms","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:46:24.718324Z","caller":"traceutil/trace.go:171","msg":"trace[2052179101] transaction","detail":"{read_only:false; response_revision:1066; number_of_response:1; }","duration":"106.561464ms","start":"2026-07-21T06:46:24.611734Z","end":"2026-07-21T06:46:24.718296Z","steps":["trace[2052179101] 'process raft request' (duration: 106.248796ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:26.669567Z","caller":"traceutil/trace.go:171","msg":"trace[728052628] transaction","detail":"{read_only:false; response_revision:1070; number_of_response:1; }","duration":"512.500988ms","start":"2026-07-21T06:46:26.157005Z","end":"2026-07-21T06:46:26.669506Z","steps":["trace[728052628] 'process raft request' (duration: 512.128999ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:26.66982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:26.156987Z","time spent":"512.679963ms","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:46:27.490221Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.8357ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:46:27.490377Z","caller":"traceutil/trace.go:171","msg":"trace[384728927] linearizableReadLoop","detail":"{readStateIndex:1113; appliedIndex:1112; }","duration":"766.17559ms","start":"2026-07-21T06:46:26.724175Z","end":"2026-07-21T06:46:27.490351Z","steps":["trace[384728927] 'read index received' (duration: 357.076322ms)","trace[384728927] 'applied index is now lower than readState.Index' (duration: 409.097378ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:46:27.490476Z","caller":"traceutil/trace.go:171","msg":"trace[2032677796] transaction","detail":"{read_only:false; response_revision:1071; number_of_response:1; }","duration":"813.564314ms","start":"2026-07-21T06:46:26.676873Z","end":"2026-07-21T06:46:27.490437Z","steps":["trace[2032677796] 'process raft request' (duration: 404.364494ms)","trace[2032677796] 'compare' (duration: 408.362439ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:46:27.490667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"766.490058ms","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-21T06:46:27.490719Z","caller":"traceutil/trace.go:171","msg":"trace[748592212] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1071; }","duration":"766.59441ms","start":"2026-07-21T06:46:26.724109Z","end":"2026-07-21T06:46:27.490704Z","steps":["trace[748592212] 'agreement among raft nodes before linearized reading' (duration: 766.350044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:27.490766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:26.724081Z","time spent":"766.672822ms","remote":"127.0.0.1:57938","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-21T06:46:27.490761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:26.67684Z","time spent":"813.722838ms","remote":"127.0.0.1:57938","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-21T06:46:27.502281Z","caller":"traceutil/trace.go:171","msg":"trace[889165635] transaction","detail":"{read_only:false; response_revision:1072; number_of_response:1; }","duration":"768.128319ms","start":"2026-07-21T06:46:26.734132Z","end":"2026-07-21T06:46:27.502261Z","steps":["trace[889165635] 'process raft request' (duration: 768.023916ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:27.502644Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:26.734102Z","time spent":"768.435368ms","remote":"127.0.0.1:57938","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-21T06:46:27.564435Z","caller":"traceutil/trace.go:171","msg":"trace[1235403469] transaction","detail":"{read_only:false; response_revision:1073; number_of_response:1; }","duration":"216.519525ms","start":"2026-07-21T06:46:27.347856Z","end":"2026-07-21T06:46:27.564376Z","steps":["trace[1235403469] 'process raft request' (duration: 216.203647ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:27.564589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.789716ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-07-21T06:46:27.56467Z","caller":"traceutil/trace.go:171","msg":"trace[2118076706] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1074; }","duration":"828.922219ms","start":"2026-07-21T06:46:26.735728Z","end":"2026-07-21T06:46:27.564651Z","steps":["trace[2118076706] 'agreement among raft nodes before linearized reading' (duration: 828.680482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:27.564705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:26.735709Z","time spent":"828.98862ms","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T06:46:27.564805Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"581.900901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:46:27.564845Z","caller":"traceutil/trace.go:171","msg":"trace[1054612158] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:1074; }","duration":"581.998833ms","start":"2026-07-21T06:46:26.982834Z","end":"2026-07-21T06:46:27.564833Z","steps":["trace[1054612158] 'agreement among raft nodes before linearized reading' (duration: 581.87446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:27.564872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:26.982809Z","time spent":"582.053974ms","remote":"127.0.0.1:60592","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":30,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:46:27.564983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"828.060797ms","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-21T06:46:27.565068Z","caller":"traceutil/trace.go:171","msg":"trace[430641856] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1074; }","duration":"828.153769ms","start":"2026-07-21T06:46:26.736897Z","end":"2026-07-21T06:46:27.565051Z","steps":["trace[430641856] 'agreement among raft nodes before linearized reading' (duration: 827.949074ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:27.565102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:26.736807Z","time spent":"828.285563ms","remote":"127.0.0.1:57938","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-21T06:46:29.615716Z","caller":"traceutil/trace.go:171","msg":"trace[118761746] transaction","detail":"{read_only:false; response_revision:1078; number_of_response:1; }","duration":"115.106522ms","start":"2026-07-21T06:46:29.500573Z","end":"2026-07-21T06:46:29.615679Z","steps":["trace[118761746] 'process raft request' (duration: 114.826415ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:31.011591Z","caller":"traceutil/trace.go:171","msg":"trace[72046634] linearizableReadLoop","detail":"{readStateIndex:1126; appliedIndex:1125; }","duration":"118.400307ms","start":"2026-07-21T06:46:30.893151Z","end":"2026-07-21T06:46:31.011551Z","steps":["trace[72046634] 'read index received' (duration: 118.170942ms)","trace[72046634] 'applied index is now lower than readState.Index' (duration: 227.835µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:46:31.011656Z","caller":"traceutil/trace.go:171","msg":"trace[1885986963] transaction","detail":"{read_only:false; response_revision:1083; number_of_response:1; }","duration":"249.113191ms","start":"2026-07-21T06:46:30.762508Z","end":"2026-07-21T06:46:31.011622Z","steps":["trace[1885986963] 'process raft request' (duration: 248.822684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:31.01215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.692666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/\" range_end:\"/registry/priorityclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:46:31.012261Z","caller":"traceutil/trace.go:171","msg":"trace[53823698] range","detail":"{range_begin:/registry/priorityclasses/; range_end:/registry/priorityclasses0; response_count:0; response_revision:1083; }","duration":"119.151777ms","start":"2026-07-21T06:46:30.893089Z","end":"2026-07-21T06:46:31.012241Z","steps":["trace[53823698] 'agreement among raft nodes before linearized reading' (duration: 118.667295ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:41.893116Z","caller":"traceutil/trace.go:171","msg":"trace[1864630135] linearizableReadLoop","detail":"{readStateIndex:1163; appliedIndex:1162; }","duration":"118.329046ms","start":"2026-07-21T06:46:41.774757Z","end":"2026-07-21T06:46:41.893086Z","steps":["trace[1864630135] 'read index received' (duration: 118.065879ms)","trace[1864630135] 'applied index is now lower than readState.Index' (duration: 262.047µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:46:41.89329Z","caller":"traceutil/trace.go:171","msg":"trace[280035910] transaction","detail":"{read_only:false; response_revision:1118; number_of_response:1; }","duration":"186.297719ms","start":"2026-07-21T06:46:41.706937Z","end":"2026-07-21T06:46:41.893234Z","steps":["trace[280035910] 'process raft request' (duration: 185.964941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:41.893463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.667135ms","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-21T06:46:41.893514Z","caller":"traceutil/trace.go:171","msg":"trace[152302088] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1118; }","duration":"118.794888ms","start":"2026-07-21T06:46:41.774706Z","end":"2026-07-21T06:46:41.893501Z","steps":["trace[152302088] 'agreement among raft nodes before linearized reading' (duration: 118.52564ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:41.89379Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.470201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-21T06:46:41.893775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.642284ms","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-21T06:46:41.893825Z","caller":"traceutil/trace.go:171","msg":"trace[1629638851] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1118; }","duration":"113.503841ms","start":"2026-07-21T06:46:41.780308Z","end":"2026-07-21T06:46:41.893812Z","steps":["trace[1629638851] 'agreement among raft nodes before linearized reading' (duration: 113.406849ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:46:41.893864Z","caller":"traceutil/trace.go:171","msg":"trace[458610550] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1118; }","duration":"118.789997ms","start":"2026-07-21T06:46:41.775054Z","end":"2026-07-21T06:46:41.893844Z","steps":["trace[458610550] 'agreement among raft nodes before linearized reading' (duration: 118.548451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:48.993731Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.296568ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:46:48.993858Z","caller":"traceutil/trace.go:171","msg":"trace[404887654] linearizableReadLoop","detail":"{readStateIndex:1242; appliedIndex:1241; }","duration":"394.650606ms","start":"2026-07-21T06:46:48.599185Z","end":"2026-07-21T06:46:48.993836Z","steps":["trace[404887654] 'read index received' (duration: 171.103751ms)","trace[404887654] 'applied index is now lower than readState.Index' (duration: 223.545475ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:46:48.993886Z","caller":"traceutil/trace.go:171","msg":"trace[449866853] transaction","detail":"{read_only:false; response_revision:1193; number_of_response:1; }","duration":"934.147476ms","start":"2026-07-21T06:46:48.059708Z","end":"2026-07-21T06:46:48.993856Z","steps":["trace[449866853] 'process raft request' (duration: 710.596111ms)","trace[449866853] 'compare' (duration: 223.143504ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:46:48.993928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.750848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:46:48.993958Z","caller":"traceutil/trace.go:171","msg":"trace[1361910663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1193; }","duration":"394.82099ms","start":"2026-07-21T06:46:48.599127Z","end":"2026-07-21T06:46:48.993948Z","steps":["trace[1361910663] 'agreement among raft nodes before linearized reading' (duration: 394.759018ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:46:48.994031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:48.599106Z","time spent":"394.917852ms","remote":"127.0.0.1:57704","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-21T06:46:48.994057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:46:48.059683Z","time spent":"934.27072ms","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:47:04.120745Z","caller":"traceutil/trace.go:171","msg":"trace[1755254138] linearizableReadLoop","detail":"{readStateIndex:1295; appliedIndex:1294; }","duration":"104.299896ms","start":"2026-07-21T06:47:04.016416Z","end":"2026-07-21T06:47:04.120716Z","steps":["trace[1755254138] 'read index received' (duration: 104.072169ms)","trace[1755254138] 'applied index is now lower than readState.Index' (duration: 226.607µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:47:04.120862Z","caller":"traceutil/trace.go:171","msg":"trace[868216082] transaction","detail":"{read_only:false; response_revision:1243; number_of_response:1; }","duration":"106.963194ms","start":"2026-07-21T06:47:04.013851Z","end":"2026-07-21T06:47:04.120814Z","steps":["trace[868216082] 'process raft request' (duration: 106.712268ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:04.121233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.487199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-21T06:47:04.1213Z","caller":"traceutil/trace.go:171","msg":"trace[1766162319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1243; }","duration":"102.600123ms","start":"2026-07-21T06:47:04.018683Z","end":"2026-07-21T06:47:04.121283Z","steps":["trace[1766162319] 'agreement among raft nodes before linearized reading' (duration: 102.359266ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:04.121477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.85931ms","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-21T06:47:04.12155Z","caller":"traceutil/trace.go:171","msg":"trace[608510824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1243; }","duration":"105.179568ms","start":"2026-07-21T06:47:04.016356Z","end":"2026-07-21T06:47:04.121536Z","steps":["trace[608510824] 'agreement among raft nodes before linearized reading' (duration: 104.523361ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:04.121351Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.380803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-21T06:47:04.121631Z","caller":"traceutil/trace.go:171","msg":"trace[202343073] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1243; }","duration":"103.68548ms","start":"2026-07-21T06:47:04.017936Z","end":"2026-07-21T06:47:04.121622Z","steps":["trace[202343073] 'agreement among raft nodes before linearized reading' (duration: 103.080994ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:04.757186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.003599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:47:04.757392Z","caller":"traceutil/trace.go:171","msg":"trace[190104776] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1295; }","duration":"158.275311ms","start":"2026-07-21T06:47:04.5991Z","end":"2026-07-21T06:47:04.757375Z","steps":["trace[190104776] 'read index received' (duration: 82.972µs)","trace[190104776] 'applied index is now lower than readState.Index' (duration: 158.191429ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:47:04.757526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.426364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:47:04.757613Z","caller":"traceutil/trace.go:171","msg":"trace[1184109852] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1246; }","duration":"158.549278ms","start":"2026-07-21T06:47:04.599054Z","end":"2026-07-21T06:47:04.757604Z","steps":["trace[1184109852] 'agreement among raft nodes before linearized reading' (duration: 158.366753ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:04.757707Z","caller":"traceutil/trace.go:171","msg":"trace[1232014085] transaction","detail":"{read_only:false; response_revision:1245; number_of_response:1; }","duration":"630.314722ms","start":"2026-07-21T06:47:04.127351Z","end":"2026-07-21T06:47:04.757666Z","steps":["trace[1232014085] 'process raft request' (duration: 629.936552ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:04.757741Z","caller":"traceutil/trace.go:171","msg":"trace[1284397141] transaction","detail":"{read_only:false; response_revision:1244; number_of_response:1; }","duration":"630.542467ms","start":"2026-07-21T06:47:04.127175Z","end":"2026-07-21T06:47:04.757718Z","steps":["trace[1284397141] 'process raft request' (duration: 212.921552ms)","trace[1284397141] 'compare' (duration: 416.67031ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:47:04.757746Z","caller":"traceutil/trace.go:171","msg":"trace[293058240] transaction","detail":"{read_only:false; response_revision:1246; number_of_response:1; }","duration":"630.100226ms","start":"2026-07-21T06:47:04.127608Z","end":"2026-07-21T06:47:04.757709Z","steps":["trace[293058240] 'process raft request' (duration: 629.736196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:04.757827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:04.127311Z","time spent":"630.449885ms","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:47:04.757834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:04.127152Z","time spent":"630.6355ms","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:47:04.757941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:04.127567Z","time spent":"630.26199ms","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:47:05.571749Z","caller":"traceutil/trace.go:171","msg":"trace[1574524383] transaction","detail":"{read_only:false; response_revision:1248; number_of_response:1; }","duration":"288.84191ms","start":"2026-07-21T06:47:05.282872Z","end":"2026-07-21T06:47:05.571714Z","steps":["trace[1574524383] 'process raft request' (duration: 288.650765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:06.109188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"247.626053ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:47:06.10943Z","caller":"traceutil/trace.go:171","msg":"trace[703750206] transaction","detail":"{read_only:false; response_revision:1250; number_of_response:1; }","duration":"309.772747ms","start":"2026-07-21T06:47:05.799636Z","end":"2026-07-21T06:47:06.109409Z","steps":["trace[703750206] 'process raft request' (duration: 309.683875ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:06.109525Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:05.799609Z","time spent":"309.865329ms","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:47:06.109821Z","caller":"traceutil/trace.go:171","msg":"trace[1206745862] transaction","detail":"{read_only:false; response_revision:1249; number_of_response:1; }","duration":"648.087787ms","start":"2026-07-21T06:47:05.461712Z","end":"2026-07-21T06:47:06.1098Z","steps":["trace[1206745862] 'process raft request' (duration: 399.731295ms)","trace[1206745862] 'compare' (duration: 247.069939ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:47:06.109947Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:05.46167Z","time spent":"648.241071ms","remote":"127.0.0.1:57764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":751,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-21T06:47:06.109986Z","caller":"traceutil/trace.go:171","msg":"trace[1578499261] linearizableReadLoop","detail":"{readStateIndex:1301; appliedIndex:1300; }","duration":"510.052306ms","start":"2026-07-21T06:47:05.599894Z","end":"2026-07-21T06:47:06.109947Z","steps":["trace[1578499261] 'read index received' (duration: 261.562671ms)","trace[1578499261] 'applied index is now lower than readState.Index' (duration: 248.487125ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:47:06.110183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.272111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:47:06.110453Z","caller":"traceutil/trace.go:171","msg":"trace[969227344] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1250; }","duration":"510.59171ms","start":"2026-07-21T06:47:05.599844Z","end":"2026-07-21T06:47:06.110436Z","steps":["trace[969227344] 'agreement among raft nodes before linearized reading' (duration: 510.24045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:06.110508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:05.599819Z","time spent":"510.672862ms","remote":"127.0.0.1:57704","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-21T06:47:06.11059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.641226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:5330"} {"level":"info","ts":"2026-07-21T06:47:06.110629Z","caller":"traceutil/trace.go:171","msg":"trace[1040168244] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:1250; }","duration":"373.730258ms","start":"2026-07-21T06:47:05.736887Z","end":"2026-07-21T06:47:06.110617Z","steps":["trace[1040168244] 'agreement among raft nodes before linearized reading' (duration: 373.590245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:06.110654Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:05.736861Z","time spent":"373.78658ms","remote":"127.0.0.1:57834","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":5353,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-21T06:47:06.297988Z","caller":"traceutil/trace.go:171","msg":"trace[232897553] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"115.385371ms","start":"2026-07-21T06:47:06.18256Z","end":"2026-07-21T06:47:06.297945Z","steps":["trace[232897553] 'process raft request' (duration: 115.154064ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:06.298121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.803805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:47:06.29819Z","caller":"traceutil/trace.go:171","msg":"trace[280892833] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1253; }","duration":"114.937398ms","start":"2026-07-21T06:47:06.18324Z","end":"2026-07-21T06:47:06.298177Z","steps":["trace[280892833] 'agreement among raft nodes before linearized reading' (duration: 114.778424ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:06.297969Z","caller":"traceutil/trace.go:171","msg":"trace[883986371] linearizableReadLoop","detail":"{readStateIndex:1305; appliedIndex:1304; }","duration":"114.620831ms","start":"2026-07-21T06:47:06.183287Z","end":"2026-07-21T06:47:06.297908Z","steps":["trace[883986371] 'read index received' (duration: 114.373504ms)","trace[883986371] 'applied index is now lower than readState.Index' (duration: 245.737µs)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:47:07.017106Z","caller":"traceutil/trace.go:171","msg":"trace[1287707539] transaction","detail":"{read_only:false; response_revision:1256; number_of_response:1; }","duration":"247.911591ms","start":"2026-07-21T06:47:06.769163Z","end":"2026-07-21T06:47:07.017075Z","steps":["trace[1287707539] 'process raft request' (duration: 247.850119ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:07.017189Z","caller":"traceutil/trace.go:171","msg":"trace[1270903452] transaction","detail":"{read_only:false; response_revision:1255; number_of_response:1; }","duration":"249.221364ms","start":"2026-07-21T06:47:06.767918Z","end":"2026-07-21T06:47:07.01714Z","steps":["trace[1270903452] 'process raft request' (duration: 189.706697ms)","trace[1270903452] 'compare' (duration: 59.244321ms)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:47:07.450842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.968743ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-21T06:47:07.461034Z","caller":"traceutil/trace.go:171","msg":"trace[871441923] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"161.435972ms","start":"2026-07-21T06:47:07.299536Z","end":"2026-07-21T06:47:07.460972Z","steps":["trace[871441923] 'process raft request' (duration: 161.277688ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:22.303752Z","caller":"traceutil/trace.go:171","msg":"trace[459158511] transaction","detail":"{read_only:false; response_revision:1367; number_of_response:1; }","duration":"241.399644ms","start":"2026-07-21T06:47:22.062323Z","end":"2026-07-21T06:47:22.303723Z","steps":["trace[459158511] 'process raft request' (duration: 241.205319ms)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:22.304006Z","caller":"traceutil/trace.go:171","msg":"trace[1137886683] linearizableReadLoop","detail":"{readStateIndex:1423; appliedIndex:1423; }","duration":"160.59324ms","start":"2026-07-21T06:47:22.143374Z","end":"2026-07-21T06:47:22.303967Z","steps":["trace[1137886683] 'read index received' (duration: 160.572689ms)","trace[1137886683] 'applied index is now lower than readState.Index' (duration: 16.981µs)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:47:22.304275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.817186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:47:22.304446Z","caller":"traceutil/trace.go:171","msg":"trace[1515694541] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:1367; }","duration":"161.104094ms","start":"2026-07-21T06:47:22.143312Z","end":"2026-07-21T06:47:22.304416Z","steps":["trace[1515694541] 'agreement among raft nodes before linearized reading' (duration: 160.747524ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:22.316153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.122901ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:530"} {"level":"info","ts":"2026-07-21T06:47:22.316234Z","caller":"traceutil/trace.go:171","msg":"trace[1926486394] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:1368; }","duration":"164.273456ms","start":"2026-07-21T06:47:22.15194Z","end":"2026-07-21T06:47:22.316214Z","steps":["trace[1926486394] 'agreement among raft nodes before linearized reading' (duration: 163.984037ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:23.10292Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575189065987362268,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:47:23.716679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.231133277s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-21T06:47:23.716966Z","caller":"traceutil/trace.go:171","msg":"trace[2027497125] linearizableReadLoop","detail":"{readStateIndex:1427; appliedIndex:1424; }","duration":"1.115151821s","start":"2026-07-21T06:47:22.601788Z","end":"2026-07-21T06:47:23.71694Z","steps":["trace[2027497125] 'read index received' (duration: 553.754648ms)","trace[2027497125] 'applied index is now lower than readState.Index' (duration: 561.396133ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:47:23.717102Z","caller":"traceutil/trace.go:171","msg":"trace[329705281] transaction","detail":"{read_only:false; response_revision:1369; number_of_response:1; }","duration":"1.395561665s","start":"2026-07-21T06:47:22.321501Z","end":"2026-07-21T06:47:23.717063Z","steps":["trace[329705281] 'process raft request' (duration: 163.906095ms)","trace[329705281] 'compare' (duration: 1.230374087s)"],"step_count":2} {"level":"warn","ts":"2026-07-21T06:47:23.71727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:22.321486Z","time spent":"1.395689069s","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":493,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:47:23.717335Z","caller":"traceutil/trace.go:171","msg":"trace[1221134547] transaction","detail":"{read_only:false; response_revision:1371; number_of_response:1; }","duration":"1.394527439s","start":"2026-07-21T06:47:22.32277Z","end":"2026-07-21T06:47:23.717298Z","steps":["trace[1221134547] 'process raft request' (duration: 1.394129319s)"],"step_count":1} {"level":"info","ts":"2026-07-21T06:47:23.717411Z","caller":"traceutil/trace.go:171","msg":"trace[1228690475] transaction","detail":"{read_only:false; response_revision:1370; number_of_response:1; }","duration":"1.394760314s","start":"2026-07-21T06:47:22.322639Z","end":"2026-07-21T06:47:23.717399Z","steps":["trace[1228690475] 'process raft request' (duration: 1.394185789s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:23.717445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.115657023s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-21T06:47:23.717498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:22.322624Z","time spent":"1.394808075s","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:47:23.717525Z","caller":"traceutil/trace.go:171","msg":"trace[1486432229] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1371; }","duration":"1.115780266s","start":"2026-07-21T06:47:22.601732Z","end":"2026-07-21T06:47:23.717512Z","steps":["trace[1486432229] 'agreement among raft nodes before linearized reading' (duration: 1.115653913s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:23.717561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:22.601711Z","time spent":"1.115842677s","remote":"127.0.0.1:57704","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-21T06:47:23.717561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:22.322742Z","time spent":"1.394674492s","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:47:23.718337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.387202ms","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-21T06:47:23.718401Z","caller":"traceutil/trace.go:171","msg":"trace[2084961824] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1371; }","duration":"534.472703ms","start":"2026-07-21T06:47:23.183912Z","end":"2026-07-21T06:47:23.718384Z","steps":["trace[2084961824] 'agreement among raft nodes before linearized reading' (duration: 534.270227ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:23.718442Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:23.183903Z","time spent":"534.530574ms","remote":"127.0.0.1:57938","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-21T06:47:23.718433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"535.313745ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-21T06:47:23.718508Z","caller":"traceutil/trace.go:171","msg":"trace[2080930430] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1371; }","duration":"535.436498ms","start":"2026-07-21T06:47:23.18305Z","end":"2026-07-21T06:47:23.718487Z","steps":["trace[2080930430] 'agreement among raft nodes before linearized reading' (duration: 535.188251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:23.718561Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:23.183022Z","time spent":"535.52472ms","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-21T06:47:23.719331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"792.880393ms","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-21T06:47:23.719383Z","caller":"traceutil/trace.go:171","msg":"trace[869029002] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1371; }","duration":"793.018586ms","start":"2026-07-21T06:47:22.92635Z","end":"2026-07-21T06:47:23.719369Z","steps":["trace[869029002] 'agreement among raft nodes before linearized reading' (duration: 791.726073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:47:23.71942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:47:22.926304Z","time spent":"793.106049ms","remote":"127.0.0.1:57938","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-21T06:47:23.959828Z","caller":"traceutil/trace.go:171","msg":"trace[480411149] transaction","detail":"{read_only:false; response_revision:1376; number_of_response:1; }","duration":"105.333402ms","start":"2026-07-21T06:47:23.854463Z","end":"2026-07-21T06:47:23.959796Z","steps":["trace[480411149] 'process raft request' (duration: 67.44781ms)","trace[480411149] 'compare' (duration: 37.744938ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:49:54.538043Z","caller":"traceutil/trace.go:171","msg":"trace[622141065] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"182.478695ms","start":"2026-07-21T06:49:54.355539Z","end":"2026-07-21T06:49:54.538018Z","steps":["trace[622141065] 'process raft request' (duration: 182.107065ms)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:16.739558Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575189065987366249,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:52:18.559428Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575189065987366249,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:52:18.601787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.003221534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-21T06:52:18.601868Z","caller":"traceutil/trace.go:171","msg":"trace[1478591984] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.003392528s","start":"2026-07-21T06:52:16.598454Z","end":"2026-07-21T06:52:18.601847Z","steps":["trace[1478591984] 'agreement among raft nodes before linearized reading' (duration: 2.003291876s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:18.601911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:16.598429Z","time spent":"2.00347336s","remote":"127.0.0.1:57704","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-21T06:52:19.062081Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575189065987366249,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:52:19.923368Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3575189065987366249,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-21T06:52:19.956364Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"3.733254336s","expected-duration":"1s"} {"level":"info","ts":"2026-07-21T06:52:19.95691Z","caller":"traceutil/trace.go:171","msg":"trace[1030246285] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"3.733868571s","start":"2026-07-21T06:52:16.22301Z","end":"2026-07-21T06:52:19.956879Z","steps":["trace[1030246285] 'process raft request' (duration: 3.733693606s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.95712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:16.222995Z","time spent":"3.734011085s","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":458,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-21T06:52:19.960042Z","caller":"traceutil/trace.go:171","msg":"trace[124964090] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"3.685642635s","start":"2026-07-21T06:52:16.27437Z","end":"2026-07-21T06:52:19.960013Z","steps":["trace[124964090] 'process raft request' (duration: 3.683546079s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.960193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:16.274342Z","time spent":"3.685774208s","remote":"127.0.0.1:57938","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-21T06:52:19.962964Z","caller":"traceutil/trace.go:171","msg":"trace[743790765] linearizableReadLoop","detail":"{readStateIndex:2868; appliedIndex:2864; }","duration":"3.723880744s","start":"2026-07-21T06:52:16.23905Z","end":"2026-07-21T06:52:19.962931Z","steps":["trace[743790765] 'read index received' (duration: 3.717592497s)","trace[743790765] 'applied index is now lower than readState.Index' (duration: 6.287077ms)"],"step_count":2} {"level":"info","ts":"2026-07-21T06:52:19.963115Z","caller":"traceutil/trace.go:171","msg":"trace[91924439] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"1.402750081s","start":"2026-07-21T06:52:18.560343Z","end":"2026-07-21T06:52:19.963093Z","steps":["trace[91924439] 'process raft request' (duration: 1.402402082s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.963206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:18.560332Z","time spent":"1.402826723s","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-21T06:52:19.963227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.724151141s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/default/kubernetes\" ","response":"range_response_count:1 size:704"} {"level":"info","ts":"2026-07-21T06:52:19.963278Z","caller":"traceutil/trace.go:171","msg":"trace[1488851294] range","detail":"{range_begin:/registry/services/specs/default/kubernetes; range_end:; response_count:1; response_revision:2751; }","duration":"3.724289084s","start":"2026-07-21T06:52:16.238973Z","end":"2026-07-21T06:52:19.963262Z","steps":["trace[1488851294] 'agreement among raft nodes before linearized reading' (duration: 3.724150061s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.963319Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:16.238918Z","time spent":"3.724391128s","remote":"127.0.0.1:57858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":727,"request content":"key:\"/registry/services/specs/default/kubernetes\" "} {"level":"warn","ts":"2026-07-21T06:52:19.963613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.72068006s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-node-lease/\" range_end:\"/registry/serviceaccounts/kube-node-lease0\" limit:500 ","response":"range_response_count:1 size:187"} {"level":"info","ts":"2026-07-21T06:52:19.963728Z","caller":"traceutil/trace.go:171","msg":"trace[1934234646] range","detail":"{range_begin:/registry/serviceaccounts/kube-node-lease/; range_end:/registry/serviceaccounts/kube-node-lease0; response_count:1; response_revision:2751; }","duration":"3.720759321s","start":"2026-07-21T06:52:16.242897Z","end":"2026-07-21T06:52:19.963657Z","steps":["trace[1934234646] 'agreement among raft nodes before linearized reading' (duration: 3.720652129s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.963829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:16.242887Z","time spent":"3.720925885s","remote":"127.0.0.1:57874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":210,"request content":"key:\"/registry/serviceaccounts/kube-node-lease/\" range_end:\"/registry/serviceaccounts/kube-node-lease0\" limit:500 "} {"level":"warn","ts":"2026-07-21T06:52:19.963904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403451999s","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-21T06:52:19.963949Z","caller":"traceutil/trace.go:171","msg":"trace[1201054467] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2751; }","duration":"1.40350866s","start":"2026-07-21T06:52:18.56043Z","end":"2026-07-21T06:52:19.963939Z","steps":["trace[1201054467] 'agreement among raft nodes before linearized reading' (duration: 1.403354467s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.963977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:18.560425Z","time spent":"1.403544311s","remote":"127.0.0.1:57938","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-21T06:52:19.964179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403744428s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-21T06:52:19.964246Z","caller":"traceutil/trace.go:171","msg":"trace[227856162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2751; }","duration":"1.40382315s","start":"2026-07-21T06:52:18.560407Z","end":"2026-07-21T06:52:19.96423Z","steps":["trace[227856162] 'agreement among raft nodes before linearized reading' (duration: 1.403609724s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.964331Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:18.560403Z","time spent":"1.403886441s","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-21T06:52:19.964456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403979973s","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-21T06:52:19.964491Z","caller":"traceutil/trace.go:171","msg":"trace[1794377792] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2751; }","duration":"1.404027905s","start":"2026-07-21T06:52:18.560454Z","end":"2026-07-21T06:52:19.964482Z","steps":["trace[1794377792] 'agreement among raft nodes before linearized reading' (duration: 1.403941923s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.964517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:18.560448Z","time spent":"1.404062806s","remote":"127.0.0.1:57938","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-21T06:52:19.964516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.403987924s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-21T06:52:19.964592Z","caller":"traceutil/trace.go:171","msg":"trace[386964774] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2751; }","duration":"1.404094816s","start":"2026-07-21T06:52:18.560478Z","end":"2026-07-21T06:52:19.964573Z","steps":["trace[386964774] 'agreement among raft nodes before linearized reading' (duration: 1.40385449s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.964616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.404207589s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-07-21T06:52:19.96463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:18.560474Z","time spent":"1.404148858s","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":555,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"info","ts":"2026-07-21T06:52:19.964664Z","caller":"traceutil/trace.go:171","msg":"trace[132880860] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2751; }","duration":"1.404272021s","start":"2026-07-21T06:52:18.560377Z","end":"2026-07-21T06:52:19.964649Z","steps":["trace[132880860] 'agreement among raft nodes before linearized reading' (duration: 1.404143278s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.964768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.350900109s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-21T06:52:19.964788Z","caller":"traceutil/trace.go:171","msg":"trace[2103800496] range","detail":"{range_begin:/registry/csinodes/; range_end:/registry/csinodes0; response_count:0; response_revision:2751; }","duration":"1.35093316s","start":"2026-07-21T06:52:18.613848Z","end":"2026-07-21T06:52:19.964781Z","steps":["trace[2103800496] 'agreement among raft nodes before linearized reading' (duration: 1.350886308s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.964778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:18.560373Z","time spent":"1.404323284s","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-21T06:52:19.964809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:18.613841Z","time spent":"1.35096345s","remote":"127.0.0.1:58064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":30,"request content":"key:\"/registry/csinodes/\" range_end:\"/registry/csinodes0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:52:19.964953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.362715724s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:52:19.964972Z","caller":"traceutil/trace.go:171","msg":"trace[506740120] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2751; }","duration":"1.362759655s","start":"2026-07-21T06:52:18.602207Z","end":"2026-07-21T06:52:19.964966Z","steps":["trace[506740120] 'agreement among raft nodes before linearized reading' (duration: 1.362726174s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.964988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:18.602192Z","time spent":"1.362792336s","remote":"127.0.0.1:57710","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-21T06:52:19.964987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.404723125s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-21T06:52:19.965032Z","caller":"traceutil/trace.go:171","msg":"trace[361776974] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2751; }","duration":"1.404779496s","start":"2026-07-21T06:52:18.560238Z","end":"2026-07-21T06:52:19.965017Z","steps":["trace[361776974] 'agreement among raft nodes before linearized reading' (duration: 1.404649652s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.965073Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:18.560232Z","time spent":"1.404829287s","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-21T06:52:19.965276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.659998641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/cluster-info\" ","response":"range_response_count:1 size:2177"} {"level":"info","ts":"2026-07-21T06:52:19.965298Z","caller":"traceutil/trace.go:171","msg":"trace[1562621069] range","detail":"{range_begin:/registry/configmaps/kube-public/cluster-info; range_end:; response_count:1; response_revision:2751; }","duration":"3.660019601s","start":"2026-07-21T06:52:16.305271Z","end":"2026-07-21T06:52:19.96529Z","steps":["trace[1562621069] 'agreement among raft nodes before linearized reading' (duration: 3.65996695s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.965317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:16.305196Z","time spent":"3.660117303s","remote":"127.0.0.1:57780","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":2200,"request content":"key:\"/registry/configmaps/kube-public/cluster-info\" "} {"level":"warn","ts":"2026-07-21T06:52:19.965474Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.405250699s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:52:19.965498Z","caller":"traceutil/trace.go:171","msg":"trace[1115581199] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:2751; }","duration":"1.405297649s","start":"2026-07-21T06:52:18.56019Z","end":"2026-07-21T06:52:19.965487Z","steps":["trace[1115581199] 'agreement among raft nodes before linearized reading' (duration: 1.405261969s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.965515Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:18.560174Z","time spent":"1.405335351s","remote":"127.0.0.1:57878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-07-21T06:52:19.965821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.659136147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-public/\" range_end:\"/registry/secrets/kube-public0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-21T06:52:19.965855Z","caller":"traceutil/trace.go:171","msg":"trace[1074188139] range","detail":"{range_begin:/registry/secrets/kube-public/; range_end:/registry/secrets/kube-public0; response_count:0; response_revision:2751; }","duration":"3.659191769s","start":"2026-07-21T06:52:16.306654Z","end":"2026-07-21T06:52:19.965846Z","steps":["trace[1074188139] 'agreement among raft nodes before linearized reading' (duration: 3.659128258s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.965878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:16.306646Z","time spent":"3.65922608s","remote":"127.0.0.1:57778","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":0,"response size":28,"request content":"key:\"/registry/secrets/kube-public/\" range_end:\"/registry/secrets/kube-public0\" limit:500 "} {"level":"warn","ts":"2026-07-21T06:52:19.965667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"3.272212177s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:526"} {"level":"info","ts":"2026-07-21T06:52:19.966007Z","caller":"traceutil/trace.go:171","msg":"trace[1330536375] range","detail":"{range_begin:/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:2751; }","duration":"3.272588506s","start":"2026-07-21T06:52:16.693408Z","end":"2026-07-21T06:52:19.965996Z","steps":["trace[1330536375] 'agreement among raft nodes before linearized reading' (duration: 3.272203637s)"],"step_count":1} {"level":"warn","ts":"2026-07-21T06:52:19.966035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-21T06:52:16.693372Z","time spent":"3.272653337s","remote":"127.0.0.1:57938","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":549,"request content":"key:\"/registry/leases/kube-system/external-attacher-leader-rbd-csi-ceph-com\" "}