{"level":"warn","ts":"2026-04-02T08:44:39.883521Z","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-02T08:44:39.883645Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.153: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.153:2380","--initial-cluster=instance=https://199.204.45.153:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.153:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.153: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-02T08:44:39.883745Z","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-02T08:44:39.883758Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-04-02T08:44:39.883784Z","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-02T08:44:39.884913Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"]} {"level":"info","ts":"2026-04-02T08:44:39.885037Z","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.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.153: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-02T08:44:39.890679Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.398681ms"} {"level":"info","ts":"2026-04-02T08:44:39.901245Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"355195d5aae85944","cluster-id":"e424441517bc0b32"} {"level":"info","ts":"2026-04-02T08:44:39.90133Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T08:44:39.901357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 0"} {"level":"info","ts":"2026-04-02T08:44:39.901372Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 355195d5aae85944 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T08:44:39.901382Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became follower at term 1"} {"level":"info","ts":"2026-04-02T08:44:39.90143Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"warn","ts":"2026-04-02T08:44:39.908544Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T08:44:39.910052Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T08:44:39.913348Z","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-02T08:44:39.915985Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"355195d5aae85944","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T08:44:39.916984Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"355195d5aae85944","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T08:44:39.917233Z","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-02T08:44:39.917285Z","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-02T08:44:39.917295Z","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-02T08:44:39.928296Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 switched to configuration voters=(3842016702051670340)"} {"level":"info","ts":"2026-04-02T08:44:39.928447Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","added-peer-id":"355195d5aae85944","added-peer-peer-urls":["https://199.204.45.153:2380"]} {"level":"info","ts":"2026-04-02T08:44:39.929194Z","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-02T08:44:39.929477Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"355195d5aae85944","initial-advertise-peer-urls":["https://199.204.45.153:2380"],"listen-peer-urls":["https://199.204.45.153:2380"],"advertise-client-urls":["https://199.204.45.153:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.153:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T08:44:39.929508Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T08:44:39.929708Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-04-02T08:44:39.929726Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.153:2380"} {"level":"info","ts":"2026-04-02T08:44:40.609885Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T08:44:40.609945Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T08:44:40.609993Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgPreVoteResp from 355195d5aae85944 at term 1"} {"level":"info","ts":"2026-04-02T08:44:40.610022Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became candidate at term 2"} {"level":"info","ts":"2026-04-02T08:44:40.610034Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 received MsgVoteResp from 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-04-02T08:44:40.610047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"355195d5aae85944 became leader at term 2"} {"level":"info","ts":"2026-04-02T08:44:40.610062Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 355195d5aae85944 elected leader 355195d5aae85944 at term 2"} {"level":"info","ts":"2026-04-02T08:44:40.611289Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"355195d5aae85944","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.153:2379]}","request-path":"/0/members/355195d5aae85944/attributes","cluster-id":"e424441517bc0b32","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T08:44:40.611305Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T08:44:40.61134Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:44:40.611508Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T08:44:40.611802Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T08:44:40.611819Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T08:44:40.612526Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"e424441517bc0b32","local-member-id":"355195d5aae85944","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:44:40.612671Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:44:40.61271Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:44:40.615188Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T08:44:40.615311Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.153:2379"} {"level":"info","ts":"2026-04-02T08:44:43.098682Z","caller":"traceutil/trace.go:171","msg":"trace[326485556] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:74; }","duration":"176.419752ms","start":"2026-04-02T08:44:42.92223Z","end":"2026-04-02T08:44:43.09865Z","steps":["trace[326485556] 'read index received' (duration: 176.110345ms)","trace[326485556] 'applied index is now lower than readState.Index' (duration: 308.077µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:44:43.09881Z","caller":"traceutil/trace.go:171","msg":"trace[1702517106] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"178.187932ms","start":"2026-04-02T08:44:42.920571Z","end":"2026-04-02T08:44:43.098759Z","steps":["trace[1702517106] 'process raft request' (duration: 177.821003ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:43.098978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.716538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T08:44:43.099062Z","caller":"traceutil/trace.go:171","msg":"trace[596885174] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:71; }","duration":"176.881842ms","start":"2026-04-02T08:44:42.922167Z","end":"2026-04-02T08:44:43.099048Z","steps":["trace[596885174] 'agreement among raft nodes before linearized reading' (duration: 176.682508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:43.099211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.917618ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-02T08:44:43.099374Z","caller":"traceutil/trace.go:171","msg":"trace[188514125] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"118.135693ms","start":"2026-04-02T08:44:42.981198Z","end":"2026-04-02T08:44:43.099333Z","steps":["trace[188514125] 'agreement among raft nodes before linearized reading' (duration: 117.893338ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:44.61045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"920.071614ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:44:44.610624Z","caller":"traceutil/trace.go:171","msg":"trace[1920341068] linearizableReadLoop","detail":"{readStateIndex:168; appliedIndex:167; }","duration":"901.479419ms","start":"2026-04-02T08:44:43.709124Z","end":"2026-04-02T08:44:44.610604Z","steps":["trace[1920341068] 'read index received' (duration: 35.131µs)","trace[1920341068] 'applied index is now lower than readState.Index' (duration: 901.443028ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:44:44.610684Z","caller":"traceutil/trace.go:171","msg":"trace[113361043] transaction","detail":"{read_only:false; response_revision:164; number_of_response:1; }","duration":"956.798073ms","start":"2026-04-02T08:44:43.65383Z","end":"2026-04-02T08:44:44.610628Z","steps":["trace[113361043] 'process raft request' (duration: 36.002043ms)","trace[113361043] 'compare' (duration: 919.914301ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:44:44.610776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"901.651773ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:44:44.610811Z","caller":"traceutil/trace.go:171","msg":"trace[523801955] range","detail":"{range_begin:/registry/limitranges/kube-system/; range_end:/registry/limitranges/kube-system0; response_count:0; response_revision:164; }","duration":"901.694514ms","start":"2026-04-02T08:44:43.709105Z","end":"2026-04-02T08:44:44.6108Z","steps":["trace[523801955] 'agreement among raft nodes before linearized reading' (duration: 901.601001ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:44.61086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:44:43.709027Z","time spent":"901.820728ms","remote":"127.0.0.1:46186","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/limitranges/kube-system/\" range_end:\"/registry/limitranges/kube-system0\" "} {"level":"warn","ts":"2026-04-02T08:44:44.610931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:44:43.653812Z","time spent":"956.996968ms","remote":"127.0.0.1:46424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":744,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T08:44:44.611151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.973906ms","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-02T08:44:44.611209Z","caller":"traceutil/trace.go:171","msg":"trace[2102366032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:0; response_revision:164; }","duration":"357.085238ms","start":"2026-04-02T08:44:44.254111Z","end":"2026-04-02T08:44:44.611196Z","steps":["trace[2102366032] 'agreement among raft nodes before linearized reading' (duration: 356.968236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:44.611167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"588.19561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T08:44:44.611241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:44:44.254086Z","time spent":"357.146809ms","remote":"127.0.0.1:46324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":0,"response size":28,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T08:44:44.611284Z","caller":"traceutil/trace.go:171","msg":"trace[1644283783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:164; }","duration":"588.379654ms","start":"2026-04-02T08:44:44.022873Z","end":"2026-04-02T08:44:44.611253Z","steps":["trace[1644283783] 'agreement among raft nodes before linearized reading' (duration: 587.959495ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:44.611336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:44:44.022846Z","time spent":"588.480506ms","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T08:44:44.611175Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.466492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:44:44.611512Z","caller":"traceutil/trace.go:171","msg":"trace[263479553] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:0; response_revision:164; }","duration":"110.807069ms","start":"2026-04-02T08:44:44.500609Z","end":"2026-04-02T08:44:44.611416Z","steps":["trace[263479553] 'agreement among raft nodes before linearized reading' (duration: 110.441651ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:44:49.018528Z","caller":"traceutil/trace.go:171","msg":"trace[333221241] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"585.311268ms","start":"2026-04-02T08:44:48.433157Z","end":"2026-04-02T08:44:49.018468Z","steps":["trace[333221241] 'process raft request' (duration: 585.132873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:49.018681Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:44:48.433121Z","time spent":"585.488822ms","remote":"127.0.0.1:46262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":201,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:44:49.019052Z","caller":"traceutil/trace.go:171","msg":"trace[681306795] linearizableReadLoop","detail":"{readStateIndex:337; appliedIndex:336; }","duration":"371.188628ms","start":"2026-04-02T08:44:48.647844Z","end":"2026-04-02T08:44:49.019033Z","steps":["trace[681306795] 'read index received' (duration: 370.34998ms)","trace[681306795] 'applied index is now lower than readState.Index' (duration: 836.978µs)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:44:49.019421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.566075ms","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-02T08:44:49.019478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.337226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T08:44:49.019626Z","caller":"traceutil/trace.go:171","msg":"trace[540072861] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:326; }","duration":"371.680948ms","start":"2026-04-02T08:44:48.647782Z","end":"2026-04-02T08:44:49.019463Z","steps":["trace[540072861] 'agreement among raft nodes before linearized reading' (duration: 371.31655ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:44:49.019626Z","caller":"traceutil/trace.go:171","msg":"trace[1612246690] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:326; }","duration":"231.507999ms","start":"2026-04-02T08:44:48.788085Z","end":"2026-04-02T08:44:49.019593Z","steps":["trace[1612246690] 'agreement among raft nodes before linearized reading' (duration: 231.347476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:49.019669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:44:48.647755Z","time spent":"371.904622ms","remote":"127.0.0.1:46324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T08:44:49.021887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.202997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T08:44:49.021955Z","caller":"traceutil/trace.go:171","msg":"trace[1863186091] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:326; }","duration":"371.3279ms","start":"2026-04-02T08:44:48.65061Z","end":"2026-04-02T08:44:49.021938Z","steps":["trace[1863186091] 'agreement among raft nodes before linearized reading' (duration: 368.778947ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:49.022265Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:44:48.650583Z","time spent":"371.539424ms","remote":"127.0.0.1:46324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T08:44:50.425869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.280690553s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:44:50.426397Z","caller":"traceutil/trace.go:171","msg":"trace[164538096] transaction","detail":"{read_only:false; response_revision:327; number_of_response:1; }","duration":"1.401289095s","start":"2026-04-02T08:44:49.024878Z","end":"2026-04-02T08:44:50.426167Z","steps":["trace[164538096] 'process raft request' (duration: 119.924537ms)","trace[164538096] 'compare' (duration: 1.28051893s)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:44:50.426605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:44:49.024853Z","time spent":"1.401625771s","remote":"127.0.0.1:46324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:44:50.426699Z","caller":"traceutil/trace.go:171","msg":"trace[943209163] transaction","detail":"{read_only:false; response_revision:328; number_of_response:1; }","duration":"1.401407887s","start":"2026-04-02T08:44:49.025257Z","end":"2026-04-02T08:44:50.426665Z","steps":["trace[943209163] 'process raft request' (duration: 1.400837855s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:50.426944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:44:49.025239Z","time spent":"1.401642161s","remote":"127.0.0.1:46324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:44:50.427438Z","caller":"traceutil/trace.go:171","msg":"trace[312752713] linearizableReadLoop","detail":"{readStateIndex:339; appliedIndex:337; }","duration":"1.401891517s","start":"2026-04-02T08:44:49.025527Z","end":"2026-04-02T08:44:50.427419Z","steps":["trace[312752713] 'read index received' (duration: 119.299574ms)","trace[312752713] 'applied index is now lower than readState.Index' (duration: 1.282590463s)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:44:50.427572Z","caller":"traceutil/trace.go:171","msg":"trace[1942921044] transaction","detail":"{read_only:false; response_revision:329; number_of_response:1; }","duration":"1.399445486s","start":"2026-04-02T08:44:49.028112Z","end":"2026-04-02T08:44:50.427558Z","steps":["trace[1942921044] 'process raft request' (duration: 1.398305703s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:50.427635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.426837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:44:50.427666Z","caller":"traceutil/trace.go:171","msg":"trace[1886793610] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:329; }","duration":"228.505088ms","start":"2026-04-02T08:44:50.199152Z","end":"2026-04-02T08:44:50.427657Z","steps":["trace[1886793610] 'agreement among raft nodes before linearized reading' (duration: 228.454107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:50.427661Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"672.32403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-04-02T08:44:50.427668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:44:49.028104Z","time spent":"1.399508897s","remote":"127.0.0.1:46324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:44:50.427713Z","caller":"traceutil/trace.go:171","msg":"trace[570740959] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:329; }","duration":"672.418502ms","start":"2026-04-02T08:44:49.75528Z","end":"2026-04-02T08:44:50.427698Z","steps":["trace[570740959] 'agreement among raft nodes before linearized reading' (duration: 672.3117ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:50.427754Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:44:49.755236Z","time spent":"672.506344ms","remote":"127.0.0.1:46324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-02T08:44:50.427748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.402204593s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" ","response":"range_response_count:1 size:216"} {"level":"info","ts":"2026-04-02T08:44:50.427809Z","caller":"traceutil/trace.go:171","msg":"trace[1398574479] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler; range_end:; response_count:1; response_revision:329; }","duration":"1.402268694s","start":"2026-04-02T08:44:49.025522Z","end":"2026-04-02T08:44:50.42779Z","steps":["trace[1398574479] 'agreement among raft nodes before linearized reading' (duration: 1.40209502s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:50.427848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:44:49.025505Z","time spent":"1.402332535s","remote":"127.0.0.1:46262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":239,"request content":"key:\"/registry/serviceaccounts/kube-system/horizontal-pod-autoscaler\" "} {"level":"warn","ts":"2026-04-02T08:44:51.508406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"967.990477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:44:51.50852Z","caller":"traceutil/trace.go:171","msg":"trace[1230364242] linearizableReadLoop","detail":"{readStateIndex:341; appliedIndex:340; }","duration":"1.072452696s","start":"2026-04-02T08:44:50.436052Z","end":"2026-04-02T08:44:51.508505Z","steps":["trace[1230364242] 'read index received' (duration: 104.49483ms)","trace[1230364242] 'applied index is now lower than readState.Index' (duration: 967.956506ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:44:51.508576Z","caller":"traceutil/trace.go:171","msg":"trace[1771645852] transaction","detail":"{read_only:false; response_revision:330; number_of_response:1; }","duration":"1.07510877s","start":"2026-04-02T08:44:50.433459Z","end":"2026-04-02T08:44:51.508568Z","steps":["trace[1771645852] 'process raft request' (duration: 106.857979ms)","trace[1771645852] 'compare' (duration: 967.361712ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:44:51.508614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:44:50.433364Z","time spent":"1.075229962s","remote":"127.0.0.1:46324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:44:51.508801Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.072749641s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/node-controller\" ","response":"range_response_count:1 size:195"} {"level":"info","ts":"2026-04-02T08:44:51.508827Z","caller":"traceutil/trace.go:171","msg":"trace[1315935400] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/node-controller; range_end:; response_count:1; response_revision:330; }","duration":"1.072790493s","start":"2026-04-02T08:44:50.436028Z","end":"2026-04-02T08:44:51.508818Z","steps":["trace[1315935400] 'agreement among raft nodes before linearized reading' (duration: 1.072736662s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:51.508844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:44:50.436019Z","time spent":"1.072821203s","remote":"127.0.0.1:46262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":218,"request content":"key:\"/registry/serviceaccounts/kube-system/node-controller\" "} {"level":"warn","ts":"2026-04-02T08:44:51.509939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"944.364372ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:44:51.510029Z","caller":"traceutil/trace.go:171","msg":"trace[690500264] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:331; }","duration":"944.510126ms","start":"2026-04-02T08:44:50.565498Z","end":"2026-04-02T08:44:51.510008Z","steps":["trace[690500264] 'agreement among raft nodes before linearized reading' (duration: 944.363773ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:51.510082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:44:50.565473Z","time spent":"944.601217ms","remote":"127.0.0.1:46034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T08:44:51.510297Z","caller":"traceutil/trace.go:171","msg":"trace[275733155] transaction","detail":"{read_only:false; response_revision:331; number_of_response:1; }","duration":"1.071838832s","start":"2026-04-02T08:44:50.438446Z","end":"2026-04-02T08:44:51.510285Z","steps":["trace[275733155] 'process raft request' (duration: 1.071272131s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:44:51.510422Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:44:50.438435Z","time spent":"1.071895473s","remote":"127.0.0.1:46240","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7288,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:45:08.654978Z","caller":"traceutil/trace.go:171","msg":"trace[654107970] linearizableReadLoop","detail":"{readStateIndex:481; appliedIndex:480; }","duration":"120.119056ms","start":"2026-04-02T08:45:08.534834Z","end":"2026-04-02T08:45:08.654953Z","steps":["trace[654107970] 'read index received' (duration: 119.950253ms)","trace[654107970] 'applied index is now lower than readState.Index' (duration: 167.703µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:08.655073Z","caller":"traceutil/trace.go:171","msg":"trace[1789288699] transaction","detail":"{read_only:false; response_revision:465; number_of_response:1; }","duration":"121.325467ms","start":"2026-04-02T08:45:08.533694Z","end":"2026-04-02T08:45:08.65502Z","steps":["trace[1789288699] 'process raft request' (duration: 121.089513ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:08.65528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.36141ms","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-02T08:45:08.655444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.388392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T08:45:08.655518Z","caller":"traceutil/trace.go:171","msg":"trace[1810939152] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:465; }","duration":"120.488813ms","start":"2026-04-02T08:45:08.534998Z","end":"2026-04-02T08:45:08.655487Z","steps":["trace[1810939152] 'agreement among raft nodes before linearized reading' (duration: 120.33197ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:08.65557Z","caller":"traceutil/trace.go:171","msg":"trace[373407369] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:465; }","duration":"120.638147ms","start":"2026-04-02T08:45:08.5348Z","end":"2026-04-02T08:45:08.655438Z","steps":["trace[373407369] 'agreement among raft nodes before linearized reading' (duration: 120.30088ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:12.991606Z","caller":"traceutil/trace.go:171","msg":"trace[1728351095] transaction","detail":"{read_only:false; response_revision:508; number_of_response:1; }","duration":"352.340111ms","start":"2026-04-02T08:45:12.639151Z","end":"2026-04-02T08:45:12.991491Z","steps":["trace[1728351095] 'process raft request' (duration: 352.169337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:12.99178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:12.639133Z","time spent":"352.573335ms","remote":"127.0.0.1:46084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":6315,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:45:13.001077Z","caller":"traceutil/trace.go:171","msg":"trace[1376197616] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"354.256923ms","start":"2026-04-02T08:45:12.646799Z","end":"2026-04-02T08:45:13.001056Z","steps":["trace[1376197616] 'process raft request' (duration: 354.082391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:13.001182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:12.646788Z","time spent":"354.351285ms","remote":"127.0.0.1:46084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8162,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:45:13.001399Z","caller":"traceutil/trace.go:171","msg":"trace[1778521545] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"313.385397ms","start":"2026-04-02T08:45:12.687972Z","end":"2026-04-02T08:45:13.001357Z","steps":["trace[1778521545] 'process raft request' (duration: 313.231514ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:13.001485Z","caller":"traceutil/trace.go:171","msg":"trace[550404614] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"332.929425ms","start":"2026-04-02T08:45:12.668542Z","end":"2026-04-02T08:45:13.001472Z","steps":["trace[550404614] 'process raft request' (duration: 332.488367ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:13.001414Z","caller":"traceutil/trace.go:171","msg":"trace[279032885] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"322.843101ms","start":"2026-04-02T08:45:12.678487Z","end":"2026-04-02T08:45:13.00133Z","steps":["trace[279032885] 'process raft request' (duration: 322.640367ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:13.001673Z","caller":"traceutil/trace.go:171","msg":"trace[281834194] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"325.253462ms","start":"2026-04-02T08:45:12.6764Z","end":"2026-04-02T08:45:13.001654Z","steps":["trace[281834194] 'process raft request' (duration: 324.695972ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:13.001687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:12.668534Z","time spent":"332.982406ms","remote":"127.0.0.1:46084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:45:13.001714Z","caller":"traceutil/trace.go:171","msg":"trace[2141996273] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"312.288088ms","start":"2026-04-02T08:45:12.689418Z","end":"2026-04-02T08:45:13.001706Z","steps":["trace[2141996273] 'process raft request' (duration: 311.821791ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:13.001733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:12.67639Z","time spent":"325.321194ms","remote":"127.0.0.1:46084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:45:13.001746Z","caller":"traceutil/trace.go:171","msg":"trace[563054192] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"293.912641ms","start":"2026-04-02T08:45:12.707827Z","end":"2026-04-02T08:45:13.001739Z","steps":["trace[563054192] 'process raft request' (duration: 293.491784ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:13.001788Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:12.689397Z","time spent":"312.345259ms","remote":"127.0.0.1:46084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T08:45:13.001683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.104229ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T08:45:13.001877Z","caller":"traceutil/trace.go:171","msg":"trace[858448313] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"192.775442ms","start":"2026-04-02T08:45:12.809095Z","end":"2026-04-02T08:45:13.001871Z","steps":["trace[858448313] 'process raft request' (duration: 192.361645ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:13.001536Z","caller":"traceutil/trace.go:171","msg":"trace[1478869857] linearizableReadLoop","detail":"{readStateIndex:531; appliedIndex:524; }","duration":"324.957727ms","start":"2026-04-02T08:45:12.676558Z","end":"2026-04-02T08:45:13.001516Z","steps":["trace[1478869857] 'read index received' (duration: 314.804831ms)","trace[1478869857] 'applied index is now lower than readState.Index' (duration: 10.151816ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:13.00161Z","caller":"traceutil/trace.go:171","msg":"trace[1046390706] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"354.755152ms","start":"2026-04-02T08:45:12.646844Z","end":"2026-04-02T08:45:13.001599Z","steps":["trace[1046390706] 'process raft request' (duration: 354.125211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:13.001963Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:12.646838Z","time spent":"355.100858ms","remote":"127.0.0.1:46084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:45:13.001481Z","caller":"traceutil/trace.go:171","msg":"trace[696594232] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"340.319643ms","start":"2026-04-02T08:45:12.661103Z","end":"2026-04-02T08:45:13.001423Z","steps":["trace[696594232] 'process raft request' (duration: 339.893355ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:13.001878Z","caller":"traceutil/trace.go:171","msg":"trace[1876717271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:519; }","duration":"325.325983ms","start":"2026-04-02T08:45:12.676539Z","end":"2026-04-02T08:45:13.001865Z","steps":["trace[1876717271] 'agreement among raft nodes before linearized reading' (duration: 325.062749ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:13.002108Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:12.661092Z","time spent":"340.976403ms","remote":"127.0.0.1:46084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T08:45:13.002127Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:12.676534Z","time spent":"325.583267ms","remote":"127.0.0.1:46324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T08:45:13.002047Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:12.67848Z","time spent":"323.276848ms","remote":"127.0.0.1:46084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29820,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T08:45:13.001601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:12.687959Z","time spent":"313.53071ms","remote":"127.0.0.1:46084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":63889,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:45:13.002394Z","caller":"traceutil/trace.go:171","msg":"trace[326427582] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"333.614256ms","start":"2026-04-02T08:45:12.668737Z","end":"2026-04-02T08:45:13.002351Z","steps":["trace[326427582] 'process raft request' (duration: 332.334334ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:13.002472Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:12.668733Z","time spent":"333.704148ms","remote":"127.0.0.1:46084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T08:45:13.009952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.345561ms","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-02T08:45:13.009992Z","caller":"traceutil/trace.go:171","msg":"trace[625612928] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:521; }","duration":"333.396932ms","start":"2026-04-02T08:45:12.676585Z","end":"2026-04-02T08:45:13.009982Z","steps":["trace[625612928] 'agreement among raft nodes before linearized reading' (duration: 333.31425ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:13.010014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:12.676583Z","time spent":"333.426992ms","remote":"127.0.0.1:46324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T08:45:13.011503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"334.825706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T08:45:13.011534Z","caller":"traceutil/trace.go:171","msg":"trace[529472235] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:521; }","duration":"334.863817ms","start":"2026-04-02T08:45:12.676663Z","end":"2026-04-02T08:45:13.011527Z","steps":["trace[529472235] 'agreement among raft nodes before linearized reading' (duration: 333.434622ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:13.011555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:12.67666Z","time spent":"334.891197ms","remote":"127.0.0.1:46324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-02T08:45:14.192707Z","caller":"traceutil/trace.go:171","msg":"trace[421652840] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"284.896478ms","start":"2026-04-02T08:45:13.907795Z","end":"2026-04-02T08:45:14.192692Z","steps":["trace[421652840] 'process raft request' (duration: 284.289358ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:14.689329Z","caller":"traceutil/trace.go:171","msg":"trace[271958227] transaction","detail":"{read_only:false; response_revision:582; number_of_response:1; }","duration":"686.674726ms","start":"2026-04-02T08:45:14.00263Z","end":"2026-04-02T08:45:14.689305Z","steps":["trace[271958227] 'process raft request' (duration: 677.685633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:14.689464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:14.002599Z","time spent":"686.813818ms","remote":"127.0.0.1:46084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":207462,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-02T08:45:14.689918Z","caller":"traceutil/trace.go:171","msg":"trace[865576757] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"453.643423ms","start":"2026-04-02T08:45:14.236262Z","end":"2026-04-02T08:45:14.689906Z","steps":["trace[865576757] 'process raft request' (duration: 453.358057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:14.689997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:14.236244Z","time spent":"453.710535ms","remote":"127.0.0.1:46084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":15664,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:45:14.690605Z","caller":"traceutil/trace.go:171","msg":"trace[621230973] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"292.624338ms","start":"2026-04-02T08:45:14.397968Z","end":"2026-04-02T08:45:14.690592Z","steps":["trace[621230973] 'process raft request' (duration: 292.11649ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:19.296559Z","caller":"traceutil/trace.go:171","msg":"trace[1214293541] transaction","detail":"{read_only:false; response_revision:617; number_of_response:1; }","duration":"130.677304ms","start":"2026-04-02T08:45:19.165849Z","end":"2026-04-02T08:45:19.296526Z","steps":["trace[1214293541] 'process raft request' (duration: 130.518692ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:19.908824Z","caller":"traceutil/trace.go:171","msg":"trace[15417416] linearizableReadLoop","detail":"{readStateIndex:639; appliedIndex:638; }","duration":"223.383402ms","start":"2026-04-02T08:45:19.685407Z","end":"2026-04-02T08:45:19.90879Z","steps":["trace[15417416] 'read index received' (duration: 223.24827ms)","trace[15417416] 'applied index is now lower than readState.Index' (duration: 133.842µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:19.908886Z","caller":"traceutil/trace.go:171","msg":"trace[127144733] transaction","detail":"{read_only:false; response_revision:618; number_of_response:1; }","duration":"248.18151ms","start":"2026-04-02T08:45:19.660667Z","end":"2026-04-02T08:45:19.908849Z","steps":["trace[127144733] 'process raft request' (duration: 247.907566ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:19.909057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.631596ms","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-02T08:45:19.909128Z","caller":"traceutil/trace.go:171","msg":"trace[649168103] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:618; }","duration":"223.749117ms","start":"2026-04-02T08:45:19.685361Z","end":"2026-04-02T08:45:19.90911Z","steps":["trace[649168103] 'agreement among raft nodes before linearized reading' (duration: 223.543434ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:20.40905Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":6432439123402056209,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T08:45:21.299578Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.497111ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:45:21.299877Z","caller":"traceutil/trace.go:171","msg":"trace[378540498] transaction","detail":"{read_only:false; response_revision:619; number_of_response:1; }","duration":"1.604888816s","start":"2026-04-02T08:45:19.694919Z","end":"2026-04-02T08:45:21.299808Z","steps":["trace[378540498] 'process raft request' (duration: 1.11707479s)","trace[378540498] 'compare' (duration: 487.065324ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:21.299927Z","caller":"traceutil/trace.go:171","msg":"trace[348356935] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"1.383309374s","start":"2026-04-02T08:45:19.916571Z","end":"2026-04-02T08:45:21.29988Z","steps":["trace[348356935] 'process raft request' (duration: 1.38312189s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:21.300142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:19.694887Z","time spent":"1.605108s","remote":"127.0.0.1:46134","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036289,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:45:21.300205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:19.916551Z","time spent":"1.383490738s","remote":"127.0.0.1:46324","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:45:21.429607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.837262ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-02T08:45:21.429956Z","caller":"traceutil/trace.go:171","msg":"trace[1341464510] linearizableReadLoop","detail":"{readStateIndex:642; appliedIndex:639; }","duration":"1.521034806s","start":"2026-04-02T08:45:19.908906Z","end":"2026-04-02T08:45:21.429941Z","steps":["trace[1341464510] 'read index received' (duration: 902.86401ms)","trace[1341464510] 'applied index is now lower than readState.Index' (duration: 618.170156ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:21.430184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.636066742s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:21.430207Z","caller":"traceutil/trace.go:171","msg":"trace[837377692] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"1.636119593s","start":"2026-04-02T08:45:19.79408Z","end":"2026-04-02T08:45:21.430199Z","steps":["trace[837377692] 'agreement among raft nodes before linearized reading' (duration: 1.636050192s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:21.43023Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:19.794065Z","time spent":"1.636159844s","remote":"127.0.0.1:46034","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T08:45:21.430331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.36490893s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:45:21.430349Z","caller":"traceutil/trace.go:171","msg":"trace[1532903179] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:621; }","duration":"1.364963141s","start":"2026-04-02T08:45:20.06538Z","end":"2026-04-02T08:45:21.430343Z","steps":["trace[1532903179] 'agreement among raft nodes before linearized reading' (duration: 1.3649356s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:21.430365Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:20.065358Z","time spent":"1.365003271s","remote":"127.0.0.1:46038","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-02T08:45:21.430583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.700559ms","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-02T08:45:21.430605Z","caller":"traceutil/trace.go:171","msg":"trace[927377493] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:621; }","duration":"373.741089ms","start":"2026-04-02T08:45:21.056857Z","end":"2026-04-02T08:45:21.430598Z","steps":["trace[927377493] 'agreement among raft nodes before linearized reading' (duration: 373.625867ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:21.430625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:21.056847Z","time spent":"373.77284ms","remote":"127.0.0.1:46324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T08:45:21.430895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.899805ms","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-02T08:45:21.430919Z","caller":"traceutil/trace.go:171","msg":"trace[303321271] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:621; }","duration":"380.958617ms","start":"2026-04-02T08:45:21.049954Z","end":"2026-04-02T08:45:21.430913Z","steps":["trace[303321271] 'agreement among raft nodes before linearized reading' (duration: 380.892796ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:21.430936Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:21.04991Z","time spent":"381.021627ms","remote":"127.0.0.1:46324","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T08:45:21.431692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"374.905269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-02T08:45:21.431808Z","caller":"traceutil/trace.go:171","msg":"trace[39027072] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:621; }","duration":"375.683171ms","start":"2026-04-02T08:45:21.056098Z","end":"2026-04-02T08:45:21.431781Z","steps":["trace[39027072] 'agreement among raft nodes before linearized reading' (duration: 374.904448ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:21.432501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:21.056086Z","time spent":"376.380382ms","remote":"127.0.0.1:46324","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-02T08:45:23.036247Z","caller":"traceutil/trace.go:171","msg":"trace[1027057291] linearizableReadLoop","detail":"{readStateIndex:667; appliedIndex:666; }","duration":"137.378857ms","start":"2026-04-02T08:45:22.898852Z","end":"2026-04-02T08:45:23.036231Z","steps":["trace[1027057291] 'read index received' (duration: 137.235695ms)","trace[1027057291] 'applied index is now lower than readState.Index' (duration: 142.282µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:23.036294Z","caller":"traceutil/trace.go:171","msg":"trace[1659374777] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"231.780164ms","start":"2026-04-02T08:45:22.804491Z","end":"2026-04-02T08:45:23.036271Z","steps":["trace[1659374777] 'process raft request' (duration: 231.630471ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:23.036436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.5581ms","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-02T08:45:23.03649Z","caller":"traceutil/trace.go:171","msg":"trace[763515703] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:645; }","duration":"137.645462ms","start":"2026-04-02T08:45:22.89882Z","end":"2026-04-02T08:45:23.036466Z","steps":["trace[763515703] 'agreement among raft nodes before linearized reading' (duration: 137.5333ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:23.03653Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.56185ms","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":"info","ts":"2026-04-02T08:45:23.036575Z","caller":"traceutil/trace.go:171","msg":"trace[1760778995] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:645; }","duration":"137.686262ms","start":"2026-04-02T08:45:22.898881Z","end":"2026-04-02T08:45:23.036567Z","steps":["trace[1760778995] 'agreement among raft nodes before linearized reading' (duration: 137.55591ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:25.958339Z","caller":"traceutil/trace.go:171","msg":"trace[404994346] transaction","detail":"{read_only:false; response_revision:696; number_of_response:1; }","duration":"105.254399ms","start":"2026-04-02T08:45:25.853036Z","end":"2026-04-02T08:45:25.95829Z","steps":["trace[404994346] 'process raft request' (duration: 105.131367ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:33.723797Z","caller":"traceutil/trace.go:171","msg":"trace[822355671] linearizableReadLoop","detail":"{readStateIndex:789; appliedIndex:788; }","duration":"195.748159ms","start":"2026-04-02T08:45:33.528022Z","end":"2026-04-02T08:45:33.72377Z","steps":["trace[822355671] 'read index received' (duration: 158.077299ms)","trace[822355671] 'applied index is now lower than readState.Index' (duration: 37.66931ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:45:33.724044Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.000912ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T08:45:33.724084Z","caller":"traceutil/trace.go:171","msg":"trace[1299377685] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:764; }","duration":"196.073184ms","start":"2026-04-02T08:45:33.528001Z","end":"2026-04-02T08:45:33.724074Z","steps":["trace[1299377685] 'agreement among raft nodes before linearized reading' (duration: 195.878601ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:33.72426Z","caller":"traceutil/trace.go:171","msg":"trace[1960132171] transaction","detail":"{read_only:false; response_revision:765; number_of_response:1; }","duration":"146.944232ms","start":"2026-04-02T08:45:33.577302Z","end":"2026-04-02T08:45:33.724247Z","steps":["trace[1960132171] 'process raft request' (duration: 146.879521ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:45:33.724445Z","caller":"traceutil/trace.go:171","msg":"trace[485544580] transaction","detail":"{read_only:false; response_revision:764; number_of_response:1; }","duration":"197.565596ms","start":"2026-04-02T08:45:33.526867Z","end":"2026-04-02T08:45:33.724433Z","steps":["trace[485544580] 'process raft request' (duration: 159.238128ms)","trace[485544580] 'compare' (duration: 37.111561ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:37.158576Z","caller":"traceutil/trace.go:171","msg":"trace[1048635499] linearizableReadLoop","detail":"{readStateIndex:808; appliedIndex:807; }","duration":"150.759478ms","start":"2026-04-02T08:45:37.007798Z","end":"2026-04-02T08:45:37.158557Z","steps":["trace[1048635499] 'read index received' (duration: 150.539905ms)","trace[1048635499] 'applied index is now lower than readState.Index' (duration: 218.783µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:45:37.158715Z","caller":"traceutil/trace.go:171","msg":"trace[446319459] transaction","detail":"{read_only:false; response_revision:782; number_of_response:1; }","duration":"398.914675ms","start":"2026-04-02T08:45:36.759792Z","end":"2026-04-02T08:45:37.158706Z","steps":["trace[446319459] 'process raft request' (duration: 398.622031ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:45:37.158808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:45:36.759769Z","time spent":"398.967665ms","remote":"127.0.0.1:46284","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:45:37.159027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.226754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-h442z\" ","response":"range_response_count:1 size:18713"} {"level":"info","ts":"2026-04-02T08:45:37.159054Z","caller":"traceutil/trace.go:171","msg":"trace[672138706] range","detail":"{range_begin:/registry/pods/kube-system/cilium-h442z; range_end:; response_count:1; response_revision:782; }","duration":"151.286255ms","start":"2026-04-02T08:45:37.007761Z","end":"2026-04-02T08:45:37.159047Z","steps":["trace[672138706] 'agreement among raft nodes before linearized reading' (duration: 151.135533ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:08.310903Z","caller":"traceutil/trace.go:171","msg":"trace[768394408] transaction","detail":"{read_only:false; response_revision:1225; number_of_response:1; }","duration":"112.985932ms","start":"2026-04-02T08:47:08.197894Z","end":"2026-04-02T08:47:08.31088Z","steps":["trace[768394408] 'process raft request' (duration: 84.316043ms)","trace[768394408] 'compare' (duration: 28.432167ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:47:08.310966Z","caller":"traceutil/trace.go:171","msg":"trace[1404797530] transaction","detail":"{read_only:false; response_revision:1226; number_of_response:1; }","duration":"110.874589ms","start":"2026-04-02T08:47:08.200074Z","end":"2026-04-02T08:47:08.310949Z","steps":["trace[1404797530] 'process raft request' (duration: 110.689527ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:16.334073Z","caller":"traceutil/trace.go:171","msg":"trace[494545324] transaction","detail":"{read_only:false; response_revision:1253; number_of_response:1; }","duration":"206.998389ms","start":"2026-04-02T08:47:16.127051Z","end":"2026-04-02T08:47:16.33405Z","steps":["trace[494545324] 'process raft request' (duration: 206.809046ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:16.365853Z","caller":"traceutil/trace.go:171","msg":"trace[622468863] transaction","detail":"{read_only:false; response_revision:1254; number_of_response:1; }","duration":"156.49874ms","start":"2026-04-02T08:47:16.209323Z","end":"2026-04-02T08:47:16.365822Z","steps":["trace[622468863] 'process raft request' (duration: 156.309598ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:16.511546Z","caller":"traceutil/trace.go:171","msg":"trace[1802239217] transaction","detail":"{read_only:false; response_revision:1258; number_of_response:1; }","duration":"140.012374ms","start":"2026-04-02T08:47:16.371493Z","end":"2026-04-02T08:47:16.511505Z","steps":["trace[1802239217] 'process raft request' (duration: 139.936023ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:16.511682Z","caller":"traceutil/trace.go:171","msg":"trace[735559909] transaction","detail":"{read_only:false; response_revision:1257; number_of_response:1; }","duration":"140.234715ms","start":"2026-04-02T08:47:16.371408Z","end":"2026-04-02T08:47:16.511643Z","steps":["trace[735559909] 'process raft request' (duration: 53.45511ms)","trace[735559909] 'compare' (duration: 86.394831ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:47:16.756825Z","caller":"traceutil/trace.go:171","msg":"trace[122733902] linearizableReadLoop","detail":"{readStateIndex:1312; appliedIndex:1311; }","duration":"154.786151ms","start":"2026-04-02T08:47:16.602015Z","end":"2026-04-02T08:47:16.756802Z","steps":["trace[122733902] 'read index received' (duration: 130.32186ms)","trace[122733902] 'applied index is now lower than readState.Index' (duration: 24.463281ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:47:16.756966Z","caller":"traceutil/trace.go:171","msg":"trace[1012589270] transaction","detail":"{read_only:false; response_revision:1262; number_of_response:1; }","duration":"160.130809ms","start":"2026-04-02T08:47:16.596796Z","end":"2026-04-02T08:47:16.756926Z","steps":["trace[1012589270] 'process raft request' (duration: 135.560546ms)","trace[1012589270] 'compare' (duration: 24.331459ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:47:16.757001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.966703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bd0b20f3-ccf2-4bd0-aecb-6bbfd90d2943\" ","response":"range_response_count:1 size:3579"} {"level":"info","ts":"2026-04-02T08:47:16.75715Z","caller":"traceutil/trace.go:171","msg":"trace[168842312] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bd0b20f3-ccf2-4bd0-aecb-6bbfd90d2943; range_end:; response_count:1; response_revision:1262; }","duration":"155.152075ms","start":"2026-04-02T08:47:16.601985Z","end":"2026-04-02T08:47:16.757137Z","steps":["trace[168842312] 'agreement among raft nodes before linearized reading' (duration: 154.958913ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:32.601337Z","caller":"traceutil/trace.go:171","msg":"trace[1701535095] transaction","detail":"{read_only:false; response_revision:1342; number_of_response:1; }","duration":"160.20901ms","start":"2026-04-02T08:47:32.441098Z","end":"2026-04-02T08:47:32.601307Z","steps":["trace[1701535095] 'process raft request' (duration: 142.989661ms)","trace[1701535095] 'compare' (duration: 16.895246ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:47:50.232994Z","caller":"traceutil/trace.go:171","msg":"trace[2044609833] transaction","detail":"{read_only:false; response_revision:1402; number_of_response:1; }","duration":"172.158538ms","start":"2026-04-02T08:47:50.060813Z","end":"2026-04-02T08:47:50.232972Z","steps":["trace[2044609833] 'process raft request' (duration: 172.042677ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:50.271591Z","caller":"traceutil/trace.go:171","msg":"trace[885927538] transaction","detail":"{read_only:false; response_revision:1403; number_of_response:1; }","duration":"157.517169ms","start":"2026-04-02T08:47:50.114026Z","end":"2026-04-02T08:47:50.271543Z","steps":["trace[885927538] 'process raft request' (duration: 157.332837ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:50.359863Z","caller":"traceutil/trace.go:171","msg":"trace[1918993325] linearizableReadLoop","detail":"{readStateIndex:1461; appliedIndex:1459; }","duration":"173.453662ms","start":"2026-04-02T08:47:50.186389Z","end":"2026-04-02T08:47:50.359842Z","steps":["trace[1918993325] 'read index received' (duration: 46.838568ms)","trace[1918993325] 'applied index is now lower than readState.Index' (duration: 126.614074ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:47:50.360025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.621083ms","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-02T08:47:50.360067Z","caller":"traceutil/trace.go:171","msg":"trace[1230143720] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1403; }","duration":"173.713675ms","start":"2026-04-02T08:47:50.186345Z","end":"2026-04-02T08:47:50.360058Z","steps":["trace[1230143720] 'agreement among raft nodes before linearized reading' (duration: 173.571953ms)"],"step_count":1}