{"level":"warn","ts":"2026-04-16T02:55:10.061873Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T02:55:10.062005Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.162:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.162:2380","--initial-cluster=instance=https://199.204.45.162:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.162:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.162: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-04-16T02:55:10.062112Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-16T02:55:10.062131Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-16T02:55:10.062319Z","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-04-16T02:55:10.063303Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"]} {"level":"info","ts":"2026-04-16T02:55:10.063408Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.162: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-04-16T02:55:10.067536Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.764275ms"} {"level":"info","ts":"2026-04-16T02:55:10.074484Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2d372260bb4c37a","cluster-id":"52bb6ec15e8722ff"} {"level":"info","ts":"2026-04-16T02:55:10.074581Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=()"} {"level":"info","ts":"2026-04-16T02:55:10.074608Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 0"} {"level":"info","ts":"2026-04-16T02:55:10.074623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2d372260bb4c37a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-16T02:55:10.074632Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became follower at term 1"} {"level":"info","ts":"2026-04-16T02:55:10.074746Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"warn","ts":"2026-04-16T02:55:10.082164Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-16T02:55:10.083833Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-16T02:55:10.084869Z","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-04-16T02:55:10.088952Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2d372260bb4c37a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-16T02:55:10.089256Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2d372260bb4c37a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-16T02:55:10.089416Z","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-04-16T02:55:10.089467Z","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-04-16T02:55:10.089484Z","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-04-16T02:55:10.092368Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a switched to configuration voters=(203631915892523898)"} {"level":"info","ts":"2026-04-16T02:55:10.092692Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","added-peer-id":"2d372260bb4c37a","added-peer-peer-urls":["https://199.204.45.162:2380"]} {"level":"info","ts":"2026-04-16T02:55:10.093711Z","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-04-16T02:55:10.093897Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-16T02:55:10.093937Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.162:2380"} {"level":"info","ts":"2026-04-16T02:55:10.09397Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2d372260bb4c37a","initial-advertise-peer-urls":["https://199.204.45.162:2380"],"listen-peer-urls":["https://199.204.45.162:2380"],"advertise-client-urls":["https://199.204.45.162:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.162:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-16T02:55:10.094034Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-16T02:55:11.07589Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a is starting a new election at term 1"} {"level":"info","ts":"2026-04-16T02:55:11.075977Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became pre-candidate at term 1"} {"level":"info","ts":"2026-04-16T02:55:11.07601Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgPreVoteResp from 2d372260bb4c37a at term 1"} {"level":"info","ts":"2026-04-16T02:55:11.076028Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became candidate at term 2"} {"level":"info","ts":"2026-04-16T02:55:11.076039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a received MsgVoteResp from 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-16T02:55:11.076056Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2d372260bb4c37a became leader at term 2"} {"level":"info","ts":"2026-04-16T02:55:11.076069Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2d372260bb4c37a elected leader 2d372260bb4c37a at term 2"} {"level":"info","ts":"2026-04-16T02:55:11.077172Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2d372260bb4c37a","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.162:2379]}","request-path":"/0/members/2d372260bb4c37a/attributes","cluster-id":"52bb6ec15e8722ff","publish-timeout":"7s"} {"level":"info","ts":"2026-04-16T02:55:11.077302Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T02:55:11.077269Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T02:55:11.077451Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-16T02:55:11.07762Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-16T02:55:11.077645Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-16T02:55:11.078699Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"52bb6ec15e8722ff","local-member-id":"2d372260bb4c37a","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T02:55:11.078873Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T02:55:11.078922Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-16T02:55:11.081776Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-16T02:55:11.082346Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.162:2379"} {"level":"warn","ts":"2026-04-16T02:55:15.844521Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744044454116344,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-16T02:55:16.140206Z","caller":"traceutil/trace.go:171","msg":"trace[1496138610] linearizableReadLoop","detail":"{readStateIndex:217; appliedIndex:216; }","duration":"796.430376ms","start":"2026-04-16T02:55:15.343749Z","end":"2026-04-16T02:55:16.140179Z","steps":["trace[1496138610] 'read index received' (duration: 796.214921ms)","trace[1496138610] 'applied index is now lower than readState.Index' (duration: 214.025µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:16.14045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"796.682973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3709"} {"level":"info","ts":"2026-04-16T02:55:16.140566Z","caller":"traceutil/trace.go:171","msg":"trace[60795189] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:212; }","duration":"796.882598ms","start":"2026-04-16T02:55:15.343672Z","end":"2026-04-16T02:55:16.140554Z","steps":["trace[60795189] 'agreement among raft nodes before linearized reading' (duration: 796.646652ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:16.140618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:15.343644Z","time spent":"796.96462ms","remote":"127.0.0.1:58878","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3731,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-16T02:55:16.140921Z","caller":"traceutil/trace.go:171","msg":"trace[1089341916] transaction","detail":"{read_only:false; response_revision:212; number_of_response:1; }","duration":"921.095864ms","start":"2026-04-16T02:55:15.219168Z","end":"2026-04-16T02:55:16.140264Z","steps":["trace[1089341916] 'process raft request' (duration: 920.835747ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:16.14165Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:15.219073Z","time spent":"921.913906ms","remote":"127.0.0.1:58882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3965,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T02:55:16.692001Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744044454116349,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T02:55:17.164792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.846739ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T02:55:17.164892Z","caller":"traceutil/trace.go:171","msg":"trace[1491080911] linearizableReadLoop","detail":"{readStateIndex:218; appliedIndex:217; }","duration":"972.960656ms","start":"2026-04-16T02:55:16.191915Z","end":"2026-04-16T02:55:17.164875Z","steps":["trace[1491080911] 'read index received' (duration: 763.403608ms)","trace[1491080911] 'applied index is now lower than readState.Index' (duration: 209.555268ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:17.165012Z","caller":"traceutil/trace.go:171","msg":"trace[17692957] transaction","detail":"{read_only:false; response_revision:213; number_of_response:1; }","duration":"1.016768409s","start":"2026-04-16T02:55:16.148205Z","end":"2026-04-16T02:55:17.164973Z","steps":["trace[17692957] 'process raft request' (duration: 807.15512ms)","trace[17692957] 'compare' (duration: 208.369547ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:17.165168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:16.148177Z","time spent":"1.016907473s","remote":"127.0.0.1:58878","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4003,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:17.166034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"973.200201ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:55:17.166085Z","caller":"traceutil/trace.go:171","msg":"trace[1155110585] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:213; }","duration":"974.207998ms","start":"2026-04-16T02:55:16.191863Z","end":"2026-04-16T02:55:17.166071Z","steps":["trace[1155110585] 'agreement among raft nodes before linearized reading' (duration: 973.13989ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:17.166168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:16.191837Z","time spent":"974.313731ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-16T02:55:17.176039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"573.576823ms","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-04-16T02:55:17.176129Z","caller":"traceutil/trace.go:171","msg":"trace[1748247288] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:214; }","duration":"573.697567ms","start":"2026-04-16T02:55:16.602413Z","end":"2026-04-16T02:55:17.17611Z","steps":["trace[1748247288] 'agreement among raft nodes before linearized reading' (duration: 573.518562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:17.176115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.392782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T02:55:17.176182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:16.6024Z","time spent":"573.768848ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-16T02:55:17.176207Z","caller":"traceutil/trace.go:171","msg":"trace[1791000804] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:0; response_revision:214; }","duration":"602.526386ms","start":"2026-04-16T02:55:16.573659Z","end":"2026-04-16T02:55:17.176186Z","steps":["trace[1791000804] 'agreement among raft nodes before linearized reading' (duration: 602.268709ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:17.176378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:16.573639Z","time spent":"602.729661ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":27,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T02:55:17.175912Z","caller":"traceutil/trace.go:171","msg":"trace[1271971302] transaction","detail":"{read_only:false; response_revision:214; number_of_response:1; }","duration":"328.545939ms","start":"2026-04-16T02:55:16.847343Z","end":"2026-04-16T02:55:17.175889Z","steps":["trace[1271971302] 'process raft request' (duration: 328.443016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:17.176599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:16.847311Z","time spent":"329.239167ms","remote":"127.0.0.1:58882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T02:55:17.732538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.031705ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-04-16T02:55:17.732654Z","caller":"traceutil/trace.go:171","msg":"trace[600488474] transaction","detail":"{read_only:false; response_revision:245; number_of_response:1; }","duration":"131.060148ms","start":"2026-04-16T02:55:17.60131Z","end":"2026-04-16T02:55:17.73237Z","steps":["trace[600488474] 'process raft request' (duration: 131.019597ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:17.732722Z","caller":"traceutil/trace.go:171","msg":"trace[1399954409] transaction","detail":"{read_only:false; response_revision:244; number_of_response:1; }","duration":"154.004386ms","start":"2026-04-16T02:55:17.578381Z","end":"2026-04-16T02:55:17.732385Z","steps":["trace[1399954409] 'process raft request' (duration: 101.626841ms)","trace[1399954409] 'compare' (duration: 52.17493ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:17.732861Z","caller":"traceutil/trace.go:171","msg":"trace[1095495118] linearizableReadLoop","detail":"{readStateIndex:250; appliedIndex:249; }","duration":"151.909602ms","start":"2026-04-16T02:55:17.58049Z","end":"2026-04-16T02:55:17.7324Z","steps":["trace[1095495118] 'read index received' (duration: 99.631348ms)","trace[1095495118] 'applied index is now lower than readState.Index' (duration: 52.276854ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:17.732928Z","caller":"traceutil/trace.go:171","msg":"trace[1675469087] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:245; }","duration":"152.143637ms","start":"2026-04-16T02:55:17.580458Z","end":"2026-04-16T02:55:17.732602Z","steps":["trace[1675469087] 'agreement among raft nodes before linearized reading' (duration: 151.945392ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:17.790387Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.545878ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-node-lease/instance\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:55:17.790519Z","caller":"traceutil/trace.go:171","msg":"trace[1691272170] range","detail":"{range_begin:/registry/leases/kube-node-lease/instance; range_end:; response_count:0; response_revision:247; }","duration":"190.730023ms","start":"2026-04-16T02:55:17.59975Z","end":"2026-04-16T02:55:17.79048Z","steps":["trace[1691272170] 'agreement among raft nodes before linearized reading' (duration: 190.485547ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:17.790485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.046125ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:1 size:664"} {"level":"info","ts":"2026-04-16T02:55:17.790604Z","caller":"traceutil/trace.go:171","msg":"trace[1462794860] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:1; response_revision:247; }","duration":"190.20687ms","start":"2026-04-16T02:55:17.600373Z","end":"2026-04-16T02:55:17.79058Z","steps":["trace[1462794860] 'agreement among raft nodes before linearized reading' (duration: 189.999554ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:18.139739Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.65051ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-16T02:55:18.139894Z","caller":"traceutil/trace.go:171","msg":"trace[725967813] transaction","detail":"{read_only:false; response_revision:264; number_of_response:1; }","duration":"195.087742ms","start":"2026-04-16T02:55:17.944793Z","end":"2026-04-16T02:55:18.13988Z","steps":["trace[725967813] 'process raft request' (duration: 195.03852ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:18.139936Z","caller":"traceutil/trace.go:171","msg":"trace[1741316073] transaction","detail":"{read_only:false; response_revision:263; number_of_response:1; }","duration":"204.216839ms","start":"2026-04-16T02:55:17.935686Z","end":"2026-04-16T02:55:18.139903Z","steps":["trace[1741316073] 'process raft request' (duration: 70.339983ms)","trace[1741316073] 'compare' (duration: 133.517107ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:18.29927Z","caller":"traceutil/trace.go:171","msg":"trace[1393851509] transaction","detail":"{read_only:false; response_revision:268; number_of_response:1; }","duration":"103.883002ms","start":"2026-04-16T02:55:18.195354Z","end":"2026-04-16T02:55:18.299237Z","steps":["trace[1393851509] 'process raft request' (duration: 81.36716ms)","trace[1393851509] 'compare' (duration: 22.205884ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:35.617713Z","caller":"traceutil/trace.go:171","msg":"trace[1081230674] transaction","detail":"{read_only:false; response_revision:424; number_of_response:1; }","duration":"341.782791ms","start":"2026-04-16T02:55:35.275896Z","end":"2026-04-16T02:55:35.617679Z","steps":["trace[1081230674] 'process raft request' (duration: 341.353942ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:35.617905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:35.275878Z","time spent":"341.932865ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:55:35.617989Z","caller":"traceutil/trace.go:171","msg":"trace[428686143] transaction","detail":"{read_only:false; response_revision:426; number_of_response:1; }","duration":"339.718677ms","start":"2026-04-16T02:55:35.278252Z","end":"2026-04-16T02:55:35.617971Z","steps":["trace[428686143] 'process raft request' (duration: 339.659355ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:35.618108Z","caller":"traceutil/trace.go:171","msg":"trace[1344230231] transaction","detail":"{read_only:false; response_revision:425; number_of_response:1; }","duration":"340.210307ms","start":"2026-04-16T02:55:35.277883Z","end":"2026-04-16T02:55:35.618093Z","steps":["trace[1344230231] 'process raft request' (duration: 339.609044ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:35.618336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:35.277872Z","time spent":"340.404381ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:35.618512Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:35.278243Z","time spent":"339.793368ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:55:39.789552Z","caller":"traceutil/trace.go:171","msg":"trace[616598277] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"155.767599ms","start":"2026-04-16T02:55:39.633766Z","end":"2026-04-16T02:55:39.789534Z","steps":["trace[616598277] 'process raft request' (duration: 154.593924ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:39.791908Z","caller":"traceutil/trace.go:171","msg":"trace[1764440655] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"157.290752ms","start":"2026-04-16T02:55:39.634597Z","end":"2026-04-16T02:55:39.791888Z","steps":["trace[1764440655] 'process raft request' (duration: 157.244791ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:39.791966Z","caller":"traceutil/trace.go:171","msg":"trace[432353450] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"157.452985ms","start":"2026-04-16T02:55:39.63449Z","end":"2026-04-16T02:55:39.791943Z","steps":["trace[432353450] 'process raft request' (duration: 157.23252ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:40.865508Z","caller":"traceutil/trace.go:171","msg":"trace[1573844912] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"727.972696ms","start":"2026-04-16T02:55:40.137494Z","end":"2026-04-16T02:55:40.865466Z","steps":["trace[1573844912] 'process raft request' (duration: 727.67837ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:40.865766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:40.137466Z","time spent":"728.179461ms","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T02:55:40.873278Z","caller":"traceutil/trace.go:171","msg":"trace[1876110614] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"725.888343ms","start":"2026-04-16T02:55:40.147351Z","end":"2026-04-16T02:55:40.873239Z","steps":["trace[1876110614] 'process raft request' (duration: 725.599287ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:40.873431Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:40.147334Z","time spent":"726.020785ms","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T02:55:40.873518Z","caller":"traceutil/trace.go:171","msg":"trace[44164014] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"721.249785ms","start":"2026-04-16T02:55:40.152074Z","end":"2026-04-16T02:55:40.873324Z","steps":["trace[44164014] 'process raft request' (duration: 721.04005ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:40.873701Z","caller":"traceutil/trace.go:171","msg":"trace[1128201143] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"716.009405ms","start":"2026-04-16T02:55:40.157683Z","end":"2026-04-16T02:55:40.873692Z","steps":["trace[1128201143] 'process raft request' (duration: 715.581196ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:40.873749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:40.157666Z","time spent":"716.063576ms","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T02:55:40.87358Z","caller":"traceutil/trace.go:171","msg":"trace[1035397211] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"718.841545ms","start":"2026-04-16T02:55:40.154712Z","end":"2026-04-16T02:55:40.873554Z","steps":["trace[1035397211] 'process raft request' (duration: 718.478257ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:40.873946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:40.154691Z","time spent":"719.184861ms","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T02:55:40.874017Z","caller":"traceutil/trace.go:171","msg":"trace[1374865372] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"715.585656ms","start":"2026-04-16T02:55:40.158421Z","end":"2026-04-16T02:55:40.874007Z","steps":["trace[1374865372] 'process raft request' (duration: 715.168917ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:40.874065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:40.158415Z","time spent":"715.630517ms","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T02:55:40.874319Z","caller":"traceutil/trace.go:171","msg":"trace[1294229844] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"715.688868ms","start":"2026-04-16T02:55:40.15861Z","end":"2026-04-16T02:55:40.874299Z","steps":["trace[1294229844] 'process raft request' (duration: 715.193778ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:40.874145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:40.152056Z","time spent":"721.653823ms","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T02:55:40.874557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:40.158596Z","time spent":"715.77543ms","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T02:55:40.874558Z","caller":"traceutil/trace.go:171","msg":"trace[2056282172] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"714.79303ms","start":"2026-04-16T02:55:40.159727Z","end":"2026-04-16T02:55:40.87452Z","steps":["trace[2056282172] 'process raft request' (duration: 714.519024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:40.874741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:40.159716Z","time spent":"714.897332ms","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T02:55:40.87497Z","caller":"traceutil/trace.go:171","msg":"trace[1852333852] transaction","detail":"{read_only:false; response_revision:478; number_of_response:1; }","duration":"705.29621ms","start":"2026-04-16T02:55:40.169646Z","end":"2026-04-16T02:55:40.874942Z","steps":["trace[1852333852] 'process raft request' (duration: 704.689707ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:40.875047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:40.169612Z","time spent":"705.397882ms","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T02:55:40.875417Z","caller":"traceutil/trace.go:171","msg":"trace[693845125] transaction","detail":"{read_only:false; response_revision:480; number_of_response:1; }","duration":"692.577282ms","start":"2026-04-16T02:55:40.182823Z","end":"2026-04-16T02:55:40.875401Z","steps":["trace[693845125] 'process raft request' (duration: 692.144773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:40.875652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:40.182805Z","time spent":"692.812688ms","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T02:55:40.87617Z","caller":"traceutil/trace.go:171","msg":"trace[392448042] transaction","detail":"{read_only:false; response_revision:481; number_of_response:1; }","duration":"541.159863ms","start":"2026-04-16T02:55:40.334996Z","end":"2026-04-16T02:55:40.876156Z","steps":["trace[392448042] 'process raft request' (duration: 540.364357ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:40.876309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:40.334897Z","time spent":"541.345637ms","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T02:55:40.876894Z","caller":"traceutil/trace.go:171","msg":"trace[1681443905] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:486; }","duration":"115.003934ms","start":"2026-04-16T02:55:40.761874Z","end":"2026-04-16T02:55:40.876878Z","steps":["trace[1681443905] 'read index received' (duration: 103.222067ms)","trace[1681443905] 'applied index is now lower than readState.Index' (duration: 11.779767ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:40.87707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.195229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:55:40.877116Z","caller":"traceutil/trace.go:171","msg":"trace[561753118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:482; }","duration":"115.289681ms","start":"2026-04-16T02:55:40.761815Z","end":"2026-04-16T02:55:40.877105Z","steps":["trace[561753118] 'agreement among raft nodes before linearized reading' (duration: 115.117327ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:40.875541Z","caller":"traceutil/trace.go:171","msg":"trace[1469371894] transaction","detail":"{read_only:false; response_revision:479; number_of_response:1; }","duration":"704.391251ms","start":"2026-04-16T02:55:40.170721Z","end":"2026-04-16T02:55:40.875112Z","steps":["trace[1469371894] 'process raft request' (duration: 703.84592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:40.878131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:40.170708Z","time spent":"707.389044ms","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-16T02:55:41.159411Z","caller":"traceutil/trace.go:171","msg":"trace[1473834093] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"109.424948ms","start":"2026-04-16T02:55:41.049958Z","end":"2026-04-16T02:55:41.159383Z","steps":["trace[1473834093] 'process raft request' (duration: 39.055793ms)","trace[1473834093] 'compare' (duration: 70.153141ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:41.161722Z","caller":"traceutil/trace.go:171","msg":"trace[2066158307] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"103.545175ms","start":"2026-04-16T02:55:41.058156Z","end":"2026-04-16T02:55:41.161701Z","steps":["trace[2066158307] 'process raft request' (duration: 103.211608ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:41.560583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.308922ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T02:55:41.560815Z","caller":"traceutil/trace.go:171","msg":"trace[1514522717] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"338.22595ms","start":"2026-04-16T02:55:41.222572Z","end":"2026-04-16T02:55:41.560798Z","steps":["trace[1514522717] 'process raft request' (duration: 338.139299ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:41.560845Z","caller":"traceutil/trace.go:171","msg":"trace[639273019] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"353.076888ms","start":"2026-04-16T02:55:41.207726Z","end":"2026-04-16T02:55:41.560803Z","steps":["trace[639273019] 'process raft request' (duration: 209.46302ms)","trace[639273019] 'compare' (duration: 143.167379ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:41.560882Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:41.222551Z","time spent":"338.304792ms","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64392,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T02:55:41.56103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:41.207707Z","time spent":"353.217982ms","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":65190,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:55:41.563676Z","caller":"traceutil/trace.go:171","msg":"trace[1747854396] linearizableReadLoop","detail":"{readStateIndex:538; appliedIndex:535; }","duration":"231.164801ms","start":"2026-04-16T02:55:41.332495Z","end":"2026-04-16T02:55:41.56366Z","steps":["trace[1747854396] 'read index received' (duration: 84.702043ms)","trace[1747854396] 'applied index is now lower than readState.Index' (duration: 146.461908ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:41.563828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.323554ms","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-04-16T02:55:41.563862Z","caller":"traceutil/trace.go:171","msg":"trace[915253877] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:516; }","duration":"231.389176ms","start":"2026-04-16T02:55:41.332465Z","end":"2026-04-16T02:55:41.563854Z","steps":["trace[915253877] 'agreement among raft nodes before linearized reading' (duration: 231.251593ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:41.564122Z","caller":"traceutil/trace.go:171","msg":"trace[248800519] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"310.85108ms","start":"2026-04-16T02:55:41.253261Z","end":"2026-04-16T02:55:41.564112Z","steps":["trace[248800519] 'process raft request' (duration: 310.277048ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:41.564188Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:41.253232Z","time spent":"310.919581ms","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":59616,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:55:41.747841Z","caller":"traceutil/trace.go:171","msg":"trace[1026930992] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"162.893451ms","start":"2026-04-16T02:55:41.584915Z","end":"2026-04-16T02:55:41.747808Z","steps":["trace[1026930992] 'process raft request' (duration: 122.113052ms)","trace[1026930992] 'compare' (duration: 40.589435ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:41.753392Z","caller":"traceutil/trace.go:171","msg":"trace[1785772161] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"155.813973ms","start":"2026-04-16T02:55:41.597564Z","end":"2026-04-16T02:55:41.753378Z","steps":["trace[1785772161] 'process raft request' (duration: 155.604039ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:41.753567Z","caller":"traceutil/trace.go:171","msg":"trace[1706584225] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"113.058164ms","start":"2026-04-16T02:55:41.640482Z","end":"2026-04-16T02:55:41.75354Z","steps":["trace[1706584225] 'process raft request' (duration: 112.853069ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:42.121196Z","caller":"traceutil/trace.go:171","msg":"trace[1414895067] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"313.74486ms","start":"2026-04-16T02:55:41.807416Z","end":"2026-04-16T02:55:42.121161Z","steps":["trace[1414895067] 'process raft request' (duration: 218.111947ms)","trace[1414895067] 'compare' (duration: 95.335067ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:42.121356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:41.807398Z","time spent":"313.876533ms","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":80688,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:42.121381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"322.096604ms","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-04-16T02:55:42.121435Z","caller":"traceutil/trace.go:171","msg":"trace[1286083837] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:522; }","duration":"322.163396ms","start":"2026-04-16T02:55:41.799256Z","end":"2026-04-16T02:55:42.121419Z","steps":["trace[1286083837] 'agreement among raft nodes before linearized reading' (duration: 321.971222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:42.121482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:41.799251Z","time spent":"322.223407ms","remote":"127.0.0.1:58950","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-04-16T02:55:42.121514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.097947ms","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-04-16T02:55:42.12159Z","caller":"traceutil/trace.go:171","msg":"trace[556436150] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:522; }","duration":"326.21106ms","start":"2026-04-16T02:55:41.795359Z","end":"2026-04-16T02:55:42.12157Z","steps":["trace[556436150] 'agreement among raft nodes before linearized reading' (duration: 325.990845ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:42.121635Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:41.795349Z","time spent":"326.278131ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-16T02:55:42.121012Z","caller":"traceutil/trace.go:171","msg":"trace[637302557] linearizableReadLoop","detail":"{readStateIndex:544; appliedIndex:543; }","duration":"283.271886ms","start":"2026-04-16T02:55:41.837718Z","end":"2026-04-16T02:55:42.12099Z","steps":["trace[637302557] 'read index received' (duration: 187.902918ms)","trace[637302557] 'applied index is now lower than readState.Index' (duration: 95.368068ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:42.126793Z","caller":"traceutil/trace.go:171","msg":"trace[291019120] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"282.767454ms","start":"2026-04-16T02:55:41.844007Z","end":"2026-04-16T02:55:42.126774Z","steps":["trace[291019120] 'process raft request' (duration: 282.629831ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:42.126917Z","caller":"traceutil/trace.go:171","msg":"trace[562265306] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"283.623763ms","start":"2026-04-16T02:55:41.843271Z","end":"2026-04-16T02:55:42.126895Z","steps":["trace[562265306] 'process raft request' (duration: 283.188964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:43.085837Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744044454116941,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T02:55:43.586967Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14085744044454116941,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-16T02:55:44.077703Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.49201181s","expected-duration":"1s"} {"level":"info","ts":"2026-04-16T02:55:44.078089Z","caller":"traceutil/trace.go:171","msg":"trace[39203818] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"1.496252487s","start":"2026-04-16T02:55:42.581819Z","end":"2026-04-16T02:55:44.078071Z","steps":["trace[39203818] 'process raft request' (duration: 1.496078554s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:44.078193Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:42.581795Z","time spent":"1.496328378s","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":74754,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:55:44.124808Z","caller":"traceutil/trace.go:171","msg":"trace[1871802410] linearizableReadLoop","detail":"{readStateIndex:565; appliedIndex:562; }","duration":"1.539189127s","start":"2026-04-16T02:55:42.585598Z","end":"2026-04-16T02:55:44.124788Z","steps":["trace[1871802410] 'read index received' (duration: 1.492395378s)","trace[1871802410] 'applied index is now lower than readState.Index' (duration: 46.793069ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:44.124851Z","caller":"traceutil/trace.go:171","msg":"trace[909195693] transaction","detail":"{read_only:false; response_revision:542; number_of_response:1; }","duration":"1.464845892s","start":"2026-04-16T02:55:42.659974Z","end":"2026-04-16T02:55:44.12482Z","steps":["trace[909195693] 'process raft request' (duration: 1.463929824s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:44.125135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.539504433s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"info","ts":"2026-04-16T02:55:44.125175Z","caller":"traceutil/trace.go:171","msg":"trace[1486294302] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:543; }","duration":"1.539583624s","start":"2026-04-16T02:55:42.58558Z","end":"2026-04-16T02:55:44.125164Z","steps":["trace[1486294302] 'agreement among raft nodes before linearized reading' (duration: 1.539324199s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:44.125205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:42.585567Z","time spent":"1.539632555s","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29592,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-04-16T02:55:44.124926Z","caller":"traceutil/trace.go:171","msg":"trace[719363841] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"663.209599ms","start":"2026-04-16T02:55:43.461693Z","end":"2026-04-16T02:55:44.124902Z","steps":["trace[719363841] 'process raft request' (duration: 663.052996ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:44.125381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:43.461671Z","time spent":"663.653889ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:44.125396Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:42.659922Z","time spent":"1.46522148s","remote":"127.0.0.1:58758","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570081,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:44.303771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.26588ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T02:55:44.303998Z","caller":"traceutil/trace.go:171","msg":"trace[2044258982] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"140.762716ms","start":"2026-04-16T02:55:44.163215Z","end":"2026-04-16T02:55:44.303978Z","steps":["trace[2044258982] 'process raft request' (duration: 140.677575ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:44.304043Z","caller":"traceutil/trace.go:171","msg":"trace[541032569] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"204.845856ms","start":"2026-04-16T02:55:44.099163Z","end":"2026-04-16T02:55:44.304009Z","steps":["trace[541032569] 'process raft request' (duration: 48.212438ms)","trace[541032569] 'compare' (duration: 156.122938ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:44.304293Z","caller":"traceutil/trace.go:171","msg":"trace[229227775] linearizableReadLoop","detail":"{readStateIndex:566; appliedIndex:565; }","duration":"179.168015ms","start":"2026-04-16T02:55:44.125108Z","end":"2026-04-16T02:55:44.304276Z","steps":["trace[229227775] 'read index received' (duration: 22.217291ms)","trace[229227775] 'applied index is now lower than readState.Index' (duration: 156.949204ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:44.304416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.541704576s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:55:44.304467Z","caller":"traceutil/trace.go:171","msg":"trace[493846181] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:545; }","duration":"1.541811178s","start":"2026-04-16T02:55:42.762642Z","end":"2026-04-16T02:55:44.304453Z","steps":["trace[493846181] 'agreement among raft nodes before linearized reading' (duration: 1.541733296s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:44.30452Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:42.762617Z","time spent":"1.541890319s","remote":"127.0.0.1:58690","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-16T02:55:44.304544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.173593ms","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-04-16T02:55:44.304595Z","caller":"traceutil/trace.go:171","msg":"trace[2080436054] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:545; }","duration":"173.270066ms","start":"2026-04-16T02:55:44.131312Z","end":"2026-04-16T02:55:44.304582Z","steps":["trace[2080436054] 'agreement among raft nodes before linearized reading' (duration: 173.094992ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:44.304591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"714.889605ms","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-04-16T02:55:44.304761Z","caller":"traceutil/trace.go:171","msg":"trace[930837122] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:545; }","duration":"715.036119ms","start":"2026-04-16T02:55:43.589651Z","end":"2026-04-16T02:55:44.304687Z","steps":["trace[930837122] 'agreement among raft nodes before linearized reading' (duration: 714.711852ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:44.304842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:43.589635Z","time spent":"715.183361ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T02:55:44.304979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.552749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:10870"} {"level":"info","ts":"2026-04-16T02:55:44.305036Z","caller":"traceutil/trace.go:171","msg":"trace[143412834] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/gatewayclasses.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:545; }","duration":"167.629552ms","start":"2026-04-16T02:55:44.137393Z","end":"2026-04-16T02:55:44.305022Z","steps":["trace[143412834] 'agreement among raft nodes before linearized reading' (duration: 167.468318ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:48.789413Z","caller":"traceutil/trace.go:171","msg":"trace[2056642467] linearizableReadLoop","detail":"{readStateIndex:595; appliedIndex:594; }","duration":"462.155477ms","start":"2026-04-16T02:55:48.327233Z","end":"2026-04-16T02:55:48.789389Z","steps":["trace[2056642467] 'read index received' (duration: 461.405472ms)","trace[2056642467] 'applied index is now lower than readState.Index' (duration: 749.185µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:48.789546Z","caller":"traceutil/trace.go:171","msg":"trace[110916451] transaction","detail":"{read_only:false; response_revision:572; number_of_response:1; }","duration":"678.180806ms","start":"2026-04-16T02:55:48.111332Z","end":"2026-04-16T02:55:48.789512Z","steps":["trace[110916451] 'process raft request' (duration: 677.494473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:48.789629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.383561ms","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-04-16T02:55:48.789659Z","caller":"traceutil/trace.go:171","msg":"trace[2054473085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:572; }","duration":"462.458303ms","start":"2026-04-16T02:55:48.327192Z","end":"2026-04-16T02:55:48.789651Z","steps":["trace[2054473085] 'agreement among raft nodes before linearized reading' (duration: 462.326091ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:48.789684Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:48.327176Z","time spent":"462.502664ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T02:55:48.789733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:48.111308Z","time spent":"678.333459ms","remote":"127.0.0.1:58808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:48.812315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"475.927437ms","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-04-16T02:55:48.812379Z","caller":"traceutil/trace.go:171","msg":"trace[104797922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:573; }","duration":"476.02971ms","start":"2026-04-16T02:55:48.336332Z","end":"2026-04-16T02:55:48.812362Z","steps":["trace[104797922] 'agreement among raft nodes before linearized reading' (duration: 475.853436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:48.812408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:48.336299Z","time spent":"476.103071ms","remote":"127.0.0.1:58950","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-04-16T02:55:48.812624Z","caller":"traceutil/trace.go:171","msg":"trace[2142803758] transaction","detail":"{read_only:false; response_revision:573; number_of_response:1; }","duration":"472.119162ms","start":"2026-04-16T02:55:48.340481Z","end":"2026-04-16T02:55:48.8126Z","steps":["trace[2142803758] 'process raft request' (duration: 471.601332ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:48.812696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.283604ms","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-04-16T02:55:48.812718Z","caller":"traceutil/trace.go:171","msg":"trace[1180755972] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:573; }","duration":"476.344246ms","start":"2026-04-16T02:55:48.336367Z","end":"2026-04-16T02:55:48.812711Z","steps":["trace[1180755972] 'agreement among raft nodes before linearized reading' (duration: 476.290965ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:48.812729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:48.340464Z","time spent":"472.208945ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:48.812644Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"476.202783ms","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-04-16T02:55:48.812885Z","caller":"traceutil/trace.go:171","msg":"trace[818855939] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:573; }","duration":"476.441718ms","start":"2026-04-16T02:55:48.336432Z","end":"2026-04-16T02:55:48.812874Z","steps":["trace[818855939] 'agreement among raft nodes before linearized reading' (duration: 476.160762ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:48.812878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.890296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8gvfn\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-04-16T02:55:48.812738Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:48.336347Z","time spent":"476.386986ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T02:55:48.813044Z","caller":"traceutil/trace.go:171","msg":"trace[1820677121] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8gvfn; range_end:; response_count:1; response_revision:573; }","duration":"106.029209ms","start":"2026-04-16T02:55:48.706913Z","end":"2026-04-16T02:55:48.812942Z","steps":["trace[1820677121] 'agreement among raft nodes before linearized reading' (duration: 105.656102ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:48.812915Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:48.336359Z","time spent":"476.548589ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T02:55:49.544445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"683.640197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-16T02:55:49.544548Z","caller":"traceutil/trace.go:171","msg":"trace[1905993352] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"749.064821ms","start":"2026-04-16T02:55:48.795465Z","end":"2026-04-16T02:55:49.54453Z","steps":["trace[1905993352] 'process raft request' (duration: 65.263971ms)","trace[1905993352] 'compare' (duration: 683.536025ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:49.544606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:48.795441Z","time spent":"749.139882ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:55:49.585617Z","caller":"traceutil/trace.go:171","msg":"trace[429488487] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"766.050188ms","start":"2026-04-16T02:55:48.819538Z","end":"2026-04-16T02:55:49.585589Z","steps":["trace[429488487] 'process raft request' (duration: 765.918645ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:49.585694Z","caller":"traceutil/trace.go:171","msg":"trace[536748193] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"758.321246ms","start":"2026-04-16T02:55:48.827324Z","end":"2026-04-16T02:55:49.585645Z","steps":["trace[536748193] 'process raft request' (duration: 758.176043ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:49.585904Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:48.819532Z","time spent":"766.232871ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:55:49.585917Z","caller":"traceutil/trace.go:171","msg":"trace[1420832521] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"750.482493ms","start":"2026-04-16T02:55:48.835407Z","end":"2026-04-16T02:55:49.585889Z","steps":["trace[1420832521] 'process raft request' (duration: 750.130296ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:49.585633Z","caller":"traceutil/trace.go:171","msg":"trace[1946532033] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"766.176941ms","start":"2026-04-16T02:55:48.819412Z","end":"2026-04-16T02:55:49.585589Z","steps":["trace[1946532033] 'process raft request' (duration: 765.904735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:49.586063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:48.835393Z","time spent":"750.599665ms","remote":"127.0.0.1:58882","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:49.586058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:48.82731Z","time spent":"758.541321ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:49.586138Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:48.819394Z","time spent":"766.68577ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-16T02:55:49.586518Z","caller":"traceutil/trace.go:171","msg":"trace[2070996809] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"731.092111ms","start":"2026-04-16T02:55:48.855408Z","end":"2026-04-16T02:55:49.5865Z","steps":["trace[2070996809] 'process raft request' (duration: 730.326197ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:49.586639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:48.85535Z","time spent":"731.216835ms","remote":"127.0.0.1:59036","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1092,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-16T02:55:51.763682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000867254s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-16T02:55:51.763751Z","caller":"traceutil/trace.go:171","msg":"trace[1935418579] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000982126s","start":"2026-04-16T02:55:49.762751Z","end":"2026-04-16T02:55:51.763733Z","steps":["trace[1935418579] 'agreement among raft nodes before linearized reading' (duration: 2.000896015s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:51.763842Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:49.762732Z","time spent":"2.001101668s","remote":"127.0.0.1:58690","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-04-16T02:55:51.904217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.263869038s","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-16T02:55:51.904295Z","caller":"traceutil/trace.go:171","msg":"trace[1788383204] linearizableReadLoop","detail":"{readStateIndex:603; appliedIndex:602; }","duration":"2.141492347s","start":"2026-04-16T02:55:49.762789Z","end":"2026-04-16T02:55:51.904282Z","steps":["trace[1788383204] 'read index received' (duration: 44.881µs)","trace[1788383204] 'applied index is now lower than readState.Index' (duration: 2.141446396s)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:55:51.904545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:50.599466Z","time spent":"1.305076163s","remote":"127.0.0.1:44760","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-16T02:55:51.961312Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.432242839s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-16T02:55:51.96302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.907756ms","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-04-16T02:55:51.963364Z","caller":"traceutil/trace.go:171","msg":"trace[113278621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:580; }","duration":"414.303863ms","start":"2026-04-16T02:55:51.549025Z","end":"2026-04-16T02:55:51.963329Z","steps":["trace[113278621] 'agreement among raft nodes before linearized reading' (duration: 413.866405ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:51.963511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:51.548967Z","time spent":"414.485237ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-16T02:55:51.963611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.595971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-16T02:55:51.963664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.094787ms","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-04-16T02:55:51.96377Z","caller":"traceutil/trace.go:171","msg":"trace[827449188] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:580; }","duration":"370.236849ms","start":"2026-04-16T02:55:51.593497Z","end":"2026-04-16T02:55:51.963734Z","steps":["trace[827449188] 'agreement among raft nodes before linearized reading' (duration: 369.316422ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:51.963757Z","caller":"traceutil/trace.go:171","msg":"trace[2109888452] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:580; }","duration":"154.749793ms","start":"2026-04-16T02:55:51.808927Z","end":"2026-04-16T02:55:51.963677Z","steps":["trace[2109888452] 'agreement among raft nodes before linearized reading' (duration: 154.57305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:51.963836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:51.593477Z","time spent":"370.347602ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":500,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-16T02:55:51.964057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.181933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:55:51.964127Z","caller":"traceutil/trace.go:171","msg":"trace[886673275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:580; }","duration":"200.261395ms","start":"2026-04-16T02:55:51.763837Z","end":"2026-04-16T02:55:51.964098Z","steps":["trace[886673275] 'agreement among raft nodes before linearized reading' (duration: 200.162113ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:51.964472Z","caller":"traceutil/trace.go:171","msg":"trace[1064957501] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"1.961452274s","start":"2026-04-16T02:55:50.002991Z","end":"2026-04-16T02:55:51.964444Z","steps":["trace[1064957501] 'process raft request' (duration: 1.95866808s)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:51.964743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.467473ms","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-04-16T02:55:51.964807Z","caller":"traceutil/trace.go:171","msg":"trace[1897436412] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:580; }","duration":"371.529164ms","start":"2026-04-16T02:55:51.593262Z","end":"2026-04-16T02:55:51.964791Z","steps":["trace[1897436412] 'agreement among raft nodes before linearized reading' (duration: 371.371551ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:51.964845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.794039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-04-16T02:55:51.964855Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:51.593227Z","time spent":"371.609325ms","remote":"127.0.0.1:58950","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-16T02:55:51.96491Z","caller":"traceutil/trace.go:171","msg":"trace[1345521580] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:580; }","duration":"371.880191ms","start":"2026-04-16T02:55:51.593003Z","end":"2026-04-16T02:55:51.964883Z","steps":["trace[1345521580] 'agreement among raft nodes before linearized reading' (duration: 371.703348ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:51.965128Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:51.592984Z","time spent":"371.956932ms","remote":"127.0.0.1:58950","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-04-16T02:55:51.965372Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-16T02:55:50.002963Z","time spent":"1.961551036s","remote":"127.0.0.1:58808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036261,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-16T02:55:51.965616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.465726ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:55:51.96576Z","caller":"traceutil/trace.go:171","msg":"trace[179806536] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:580; }","duration":"156.66616ms","start":"2026-04-16T02:55:51.809055Z","end":"2026-04-16T02:55:51.965721Z","steps":["trace[179806536] 'agreement among raft nodes before linearized reading' (duration: 153.809365ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:53.159033Z","caller":"traceutil/trace.go:171","msg":"trace[207975201] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"178.908865ms","start":"2026-04-16T02:55:52.980099Z","end":"2026-04-16T02:55:53.159008Z","steps":["trace[207975201] 'process raft request' (duration: 178.289894ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:55:54.112523Z","caller":"traceutil/trace.go:171","msg":"trace[945529468] linearizableReadLoop","detail":"{readStateIndex:648; appliedIndex:647; }","duration":"119.876572ms","start":"2026-04-16T02:55:53.992626Z","end":"2026-04-16T02:55:54.112503Z","steps":["trace[945529468] 'read index received' (duration: 119.820721ms)","trace[945529468] 'applied index is now lower than readState.Index' (duration: 55.001µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:55:54.112642Z","caller":"traceutil/trace.go:171","msg":"trace[153775900] transaction","detail":"{read_only:false; response_revision:624; number_of_response:1; }","duration":"214.997635ms","start":"2026-04-16T02:55:53.897627Z","end":"2026-04-16T02:55:54.112624Z","steps":["trace[153775900] 'process raft request' (duration: 214.77283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:54.11297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.322201ms","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-04-16T02:55:54.113039Z","caller":"traceutil/trace.go:171","msg":"trace[1045472691] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:624; }","duration":"120.425302ms","start":"2026-04-16T02:55:53.992599Z","end":"2026-04-16T02:55:54.113024Z","steps":["trace[1045472691] 'agreement among raft nodes before linearized reading' (duration: 120.196858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:54.11753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.525129ms","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-04-16T02:55:54.1176Z","caller":"traceutil/trace.go:171","msg":"trace[1901391616] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:625; }","duration":"124.603321ms","start":"2026-04-16T02:55:53.992985Z","end":"2026-04-16T02:55:54.117588Z","steps":["trace[1901391616] 'agreement among raft nodes before linearized reading' (duration: 124.442768ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:54.117586Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.445849ms","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-04-16T02:55:54.11786Z","caller":"traceutil/trace.go:171","msg":"trace[1534059284] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:625; }","duration":"123.721694ms","start":"2026-04-16T02:55:53.9941Z","end":"2026-04-16T02:55:54.117821Z","steps":["trace[1534059284] 'agreement among raft nodes before linearized reading' (duration: 123.404648ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:54.117622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.982591ms","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-04-16T02:55:54.118012Z","caller":"traceutil/trace.go:171","msg":"trace[1938606243] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:625; }","duration":"123.398568ms","start":"2026-04-16T02:55:53.994595Z","end":"2026-04-16T02:55:54.117993Z","steps":["trace[1938606243] 'agreement among raft nodes before linearized reading' (duration: 122.854798ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:55:57.123864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.99359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io\" ","response":"range_response_count:1 size:4180"} {"level":"info","ts":"2026-04-16T02:55:57.123942Z","caller":"traceutil/trace.go:171","msg":"trace[174177953] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumcidrgroups.cilium.io; range_end:; response_count:1; response_revision:664; }","duration":"228.084501ms","start":"2026-04-16T02:55:56.895837Z","end":"2026-04-16T02:55:57.123922Z","steps":["trace[174177953] 'range keys from in-memory index tree' (duration: 227.859567ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:56:07.787443Z","caller":"traceutil/trace.go:171","msg":"trace[1932123722] transaction","detail":"{read_only:false; response_revision:750; number_of_response:1; }","duration":"218.592589ms","start":"2026-04-16T02:56:07.568824Z","end":"2026-04-16T02:56:07.787416Z","steps":["trace[1932123722] 'process raft request' (duration: 218.389566ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:56:40.455309Z","caller":"traceutil/trace.go:171","msg":"trace[1201749621] transaction","detail":"{read_only:false; response_revision:985; number_of_response:1; }","duration":"108.248193ms","start":"2026-04-16T02:56:40.347037Z","end":"2026-04-16T02:56:40.455285Z","steps":["trace[1201749621] 'process raft request' (duration: 107.740166ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:56:44.625222Z","caller":"traceutil/trace.go:171","msg":"trace[1677671224] transaction","detail":"{read_only:false; response_revision:1019; number_of_response:1; }","duration":"110.052687ms","start":"2026-04-16T02:56:44.515149Z","end":"2026-04-16T02:56:44.625202Z","steps":["trace[1677671224] 'process raft request' (duration: 102.358303ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:56:44.625689Z","caller":"traceutil/trace.go:171","msg":"trace[588227325] linearizableReadLoop","detail":"{readStateIndex:1059; appliedIndex:1057; }","duration":"105.167331ms","start":"2026-04-16T02:56:44.520497Z","end":"2026-04-16T02:56:44.625665Z","steps":["trace[588227325] 'read index received' (duration: 97.098371ms)","trace[588227325] 'applied index is now lower than readState.Index' (duration: 8.06787ms)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:56:44.625907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.394275ms","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-04-16T02:56:44.625906Z","caller":"traceutil/trace.go:171","msg":"trace[1952047862] transaction","detail":"{read_only:false; response_revision:1020; number_of_response:1; }","duration":"110.606705ms","start":"2026-04-16T02:56:44.51528Z","end":"2026-04-16T02:56:44.625886Z","steps":["trace[1952047862] 'process raft request' (duration: 110.192469ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:56:44.625969Z","caller":"traceutil/trace.go:171","msg":"trace[305413033] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1020; }","duration":"105.467136ms","start":"2026-04-16T02:56:44.520486Z","end":"2026-04-16T02:56:44.625953Z","steps":["trace[305413033] 'agreement among raft nodes before linearized reading' (duration: 105.304473ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:56:58.261923Z","caller":"traceutil/trace.go:171","msg":"trace[51056758] transaction","detail":"{read_only:false; response_revision:1085; number_of_response:1; }","duration":"125.677965ms","start":"2026-04-16T02:56:58.136225Z","end":"2026-04-16T02:56:58.261903Z","steps":["trace[51056758] 'process raft request' (duration: 125.561733ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:10.43593Z","caller":"traceutil/trace.go:171","msg":"trace[1327685326] linearizableReadLoop","detail":"{readStateIndex:1172; appliedIndex:1171; }","duration":"170.082782ms","start":"2026-04-16T02:57:10.265825Z","end":"2026-04-16T02:57:10.435908Z","steps":["trace[1327685326] 'read index received' (duration: 169.91956ms)","trace[1327685326] 'applied index is now lower than readState.Index' (duration: 162.022µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:57:10.436055Z","caller":"traceutil/trace.go:171","msg":"trace[558095330] transaction","detail":"{read_only:false; response_revision:1128; number_of_response:1; }","duration":"184.031813ms","start":"2026-04-16T02:57:10.251988Z","end":"2026-04-16T02:57:10.436019Z","steps":["trace[558095330] 'process raft request' (duration: 183.77751ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:10.436152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.295545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:controller:attachdetach-controller\" ","response":"range_response_count:1 size:767"} {"level":"info","ts":"2026-04-16T02:57:10.436246Z","caller":"traceutil/trace.go:171","msg":"trace[996561167] range","detail":"{range_begin:/registry/clusterrolebindings/system:controller:attachdetach-controller; range_end:; response_count:1; response_revision:1128; }","duration":"170.439377ms","start":"2026-04-16T02:57:10.265784Z","end":"2026-04-16T02:57:10.436223Z","steps":["trace[996561167] 'agreement among raft nodes before linearized reading' (duration: 170.256115ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:10.436333Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.723965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:heapster\" ","response":"range_response_count:1 size:638"} {"level":"info","ts":"2026-04-16T02:57:10.436439Z","caller":"traceutil/trace.go:171","msg":"trace[1416096420] range","detail":"{range_begin:/registry/clusterroles/system:heapster; range_end:; response_count:1; response_revision:1128; }","duration":"122.876218ms","start":"2026-04-16T02:57:10.313545Z","end":"2026-04-16T02:57:10.436421Z","steps":["trace[1416096420] 'agreement among raft nodes before linearized reading' (duration: 122.642915ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:14.521922Z","caller":"traceutil/trace.go:171","msg":"trace[417598800] linearizableReadLoop","detail":"{readStateIndex:1185; appliedIndex:1184; }","duration":"207.35921ms","start":"2026-04-16T02:57:14.314536Z","end":"2026-04-16T02:57:14.521895Z","steps":["trace[417598800] 'read index received' (duration: 207.168888ms)","trace[417598800] 'applied index is now lower than readState.Index' (duration: 188.882µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:57:14.52208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.529342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-controller-manager\" ","response":"range_response_count:1 size:745"} {"level":"warn","ts":"2026-04-16T02:57:14.522089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.554098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:kube-dns\" ","response":"range_response_count:1 size:674"} {"level":"info","ts":"2026-04-16T02:57:14.522113Z","caller":"traceutil/trace.go:171","msg":"trace[1301936584] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-controller-manager; range_end:; response_count:1; response_revision:1140; }","duration":"207.603623ms","start":"2026-04-16T02:57:14.3145Z","end":"2026-04-16T02:57:14.522103Z","steps":["trace[1301936584] 'agreement among raft nodes before linearized reading' (duration: 207.525262ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:14.522131Z","caller":"traceutil/trace.go:171","msg":"trace[553286377] range","detail":"{range_begin:/registry/clusterrolebindings/system:kube-dns; range_end:; response_count:1; response_revision:1140; }","duration":"165.628589ms","start":"2026-04-16T02:57:14.356487Z","end":"2026-04-16T02:57:14.522116Z","steps":["trace[553286377] 'agreement among raft nodes before linearized reading' (duration: 165.553958ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:18.941904Z","caller":"traceutil/trace.go:171","msg":"trace[600352431] transaction","detail":"{read_only:false; response_revision:1157; number_of_response:1; }","duration":"101.17476ms","start":"2026-04-16T02:57:18.840707Z","end":"2026-04-16T02:57:18.941881Z","steps":["trace[600352431] 'process raft request' (duration: 100.670894ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:19.991676Z","caller":"traceutil/trace.go:171","msg":"trace[1399720313] linearizableReadLoop","detail":"{readStateIndex:1207; appliedIndex:1206; }","duration":"131.517635ms","start":"2026-04-16T02:57:19.86014Z","end":"2026-04-16T02:57:19.991657Z","steps":["trace[1399720313] 'read index received' (duration: 131.337943ms)","trace[1399720313] 'applied index is now lower than readState.Index' (duration: 178.872µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:57:19.991739Z","caller":"traceutil/trace.go:171","msg":"trace[316575761] transaction","detail":"{read_only:false; response_revision:1161; number_of_response:1; }","duration":"153.24015ms","start":"2026-04-16T02:57:19.838468Z","end":"2026-04-16T02:57:19.991708Z","steps":["trace[316575761] 'process raft request' (duration: 153.057538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:19.991816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.662396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/kube-root-ca.crt\" ","response":"range_response_count:1 size:1746"} {"level":"warn","ts":"2026-04-16T02:57:19.991986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.074763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/\" range_end:\"/registry/configmaps/kube-system0\" limit:500 ","response":"range_response_count:8 size:16617"} {"level":"warn","ts":"2026-04-16T02:57:19.992029Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.704074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-public/cluster-info\" ","response":"range_response_count:1 size:2178"} {"level":"info","ts":"2026-04-16T02:57:19.992046Z","caller":"traceutil/trace.go:171","msg":"trace[214710757] range","detail":"{range_begin:/registry/configmaps/kube-system/; range_end:/registry/configmaps/kube-system0; response_count:8; response_revision:1161; }","duration":"128.149124ms","start":"2026-04-16T02:57:19.86388Z","end":"2026-04-16T02:57:19.992029Z","steps":["trace[214710757] 'agreement among raft nodes before linearized reading' (duration: 128.021863ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:19.992085Z","caller":"traceutil/trace.go:171","msg":"trace[2003881052] range","detail":"{range_begin:/registry/configmaps/kube-public/cluster-info; range_end:; response_count:1; response_revision:1161; }","duration":"124.786885ms","start":"2026-04-16T02:57:19.867282Z","end":"2026-04-16T02:57:19.992068Z","steps":["trace[2003881052] 'agreement among raft nodes before linearized reading' (duration: 124.690573ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:19.992071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.442894ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cronjobs/kube-system/\" range_end:\"/registry/cronjobs/kube-system0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:57:19.992178Z","caller":"traceutil/trace.go:171","msg":"trace[1830346811] range","detail":"{range_begin:/registry/cronjobs/kube-system/; range_end:/registry/cronjobs/kube-system0; response_count:0; response_revision:1161; }","duration":"115.577646ms","start":"2026-04-16T02:57:19.876574Z","end":"2026-04-16T02:57:19.992152Z","steps":["trace[1830346811] 'agreement among raft nodes before linearized reading' (duration: 115.371773ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:19.991927Z","caller":"traceutil/trace.go:171","msg":"trace[1313131937] range","detail":"{range_begin:/registry/configmaps/kube-public/kube-root-ca.crt; range_end:; response_count:1; response_revision:1161; }","duration":"131.789078ms","start":"2026-04-16T02:57:19.860106Z","end":"2026-04-16T02:57:19.991895Z","steps":["trace[1313131937] 'agreement among raft nodes before linearized reading' (duration: 131.656666ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:30.89601Z","caller":"traceutil/trace.go:171","msg":"trace[1606268446] linearizableReadLoop","detail":"{readStateIndex:1243; appliedIndex:1242; }","duration":"175.684687ms","start":"2026-04-16T02:57:30.720303Z","end":"2026-04-16T02:57:30.895988Z","steps":["trace[1606268446] 'read index received' (duration: 175.559865ms)","trace[1606268446] 'applied index is now lower than readState.Index' (duration: 123.792µs)"],"step_count":2} {"level":"warn","ts":"2026-04-16T02:57:30.896192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.871249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/default/\" range_end:\"/registry/pods/default0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:57:30.896083Z","caller":"traceutil/trace.go:171","msg":"trace[818437652] transaction","detail":"{read_only:false; response_revision:1195; number_of_response:1; }","duration":"230.22861ms","start":"2026-04-16T02:57:30.665831Z","end":"2026-04-16T02:57:30.896059Z","steps":["trace[818437652] 'process raft request' (duration: 230.002937ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:30.896241Z","caller":"traceutil/trace.go:171","msg":"trace[712523841] range","detail":"{range_begin:/registry/pods/default/; range_end:/registry/pods/default0; response_count:0; response_revision:1195; }","duration":"175.973721ms","start":"2026-04-16T02:57:30.720254Z","end":"2026-04-16T02:57:30.896228Z","steps":["trace[712523841] 'agreement among raft nodes before linearized reading' (duration: 175.87811ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:30.902035Z","caller":"traceutil/trace.go:171","msg":"trace[2070743794] transaction","detail":"{read_only:false; response_revision:1196; number_of_response:1; }","duration":"155.327274ms","start":"2026-04-16T02:57:30.746686Z","end":"2026-04-16T02:57:30.902014Z","steps":["trace[2070743794] 'process raft request' (duration: 155.195122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:30.902076Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.691872ms","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-04-16T02:57:30.902124Z","caller":"traceutil/trace.go:171","msg":"trace[2053817181] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1196; }","duration":"158.762764ms","start":"2026-04-16T02:57:30.743346Z","end":"2026-04-16T02:57:30.902109Z","steps":["trace[2053817181] 'agreement among raft nodes before linearized reading' (duration: 158.628272ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:31.16678Z","caller":"traceutil/trace.go:171","msg":"trace[1180507096] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"129.455645ms","start":"2026-04-16T02:57:31.037288Z","end":"2026-04-16T02:57:31.166744Z","steps":["trace[1180507096] 'process raft request' (duration: 84.873387ms)","trace[1180507096] 'compare' (duration: 44.431006ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:57:31.166966Z","caller":"traceutil/trace.go:171","msg":"trace[738923705] linearizableReadLoop","detail":"{readStateIndex:1248; appliedIndex:1246; }","duration":"106.949328ms","start":"2026-04-16T02:57:31.059984Z","end":"2026-04-16T02:57:31.166933Z","steps":["trace[738923705] 'read index received' (duration: 62.231129ms)","trace[738923705] 'applied index is now lower than readState.Index' (duration: 44.715999ms)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:57:31.167045Z","caller":"traceutil/trace.go:171","msg":"trace[406115865] transaction","detail":"{read_only:false; response_revision:1200; number_of_response:1; }","duration":"129.580456ms","start":"2026-04-16T02:57:31.037454Z","end":"2026-04-16T02:57:31.167034Z","steps":["trace[406115865] 'process raft request' (duration: 129.250213ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:31.167211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.215191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-npc68\" ","response":"range_response_count:1 size:5484"} {"level":"warn","ts":"2026-04-16T02:57:31.167207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.46889ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-node-lease/\" range_end:\"/registry/pods/kube-node-lease0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-16T02:57:31.167262Z","caller":"traceutil/trace.go:171","msg":"trace[152239000] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-npc68; range_end:; response_count:1; response_revision:1200; }","duration":"107.302323ms","start":"2026-04-16T02:57:31.059947Z","end":"2026-04-16T02:57:31.16725Z","steps":["trace[152239000] 'agreement among raft nodes before linearized reading' (duration: 107.09002ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:31.167273Z","caller":"traceutil/trace.go:171","msg":"trace[490992146] range","detail":"{range_begin:/registry/pods/kube-node-lease/; range_end:/registry/pods/kube-node-lease0; response_count:0; response_revision:1200; }","duration":"104.569351ms","start":"2026-04-16T02:57:31.062688Z","end":"2026-04-16T02:57:31.167258Z","steps":["trace[490992146] 'agreement among raft nodes before linearized reading' (duration: 104.45701ms)"],"step_count":1} {"level":"info","ts":"2026-04-16T02:57:31.7476Z","caller":"traceutil/trace.go:171","msg":"trace[1671321289] linearizableReadLoop","detail":"{readStateIndex:1249; appliedIndex:1248; }","duration":"297.608689ms","start":"2026-04-16T02:57:31.449969Z","end":"2026-04-16T02:57:31.747577Z","steps":["trace[1671321289] 'read index received' (duration: 297.368946ms)","trace[1671321289] 'applied index is now lower than readState.Index' (duration: 238.823µs)"],"step_count":2} {"level":"info","ts":"2026-04-16T02:57:31.747718Z","caller":"traceutil/trace.go:171","msg":"trace[377569257] transaction","detail":"{read_only:false; response_revision:1201; number_of_response:1; }","duration":"299.227947ms","start":"2026-04-16T02:57:31.448462Z","end":"2026-04-16T02:57:31.74769Z","steps":["trace[377569257] 'process raft request' (duration: 298.940804ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:31.747873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.168338ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8gvfn\" ","response":"range_response_count:1 size:18525"} {"level":"info","ts":"2026-04-16T02:57:31.747925Z","caller":"traceutil/trace.go:171","msg":"trace[92888065] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8gvfn; range_end:; response_count:1; response_revision:1201; }","duration":"250.26071ms","start":"2026-04-16T02:57:31.497648Z","end":"2026-04-16T02:57:31.747908Z","steps":["trace[92888065] 'agreement among raft nodes before linearized reading' (duration: 250.086398ms)"],"step_count":1} {"level":"warn","ts":"2026-04-16T02:57:31.747931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.929362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/\" range_end:\"/registry/pods/local-path-storage0\" limit:500 ","response":"range_response_count:1 size:3718"} {"level":"info","ts":"2026-04-16T02:57:31.748013Z","caller":"traceutil/trace.go:171","msg":"trace[390550543] range","detail":"{range_begin:/registry/pods/local-path-storage/; range_end:/registry/pods/local-path-storage0; response_count:1; response_revision:1201; }","duration":"298.034904ms","start":"2026-04-16T02:57:31.449957Z","end":"2026-04-16T02:57:31.747992Z","steps":["trace[390550543] 'agreement among raft nodes before linearized reading' (duration: 297.75613ms)"],"step_count":1}