{"level":"warn","ts":"2026-04-06T18:38:12.869363Z","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-06T18:38:12.869498Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.22: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.22:2380","--initial-cluster=instance=https://199.204.45.22:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.22:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.22: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-06T18:38:12.870692Z","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-06T18:38:12.870716Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-06T18:38:12.870878Z","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-06T18:38:12.872272Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"]} {"level":"info","ts":"2026-04-06T18:38:12.872456Z","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.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.22: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-06T18:38:12.876288Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.570431ms"} {"level":"info","ts":"2026-04-06T18:38:12.88409Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fed19d9291630e09","cluster-id":"6ebf1e01320cc017"} {"level":"info","ts":"2026-04-06T18:38:12.884259Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=()"} {"level":"info","ts":"2026-04-06T18:38:12.884294Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 0"} {"level":"info","ts":"2026-04-06T18:38:12.884307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fed19d9291630e09 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-06T18:38:12.884321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became follower at term 1"} {"level":"info","ts":"2026-04-06T18:38:12.884433Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"warn","ts":"2026-04-06T18:38:12.894151Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-06T18:38:12.895417Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-06T18:38:12.896494Z","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-06T18:38:12.898492Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fed19d9291630e09","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-06T18:38:12.898631Z","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-06T18:38:12.898739Z","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-06T18:38:12.89875Z","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-06T18:38:12.899864Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fed19d9291630e09","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-06T18:38:12.900491Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 switched to configuration voters=(18361630408596196873)"} {"level":"info","ts":"2026-04-06T18:38:12.900712Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","added-peer-id":"fed19d9291630e09","added-peer-peer-urls":["https://199.204.45.22:2380"]} {"level":"info","ts":"2026-04-06T18:38:12.905781Z","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-06T18:38:12.905889Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-06T18:38:12.905957Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.22:2380"} {"level":"info","ts":"2026-04-06T18:38:12.906158Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fed19d9291630e09","initial-advertise-peer-urls":["https://199.204.45.22:2380"],"listen-peer-urls":["https://199.204.45.22:2380"],"advertise-client-urls":["https://199.204.45.22:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.22:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-06T18:38:12.906225Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-06T18:38:13.185315Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 is starting a new election at term 1"} {"level":"info","ts":"2026-04-06T18:38:13.185453Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-06T18:38:13.185485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgPreVoteResp from fed19d9291630e09 at term 1"} {"level":"info","ts":"2026-04-06T18:38:13.185507Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became candidate at term 2"} {"level":"info","ts":"2026-04-06T18:38:13.185518Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 received MsgVoteResp from fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-04-06T18:38:13.185534Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fed19d9291630e09 became leader at term 2"} {"level":"info","ts":"2026-04-06T18:38:13.185552Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fed19d9291630e09 elected leader fed19d9291630e09 at term 2"} {"level":"info","ts":"2026-04-06T18:38:13.187125Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fed19d9291630e09","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.22:2379]}","request-path":"/0/members/fed19d9291630e09/attributes","cluster-id":"6ebf1e01320cc017","publish-timeout":"7s"} {"level":"info","ts":"2026-04-06T18:38:13.189367Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T18:38:13.189982Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T18:38:13.190178Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-06T18:38:13.190908Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6ebf1e01320cc017","local-member-id":"fed19d9291630e09","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T18:38:13.191045Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T18:38:13.19108Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-06T18:38:13.192104Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-06T18:38:13.192136Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-06T18:38:13.194718Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.22:2379"} {"level":"info","ts":"2026-04-06T18:38:13.195779Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-06T18:38:14.80576Z","caller":"traceutil/trace.go:171","msg":"trace[1820679146] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"122.108467ms","start":"2026-04-06T18:38:14.683628Z","end":"2026-04-06T18:38:14.805737Z","steps":["trace[1820679146] 'process raft request' (duration: 121.75338ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:38:14.805864Z","caller":"traceutil/trace.go:171","msg":"trace[345171203] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"112.122483ms","start":"2026-04-06T18:38:14.693584Z","end":"2026-04-06T18:38:14.805706Z","steps":["trace[345171203] 'read index received' (duration: 111.770297ms)","trace[345171203] 'applied index is now lower than readState.Index' (duration: 351.146µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T18:38:14.805777Z","caller":"traceutil/trace.go:171","msg":"trace[440248197] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"122.094747ms","start":"2026-04-06T18:38:14.683662Z","end":"2026-04-06T18:38:14.805756Z","steps":["trace[440248197] 'process raft request' (duration: 121.990925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:14.806085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.465101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T18:38:14.806225Z","caller":"traceutil/trace.go:171","msg":"trace[146857893] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:3; }","duration":"112.629554ms","start":"2026-04-06T18:38:14.69357Z","end":"2026-04-06T18:38:14.8062Z","steps":["trace[146857893] 'agreement among raft nodes before linearized reading' (duration: 112.332478ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:38:15.120588Z","caller":"traceutil/trace.go:171","msg":"trace[2142035114] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:6; }","duration":"314.633455ms","start":"2026-04-06T18:38:14.805931Z","end":"2026-04-06T18:38:15.120565Z","steps":["trace[2142035114] 'read index received' (duration: 314.215717ms)","trace[2142035114] 'applied index is now lower than readState.Index' (duration: 416.868µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T18:38:15.120603Z","caller":"traceutil/trace.go:171","msg":"trace[1164103761] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"362.198687ms","start":"2026-04-06T18:38:14.758344Z","end":"2026-04-06T18:38:15.120543Z","steps":["trace[1164103761] 'process raft request' (duration: 362.029804ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:38:15.120719Z","caller":"traceutil/trace.go:171","msg":"trace[1891418984] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"343.814632ms","start":"2026-04-06T18:38:14.776893Z","end":"2026-04-06T18:38:15.120707Z","steps":["trace[1891418984] 'process raft request' (duration: 343.641408ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:38:15.120803Z","caller":"traceutil/trace.go:171","msg":"trace[147135657] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"364.183816ms","start":"2026-04-06T18:38:14.756571Z","end":"2026-04-06T18:38:15.120755Z","steps":["trace[147135657] 'process raft request' (duration: 363.594304ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:15.12089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.662344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-06T18:38:15.120847Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.743728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-06T18:38:15.120956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.054601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T18:38:15.120972Z","caller":"traceutil/trace.go:171","msg":"trace[1391126066] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"362.924511ms","start":"2026-04-06T18:38:14.758025Z","end":"2026-04-06T18:38:15.12095Z","steps":["trace[1391126066] 'agreement among raft nodes before linearized reading' (duration: 362.734868ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:38:15.12099Z","caller":"traceutil/trace.go:171","msg":"trace[395576271] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:0; response_revision:11; }","duration":"367.115423ms","start":"2026-04-06T18:38:14.753866Z","end":"2026-04-06T18:38:15.120981Z","steps":["trace[395576271] 'agreement among raft nodes before linearized reading' (duration: 367.071622ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:38:15.120879Z","caller":"traceutil/trace.go:171","msg":"trace[2146091608] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"362.34129ms","start":"2026-04-06T18:38:14.758463Z","end":"2026-04-06T18:38:15.120805Z","steps":["trace[2146091608] 'process raft request' (duration: 362.007874ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:15.121106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.472903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T18:38:15.121137Z","caller":"traceutil/trace.go:171","msg":"trace[721854656] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"313.582925ms","start":"2026-04-06T18:38:14.807547Z","end":"2026-04-06T18:38:15.12113Z","steps":["trace[721854656] 'agreement among raft nodes before linearized reading' (duration: 313.540764ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:38:15.121134Z","caller":"traceutil/trace.go:171","msg":"trace[1716915783] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"362.191457ms","start":"2026-04-06T18:38:14.758374Z","end":"2026-04-06T18:38:15.120565Z","steps":["trace[1716915783] 'process raft request' (duration: 362.036984ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:15.121168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:14.807508Z","time spent":"313.655136ms","remote":"127.0.0.1:34682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-06T18:38:15.121205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:14.758333Z","time spent":"362.36628ms","remote":"127.0.0.1:35114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T18:38:15.12126Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:14.758342Z","time spent":"362.84373ms","remote":"127.0.0.1:35114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T18:38:15.121352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.487345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T18:38:15.121404Z","caller":"traceutil/trace.go:171","msg":"trace[1219416565] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"293.607498ms","start":"2026-04-06T18:38:14.827785Z","end":"2026-04-06T18:38:15.121393Z","steps":["trace[1219416565] 'agreement among raft nodes before linearized reading' (duration: 293.461235ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:38:15.12141Z","caller":"traceutil/trace.go:171","msg":"trace[367340646] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"347.650905ms","start":"2026-04-06T18:38:14.773746Z","end":"2026-04-06T18:38:15.121397Z","steps":["trace[367340646] 'process raft request' (duration: 346.753428ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:15.121419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"294.130087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T18:38:15.121451Z","caller":"traceutil/trace.go:171","msg":"trace[369551236] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"294.203629ms","start":"2026-04-06T18:38:14.827239Z","end":"2026-04-06T18:38:15.121442Z","steps":["trace[369551236] 'agreement among raft nodes before linearized reading' (duration: 294.154808ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:15.121463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:14.773733Z","time spent":"347.707097ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3158,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T18:38:15.121377Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:14.758438Z","time spent":"362.687487ms","remote":"127.0.0.1:35114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T18:38:15.121564Z","caller":"traceutil/trace.go:171","msg":"trace[1285697169] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"362.114256ms","start":"2026-04-06T18:38:14.758439Z","end":"2026-04-06T18:38:15.120553Z","steps":["trace[1285697169] 'process raft request' (duration: 361.997483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:15.121036Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:14.758014Z","time spent":"362.988022ms","remote":"127.0.0.1:34654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-06T18:38:15.121645Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:14.75841Z","time spent":"363.187597ms","remote":"127.0.0.1:35114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T18:38:15.121679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.999519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-06T18:38:15.12172Z","caller":"traceutil/trace.go:171","msg":"trace[204585620] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"288.06938ms","start":"2026-04-06T18:38:14.833641Z","end":"2026-04-06T18:38:15.12171Z","steps":["trace[204585620] 'agreement among raft nodes before linearized reading' (duration: 288.014909ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:38:15.120921Z","caller":"traceutil/trace.go:171","msg":"trace[585750997] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"366.752745ms","start":"2026-04-06T18:38:14.754155Z","end":"2026-04-06T18:38:15.120908Z","steps":["trace[585750997] 'agreement among raft nodes before linearized reading' (duration: 366.716395ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:15.121768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:14.754121Z","time spent":"367.638353ms","remote":"127.0.0.1:34682","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-06T18:38:15.121804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:14.77687Z","time spent":"343.876762ms","remote":"127.0.0.1:34850","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":845,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T18:38:15.121567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.29456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3170"} {"level":"info","ts":"2026-04-06T18:38:15.121953Z","caller":"traceutil/trace.go:171","msg":"trace[1560154296] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"312.729058ms","start":"2026-04-06T18:38:14.809211Z","end":"2026-04-06T18:38:15.12194Z","steps":["trace[1560154296] 'agreement among raft nodes before linearized reading' (duration: 312.31587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:15.122Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:14.809199Z","time spent":"312.786219ms","remote":"127.0.0.1:34750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3193,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-06T18:38:15.121533Z","caller":"traceutil/trace.go:171","msg":"trace[759434460] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"363.40289ms","start":"2026-04-06T18:38:14.758118Z","end":"2026-04-06T18:38:15.121521Z","steps":["trace[759434460] 'process raft request' (duration: 362.220658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:15.122132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:14.756538Z","time spent":"364.37237ms","remote":"127.0.0.1:35022","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T18:38:15.122182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:14.758091Z","time spent":"364.029513ms","remote":"127.0.0.1:35114","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T18:38:15.121029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:14.753853Z","time spent":"367.168574ms","remote":"127.0.0.1:34566","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.204.45.22\" "} {"level":"info","ts":"2026-04-06T18:38:43.85283Z","caller":"traceutil/trace.go:171","msg":"trace[160326182] transaction","detail":"{read_only:false; response_revision:466; number_of_response:1; }","duration":"302.953451ms","start":"2026-04-06T18:38:43.549845Z","end":"2026-04-06T18:38:43.852799Z","steps":["trace[160326182] 'process raft request' (duration: 302.808079ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:43.852996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:43.549824Z","time spent":"303.093333ms","remote":"127.0.0.1:34590","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-06T18:38:43.911168Z","caller":"traceutil/trace.go:171","msg":"trace[2086389042] transaction","detail":"{read_only:false; response_revision:467; number_of_response:1; }","duration":"361.262648ms","start":"2026-04-06T18:38:43.549881Z","end":"2026-04-06T18:38:43.911144Z","steps":["trace[2086389042] 'process raft request' (duration: 360.983534ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:38:43.911278Z","caller":"traceutil/trace.go:171","msg":"trace[808218124] transaction","detail":"{read_only:false; response_revision:468; number_of_response:1; }","duration":"355.141664ms","start":"2026-04-06T18:38:43.55608Z","end":"2026-04-06T18:38:43.911222Z","steps":["trace[808218124] 'process raft request' (duration: 354.967552ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:38:43.911307Z","caller":"traceutil/trace.go:171","msg":"trace[1940088016] transaction","detail":"{read_only:false; response_revision:469; number_of_response:1; }","duration":"354.218201ms","start":"2026-04-06T18:38:43.557064Z","end":"2026-04-06T18:38:43.911282Z","steps":["trace[1940088016] 'process raft request' (duration: 354.043479ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:38:43.911398Z","caller":"traceutil/trace.go:171","msg":"trace[1110188238] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"353.855197ms","start":"2026-04-06T18:38:43.55749Z","end":"2026-04-06T18:38:43.911345Z","steps":["trace[1110188238] 'process raft request' (duration: 353.699305ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:43.911463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:43.55607Z","time spent":"355.307576ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":9490,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T18:38:43.911487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:43.557482Z","time spent":"353.967548ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28166,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T18:38:43.911503Z","caller":"traceutil/trace.go:171","msg":"trace[418454480] transaction","detail":"{read_only:false; response_revision:471; number_of_response:1; }","duration":"350.236267ms","start":"2026-04-06T18:38:43.561231Z","end":"2026-04-06T18:38:43.911468Z","steps":["trace[418454480] 'process raft request' (duration: 350.007694ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:43.911522Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:43.557056Z","time spent":"354.383973ms","remote":"127.0.0.1:34590","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-06T18:38:43.911706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:43.561222Z","time spent":"350.383029ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":22019,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T18:38:43.911709Z","caller":"traceutil/trace.go:171","msg":"trace[2030056152] transaction","detail":"{read_only:false; response_revision:472; number_of_response:1; }","duration":"349.063271ms","start":"2026-04-06T18:38:43.562624Z","end":"2026-04-06T18:38:43.911687Z","steps":["trace[2030056152] 'process raft request' (duration: 348.663646ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:43.911791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:43.562615Z","time spent":"349.140482ms","remote":"127.0.0.1:34590","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-06T18:38:43.911832Z","caller":"traceutil/trace.go:171","msg":"trace[1528649930] transaction","detail":"{read_only:false; response_revision:474; number_of_response:1; }","duration":"341.569488ms","start":"2026-04-06T18:38:43.570251Z","end":"2026-04-06T18:38:43.911821Z","steps":["trace[1528649930] 'process raft request' (duration: 341.184563ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:43.911886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:43.57024Z","time spent":"341.621489ms","remote":"127.0.0.1:34590","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-06T18:38:43.911748Z","caller":"traceutil/trace.go:171","msg":"trace[1843966020] transaction","detail":"{read_only:false; response_revision:473; number_of_response:1; }","duration":"346.548047ms","start":"2026-04-06T18:38:43.565184Z","end":"2026-04-06T18:38:43.911732Z","steps":["trace[1843966020] 'process raft request' (duration: 346.174342ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:43.912022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:43.549852Z","time spent":"361.376019ms","remote":"127.0.0.1:34590","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-06T18:38:43.91207Z","caller":"traceutil/trace.go:171","msg":"trace[155701967] transaction","detail":"{read_only:false; response_revision:475; number_of_response:1; }","duration":"340.457464ms","start":"2026-04-06T18:38:43.571597Z","end":"2026-04-06T18:38:43.912055Z","steps":["trace[155701967] 'process raft request' (duration: 340.087278ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:43.912065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:43.565164Z","time spent":"346.870471ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29801,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-06T18:38:43.912162Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:43.571588Z","time spent":"340.539655ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58271,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T18:38:43.912152Z","caller":"traceutil/trace.go:171","msg":"trace[46083341] transaction","detail":"{read_only:false; response_revision:476; number_of_response:1; }","duration":"323.225658ms","start":"2026-04-06T18:38:43.588914Z","end":"2026-04-06T18:38:43.912139Z","steps":["trace[46083341] 'process raft request' (duration: 322.920194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:43.912247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:43.588892Z","time spent":"323.326449ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79374,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T18:38:43.912381Z","caller":"traceutil/trace.go:171","msg":"trace[756598834] linearizableReadLoop","detail":"{readStateIndex:498; appliedIndex:486; }","duration":"100.832899ms","start":"2026-04-06T18:38:43.811524Z","end":"2026-04-06T18:38:43.912357Z","steps":["trace[756598834] 'read index received' (duration: 41.248064ms)","trace[756598834] 'applied index is now lower than readState.Index' (duration: 59.583605ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T18:38:43.912416Z","caller":"traceutil/trace.go:171","msg":"trace[1044156703] transaction","detail":"{read_only:false; response_revision:477; number_of_response:1; }","duration":"206.100967ms","start":"2026-04-06T18:38:43.706302Z","end":"2026-04-06T18:38:43.912403Z","steps":["trace[1044156703] 'process raft request' (duration: 205.887624ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:43.912549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.016911ms","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-06T18:38:43.91261Z","caller":"traceutil/trace.go:171","msg":"trace[1973518030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:477; }","duration":"101.121502ms","start":"2026-04-06T18:38:43.811474Z","end":"2026-04-06T18:38:43.912596Z","steps":["trace[1973518030] 'agreement among raft nodes before linearized reading' (duration: 100.97586ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:44.776147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.751376ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T18:38:44.776287Z","caller":"traceutil/trace.go:171","msg":"trace[1052156347] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"375.620779ms","start":"2026-04-06T18:38:44.400642Z","end":"2026-04-06T18:38:44.776263Z","steps":["trace[1052156347] 'process raft request' (duration: 172.402307ms)","trace[1052156347] 'compare' (duration: 202.625594ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T18:38:44.776351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:44.400617Z","time spent":"375.70128ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":98689,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T18:38:44.777717Z","caller":"traceutil/trace.go:171","msg":"trace[1160227137] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"361.654459ms","start":"2026-04-06T18:38:44.416031Z","end":"2026-04-06T18:38:44.777685Z","steps":["trace[1160227137] 'process raft request' (duration: 360.400192ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:44.777856Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:44.415997Z","time spent":"361.78189ms","remote":"127.0.0.1:34590","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":151221,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T18:38:44.77962Z","caller":"traceutil/trace.go:171","msg":"trace[1355413465] linearizableReadLoop","detail":"{readStateIndex:550; appliedIndex:548; }","duration":"350.446277ms","start":"2026-04-06T18:38:44.429159Z","end":"2026-04-06T18:38:44.779606Z","steps":["trace[1355413465] 'read index received' (duration: 143.89798ms)","trace[1355413465] 'applied index is now lower than readState.Index' (duration: 206.547267ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T18:38:44.779745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.574609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T18:38:44.779772Z","caller":"traceutil/trace.go:171","msg":"trace[1488078266] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:525; }","duration":"350.632729ms","start":"2026-04-06T18:38:44.42913Z","end":"2026-04-06T18:38:44.779763Z","steps":["trace[1488078266] 'agreement among raft nodes before linearized reading' (duration: 350.543678ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:44.779796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:44.429114Z","time spent":"350.675741ms","remote":"127.0.0.1:34542","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-06T18:38:44.779944Z","caller":"traceutil/trace.go:171","msg":"trace[640192205] transaction","detail":"{read_only:false; response_revision:525; number_of_response:1; }","duration":"227.016145ms","start":"2026-04-06T18:38:44.552919Z","end":"2026-04-06T18:38:44.779936Z","steps":["trace[640192205] 'process raft request' (duration: 224.886936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:50.41277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.056853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"warn","ts":"2026-04-06T18:38:50.412659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"335.765916ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T18:38:50.412854Z","caller":"traceutil/trace.go:171","msg":"trace[499974647] linearizableReadLoop","detail":"{readStateIndex:601; appliedIndex:600; }","duration":"389.225796ms","start":"2026-04-06T18:38:50.023615Z","end":"2026-04-06T18:38:50.412841Z","steps":["trace[499974647] 'read index received' (duration: 195.9313ms)","trace[499974647] 'applied index is now lower than readState.Index' (duration: 193.293716ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T18:38:50.412897Z","caller":"traceutil/trace.go:171","msg":"trace[202045615] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:573; }","duration":"336.059589ms","start":"2026-04-06T18:38:50.076808Z","end":"2026-04-06T18:38:50.412867Z","steps":["trace[202045615] 'range keys from in-memory index tree' (duration: 335.741475ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:50.413218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.597791ms","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-06T18:38:50.413258Z","caller":"traceutil/trace.go:171","msg":"trace[309925865] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:574; }","duration":"389.660842ms","start":"2026-04-06T18:38:50.023585Z","end":"2026-04-06T18:38:50.413246Z","steps":["trace[309925865] 'agreement among raft nodes before linearized reading' (duration: 389.50626ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:50.413282Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:50.023567Z","time spent":"389.709163ms","remote":"127.0.0.1:34852","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-06T18:38:50.413364Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.384582ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bgndq\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-04-06T18:38:50.413462Z","caller":"traceutil/trace.go:171","msg":"trace[119468909] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bgndq; range_end:; response_count:1; response_revision:574; }","duration":"332.548355ms","start":"2026-04-06T18:38:50.080881Z","end":"2026-04-06T18:38:50.413429Z","steps":["trace[119468909] 'agreement among raft nodes before linearized reading' (duration: 332.316232ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:50.413437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.835815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-04-06T18:38:50.413513Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:50.080866Z","time spent":"332.636356ms","remote":"127.0.0.1:34756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16984,"request content":"key:\"/registry/pods/kube-system/cilium-bgndq\" "} {"level":"info","ts":"2026-04-06T18:38:50.413523Z","caller":"traceutil/trace.go:171","msg":"trace[278004309] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:574; }","duration":"204.978177ms","start":"2026-04-06T18:38:50.208525Z","end":"2026-04-06T18:38:50.413503Z","steps":["trace[278004309] 'agreement among raft nodes before linearized reading' (duration: 204.699483ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:38:50.413626Z","caller":"traceutil/trace.go:171","msg":"trace[1666167714] transaction","detail":"{read_only:false; response_revision:574; number_of_response:1; }","duration":"392.780282ms","start":"2026-04-06T18:38:50.020359Z","end":"2026-04-06T18:38:50.41314Z","steps":["trace[1666167714] 'process raft request' (duration: 199.286593ms)","trace[1666167714] 'compare' (duration: 192.956472ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T18:38:50.413777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:50.020338Z","time spent":"393.331279ms","remote":"127.0.0.1:35086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-06T18:38:50.834008Z","caller":"traceutil/trace.go:171","msg":"trace[986655612] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"414.224029ms","start":"2026-04-06T18:38:50.41975Z","end":"2026-04-06T18:38:50.833974Z","steps":["trace[986655612] 'process raft request' (duration: 413.904545ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:38:50.834255Z","caller":"traceutil/trace.go:171","msg":"trace[190170942] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"406.963456ms","start":"2026-04-06T18:38:50.427273Z","end":"2026-04-06T18:38:50.834236Z","steps":["trace[190170942] 'process raft request' (duration: 406.52638ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:38:50.834341Z","caller":"traceutil/trace.go:171","msg":"trace[998805091] linearizableReadLoop","detail":"{readStateIndex:604; appliedIndex:602; }","duration":"409.612669ms","start":"2026-04-06T18:38:50.424339Z","end":"2026-04-06T18:38:50.833952Z","steps":["trace[998805091] 'read index received' (duration: 34.615647ms)","trace[998805091] 'applied index is now lower than readState.Index' (duration: 374.996092ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T18:38:50.834394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:50.427255Z","time spent":"407.059677ms","remote":"127.0.0.1:34756","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T18:38:50.834249Z","caller":"traceutil/trace.go:171","msg":"trace[1560074359] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"415.247422ms","start":"2026-04-06T18:38:50.418978Z","end":"2026-04-06T18:38:50.834225Z","steps":["trace[1560074359] 'process raft request' (duration: 397.158659ms)","trace[1560074359] 'compare' (duration: 17.132081ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T18:38:50.834605Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:50.418954Z","time spent":"415.613407ms","remote":"127.0.0.1:34852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T18:38:50.834431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"410.082815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T18:38:50.834716Z","caller":"traceutil/trace.go:171","msg":"trace[324930245] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:578; }","duration":"410.3929ms","start":"2026-04-06T18:38:50.424311Z","end":"2026-04-06T18:38:50.834704Z","steps":["trace[324930245] 'agreement among raft nodes before linearized reading' (duration: 410.082116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:50.834753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:50.424302Z","time spent":"410.44443ms","remote":"127.0.0.1:34826","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" "} {"level":"warn","ts":"2026-04-06T18:38:50.834523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:50.419736Z","time spent":"414.379731ms","remote":"127.0.0.1:34852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T18:38:51.574479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"457.755048ms","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-06T18:38:51.574555Z","caller":"traceutil/trace.go:171","msg":"trace[1045123732] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:578; }","duration":"457.87807ms","start":"2026-04-06T18:38:51.116658Z","end":"2026-04-06T18:38:51.574536Z","steps":["trace[1045123732] 'range keys from in-memory index tree' (duration: 457.591816ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:51.574596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:51.116636Z","time spent":"457.951ms","remote":"127.0.0.1:34852","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-04-06T18:38:51.57495Z","caller":"traceutil/trace.go:171","msg":"trace[67105658] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"146.126354ms","start":"2026-04-06T18:38:51.428802Z","end":"2026-04-06T18:38:51.574928Z","steps":["trace[67105658] 'read index received' (duration: 145.105991ms)","trace[67105658] 'applied index is now lower than readState.Index' (duration: 1.019233ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T18:38:51.575144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.327096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T18:38:51.575181Z","caller":"traceutil/trace.go:171","msg":"trace[1093146500] transaction","detail":"{read_only:false; response_revision:579; number_of_response:1; }","duration":"371.28131ms","start":"2026-04-06T18:38:51.203882Z","end":"2026-04-06T18:38:51.575163Z","steps":["trace[1093146500] 'process raft request' (duration: 370.250756ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:38:51.575202Z","caller":"traceutil/trace.go:171","msg":"trace[998377380] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:579; }","duration":"146.436587ms","start":"2026-04-06T18:38:51.428748Z","end":"2026-04-06T18:38:51.575184Z","steps":["trace[998377380] 'agreement among raft nodes before linearized reading' (duration: 146.268195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:38:51.575305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:38:51.203855Z","time spent":"371.386291ms","remote":"127.0.0.1:34666","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T18:39:07.18607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.031845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-06T18:39:07.186163Z","caller":"traceutil/trace.go:171","msg":"trace[1200846197] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:741; }","duration":"296.167997ms","start":"2026-04-06T18:39:06.889976Z","end":"2026-04-06T18:39:07.186144Z","steps":["trace[1200846197] 'range keys from in-memory index tree' (duration: 295.841283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:39:07.186404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.294627ms","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-06T18:39:07.186442Z","caller":"traceutil/trace.go:171","msg":"trace[1412802069] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:741; }","duration":"278.369238ms","start":"2026-04-06T18:39:06.908062Z","end":"2026-04-06T18:39:07.186431Z","steps":["trace[1412802069] 'range keys from in-memory index tree' (duration: 278.170436ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:39:07.186714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.023611ms","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-06T18:39:07.186779Z","caller":"traceutil/trace.go:171","msg":"trace[1686764922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:741; }","duration":"276.125182ms","start":"2026-04-06T18:39:06.910638Z","end":"2026-04-06T18:39:07.186763Z","steps":["trace[1686764922] 'range keys from in-memory index tree' (duration: 275.904639ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:40:03.776155Z","caller":"traceutil/trace.go:171","msg":"trace[336613493] transaction","detail":"{read_only:false; response_revision:1412; number_of_response:1; }","duration":"152.122601ms","start":"2026-04-06T18:40:03.624006Z","end":"2026-04-06T18:40:03.776128Z","steps":["trace[336613493] 'process raft request' (duration: 151.944256ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:40:03.776947Z","caller":"traceutil/trace.go:171","msg":"trace[1514121852] transaction","detail":"{read_only:false; response_revision:1413; number_of_response:1; }","duration":"152.240635ms","start":"2026-04-06T18:40:03.624685Z","end":"2026-04-06T18:40:03.776925Z","steps":["trace[1514121852] 'process raft request' (duration: 152.132071ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:40:27.120111Z","caller":"traceutil/trace.go:171","msg":"trace[23968677] linearizableReadLoop","detail":"{readStateIndex:1903; appliedIndex:1902; }","duration":"114.519835ms","start":"2026-04-06T18:40:27.00556Z","end":"2026-04-06T18:40:27.12008Z","steps":["trace[23968677] 'read index received' (duration: 114.399702ms)","trace[23968677] 'applied index is now lower than readState.Index' (duration: 119.073µs)"],"step_count":2} {"level":"info","ts":"2026-04-06T18:40:27.120197Z","caller":"traceutil/trace.go:171","msg":"trace[667431745] transaction","detail":"{read_only:false; response_revision:1827; number_of_response:1; }","duration":"309.201487ms","start":"2026-04-06T18:40:26.810944Z","end":"2026-04-06T18:40:27.120146Z","steps":["trace[667431745] 'process raft request' (duration: 308.978512ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:40:27.120309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.739351ms","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-06T18:40:27.120351Z","caller":"traceutil/trace.go:171","msg":"trace[1511491232] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1827; }","duration":"114.840693ms","start":"2026-04-06T18:40:27.0055Z","end":"2026-04-06T18:40:27.12034Z","steps":["trace[1511491232] 'agreement among raft nodes before linearized reading' (duration: 114.687409ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:40:27.120435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:40:26.810922Z","time spent":"309.366652ms","remote":"127.0.0.1:34852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T18:40:27.668287Z","caller":"traceutil/trace.go:171","msg":"trace[264547286] transaction","detail":"{read_only:false; response_revision:1829; number_of_response:1; }","duration":"101.540177ms","start":"2026-04-06T18:40:27.566721Z","end":"2026-04-06T18:40:27.668261Z","steps":["trace[264547286] 'process raft request' (duration: 101.192408ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:40:39.445284Z","caller":"traceutil/trace.go:171","msg":"trace[1496284510] linearizableReadLoop","detail":"{readStateIndex:2009; appliedIndex:2008; }","duration":"237.223735ms","start":"2026-04-06T18:40:39.208041Z","end":"2026-04-06T18:40:39.445265Z","steps":["trace[1496284510] 'read index received' (duration: 237.172844ms)","trace[1496284510] 'applied index is now lower than readState.Index' (duration: 50.261µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T18:40:39.445492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.43415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-06T18:40:39.445528Z","caller":"traceutil/trace.go:171","msg":"trace[933048747] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1930; }","duration":"237.508701ms","start":"2026-04-06T18:40:39.208011Z","end":"2026-04-06T18:40:39.445519Z","steps":["trace[933048747] 'agreement among raft nodes before linearized reading' (duration: 237.385689ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:40:39.445535Z","caller":"traceutil/trace.go:171","msg":"trace[963614766] transaction","detail":"{read_only:false; response_revision:1930; number_of_response:1; }","duration":"281.517062ms","start":"2026-04-06T18:40:39.163997Z","end":"2026-04-06T18:40:39.445514Z","steps":["trace[963614766] 'process raft request' (duration: 281.129854ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:40:42.989544Z","caller":"traceutil/trace.go:171","msg":"trace[1978744102] transaction","detail":"{read_only:false; response_revision:1952; number_of_response:1; }","duration":"178.038494ms","start":"2026-04-06T18:40:42.811483Z","end":"2026-04-06T18:40:42.989521Z","steps":["trace[1978744102] 'process raft request' (duration: 176.257695ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:40:42.999522Z","caller":"traceutil/trace.go:171","msg":"trace[2123062151] transaction","detail":"{read_only:false; response_revision:1953; number_of_response:1; }","duration":"187.81519ms","start":"2026-04-06T18:40:42.811687Z","end":"2026-04-06T18:40:42.999502Z","steps":["trace[2123062151] 'process raft request' (duration: 187.082384ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:41:06.357021Z","caller":"traceutil/trace.go:171","msg":"trace[1673216480] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"154.750452ms","start":"2026-04-06T18:41:06.202242Z","end":"2026-04-06T18:41:06.356993Z","steps":["trace[1673216480] 'process raft request' (duration: 154.569799ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:41:06.452041Z","caller":"traceutil/trace.go:171","msg":"trace[2050047213] linearizableReadLoop","detail":"{readStateIndex:2175; appliedIndex:2172; }","duration":"149.687604ms","start":"2026-04-06T18:41:06.302326Z","end":"2026-04-06T18:41:06.452014Z","steps":["trace[2050047213] 'read index received' (duration: 54.48131ms)","trace[2050047213] 'applied index is now lower than readState.Index' (duration: 95.205304ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T18:41:06.452148Z","caller":"traceutil/trace.go:171","msg":"trace[179225464] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"249.693241ms","start":"2026-04-06T18:41:06.202443Z","end":"2026-04-06T18:41:06.452137Z","steps":["trace[179225464] 'process raft request' (duration: 236.566859ms)","trace[179225464] 'compare' (duration: 12.658923ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T18:41:06.4523Z","caller":"traceutil/trace.go:171","msg":"trace[400648775] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"154.198622ms","start":"2026-04-06T18:41:06.298051Z","end":"2026-04-06T18:41:06.45225Z","steps":["trace[400648775] 'process raft request' (duration: 153.911756ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:41:06.452377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.032251ms","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-06T18:41:06.452417Z","caller":"traceutil/trace.go:171","msg":"trace[947804167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2091; }","duration":"150.131923ms","start":"2026-04-06T18:41:06.302276Z","end":"2026-04-06T18:41:06.452408Z","steps":["trace[947804167] 'agreement among raft nodes before linearized reading' (duration: 149.97047ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:41:06.463776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.607069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.22\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-06T18:41:06.463832Z","caller":"traceutil/trace.go:171","msg":"trace[1147810567] range","detail":"{range_begin:/registry/masterleases/199.204.45.22; range_end:; response_count:1; response_revision:2092; }","duration":"126.690201ms","start":"2026-04-06T18:41:06.337129Z","end":"2026-04-06T18:41:06.463819Z","steps":["trace[1147810567] 'agreement among raft nodes before linearized reading' (duration: 126.534298ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:41:06.966236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.772356ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T18:41:06.966309Z","caller":"traceutil/trace.go:171","msg":"trace[113915937] transaction","detail":"{read_only:false; response_revision:2095; number_of_response:1; }","duration":"360.163829ms","start":"2026-04-06T18:41:06.606133Z","end":"2026-04-06T18:41:06.966297Z","steps":["trace[113915937] 'process raft request' (duration: 143.27587ms)","trace[113915937] 'compare' (duration: 216.702685ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T18:41:06.966283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"385.501327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"warn","ts":"2026-04-06T18:41:06.966344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:41:06.606115Z","time spent":"360.215049ms","remote":"127.0.0.1:34852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T18:41:06.966369Z","caller":"traceutil/trace.go:171","msg":"trace[147921003] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2094; }","duration":"385.6389ms","start":"2026-04-06T18:41:06.580708Z","end":"2026-04-06T18:41:06.966347Z","steps":["trace[147921003] 'range keys from in-memory index tree' (duration: 385.307803ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:41:06.966411Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:41:06.580684Z","time spent":"385.717552ms","remote":"127.0.0.1:34770","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2009,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-04-06T18:41:07.02254Z","caller":"traceutil/trace.go:171","msg":"trace[1525384912] transaction","detail":"{read_only:false; response_revision:2096; number_of_response:1; }","duration":"381.871745ms","start":"2026-04-06T18:41:06.640648Z","end":"2026-04-06T18:41:07.02252Z","steps":["trace[1525384912] 'process raft request' (duration: 381.741823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:41:07.022669Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:41:06.640614Z","time spent":"381.981358ms","remote":"127.0.0.1:34852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-06T18:41:56.864771Z","caller":"traceutil/trace.go:171","msg":"trace[332524198] transaction","detail":"{read_only:false; response_revision:2469; number_of_response:1; }","duration":"282.13143ms","start":"2026-04-06T18:41:56.582612Z","end":"2026-04-06T18:41:56.864743Z","steps":["trace[332524198] 'process raft request' (duration: 281.987968ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:41:56.876067Z","caller":"traceutil/trace.go:171","msg":"trace[874166974] linearizableReadLoop","detail":"{readStateIndex:2568; appliedIndex:2565; }","duration":"222.538032ms","start":"2026-04-06T18:41:56.653508Z","end":"2026-04-06T18:41:56.876046Z","steps":["trace[874166974] 'read index received' (duration: 211.079369ms)","trace[874166974] 'applied index is now lower than readState.Index' (duration: 11.457633ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T18:41:56.876128Z","caller":"traceutil/trace.go:171","msg":"trace[681830967] transaction","detail":"{read_only:false; response_revision:2470; number_of_response:1; }","duration":"281.661812ms","start":"2026-04-06T18:41:56.594444Z","end":"2026-04-06T18:41:56.876106Z","steps":["trace[681830967] 'process raft request' (duration: 281.455918ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:41:56.876189Z","caller":"traceutil/trace.go:171","msg":"trace[178290454] transaction","detail":"{read_only:false; response_revision:2471; number_of_response:1; }","duration":"262.631849ms","start":"2026-04-06T18:41:56.613547Z","end":"2026-04-06T18:41:56.876178Z","steps":["trace[178290454] 'process raft request' (duration: 262.462726ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:41:56.876418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.903257ms","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-06T18:41:56.876459Z","caller":"traceutil/trace.go:171","msg":"trace[2063352203] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2471; }","duration":"223.015509ms","start":"2026-04-06T18:41:56.653434Z","end":"2026-04-06T18:41:56.876449Z","steps":["trace[2063352203] 'agreement among raft nodes before linearized reading' (duration: 222.853836ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:41:56.87659Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.636407ms","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-06T18:41:56.876621Z","caller":"traceutil/trace.go:171","msg":"trace[1213467306] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2471; }","duration":"221.685438ms","start":"2026-04-06T18:41:56.654928Z","end":"2026-04-06T18:41:56.876614Z","steps":["trace[1213467306] 'agreement among raft nodes before linearized reading' (duration: 221.611396ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:42:29.332174Z","caller":"traceutil/trace.go:171","msg":"trace[1325253434] transaction","detail":"{read_only:false; response_revision:2651; number_of_response:1; }","duration":"101.567981ms","start":"2026-04-06T18:42:29.230578Z","end":"2026-04-06T18:42:29.332146Z","steps":["trace[1325253434] 'process raft request' (duration: 101.188046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:42:29.958832Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.941483ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-06T18:42:29.958921Z","caller":"traceutil/trace.go:171","msg":"trace[1405312390] linearizableReadLoop","detail":"{readStateIndex:2758; appliedIndex:2757; }","duration":"313.479833ms","start":"2026-04-06T18:42:29.645427Z","end":"2026-04-06T18:42:29.958906Z","steps":["trace[1405312390] 'read index received' (duration: 86.383377ms)","trace[1405312390] 'applied index is now lower than readState.Index' (duration: 227.095606ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T18:42:29.958979Z","caller":"traceutil/trace.go:171","msg":"trace[46271284] transaction","detail":"{read_only:false; response_revision:2654; number_of_response:1; }","duration":"363.41378ms","start":"2026-04-06T18:42:29.595555Z","end":"2026-04-06T18:42:29.958969Z","steps":["trace[46271284] 'process raft request' (duration: 136.262663ms)","trace[46271284] 'compare' (duration: 226.744751ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T18:42:29.959022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:42:29.595542Z","time spent":"363.460071ms","remote":"127.0.0.1:34852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-06T18:42:29.959131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.714876ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T18:42:29.959153Z","caller":"traceutil/trace.go:171","msg":"trace[1915535123] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2654; }","duration":"313.767707ms","start":"2026-04-06T18:42:29.645378Z","end":"2026-04-06T18:42:29.959146Z","steps":["trace[1915535123] 'agreement among raft nodes before linearized reading' (duration: 313.728057ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:42:29.959172Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-06T18:42:29.645359Z","time spent":"313.809907ms","remote":"127.0.0.1:35026","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":0,"response size":28,"request content":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" "} {"level":"warn","ts":"2026-04-06T18:42:30.020123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.483111ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak-0.18a3d8b047951aa9\" ","response":"range_response_count:1 size:781"} {"level":"info","ts":"2026-04-06T18:42:30.020194Z","caller":"traceutil/trace.go:171","msg":"trace[1133584471] range","detail":"{range_begin:/registry/events/auth-system/keycloak-0.18a3d8b047951aa9; range_end:; response_count:1; response_revision:2655; }","duration":"277.564632ms","start":"2026-04-06T18:42:29.742614Z","end":"2026-04-06T18:42:30.020179Z","steps":["trace[1133584471] 'agreement among raft nodes before linearized reading' (duration: 277.367589ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:46:14.639315Z","caller":"traceutil/trace.go:171","msg":"trace[2013620386] transaction","detail":"{read_only:false; response_revision:4554; number_of_response:1; }","duration":"103.420933ms","start":"2026-04-06T18:46:14.535872Z","end":"2026-04-06T18:46:14.639293Z","steps":["trace[2013620386] 'process raft request' (duration: 103.206999ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:46:20.723107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.548948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-06T18:46:20.723242Z","caller":"traceutil/trace.go:171","msg":"trace[1599035211] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4658; }","duration":"202.723911ms","start":"2026-04-06T18:46:20.520484Z","end":"2026-04-06T18:46:20.723208Z","steps":["trace[1599035211] 'count revisions from in-memory index tree' (duration: 202.475906ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:46:20.723942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.82374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194416"} {"level":"info","ts":"2026-04-06T18:46:20.724018Z","caller":"traceutil/trace.go:171","msg":"trace[1426258472] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4658; }","duration":"204.944222ms","start":"2026-04-06T18:46:20.519057Z","end":"2026-04-06T18:46:20.724001Z","steps":["trace[1426258472] 'range keys from in-memory index tree' (duration: 203.853112ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:46:21.315273Z","caller":"traceutil/trace.go:171","msg":"trace[41436788] transaction","detail":"{read_only:false; response_revision:4662; number_of_response:1; }","duration":"119.7822ms","start":"2026-04-06T18:46:21.195438Z","end":"2026-04-06T18:46:21.315221Z","steps":["trace[41436788] 'process raft request' (duration: 119.642368ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:46:21.431257Z","caller":"traceutil/trace.go:171","msg":"trace[381031541] linearizableReadLoop","detail":"{readStateIndex:4844; appliedIndex:4842; }","duration":"203.593132ms","start":"2026-04-06T18:46:21.227642Z","end":"2026-04-06T18:46:21.431236Z","steps":["trace[381031541] 'read index received' (duration: 87.425147ms)","trace[381031541] 'applied index is now lower than readState.Index' (duration: 116.167045ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T18:46:21.431596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.938488ms","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-06T18:46:21.431653Z","caller":"traceutil/trace.go:171","msg":"trace[1542035565] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4663; }","duration":"204.032749ms","start":"2026-04-06T18:46:21.227606Z","end":"2026-04-06T18:46:21.431639Z","steps":["trace[1542035565] 'agreement among raft nodes before linearized reading' (duration: 203.837795ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:46:21.431966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.176184ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-06T18:46:21.432009Z","caller":"traceutil/trace.go:171","msg":"trace[81210386] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4663; }","duration":"203.241855ms","start":"2026-04-06T18:46:21.228756Z","end":"2026-04-06T18:46:21.431998Z","steps":["trace[81210386] 'agreement among raft nodes before linearized reading' (duration: 202.631833ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:46:21.432935Z","caller":"traceutil/trace.go:171","msg":"trace[1562146028] transaction","detail":"{read_only:false; response_revision:4663; number_of_response:1; }","duration":"231.542345ms","start":"2026-04-06T18:46:21.200614Z","end":"2026-04-06T18:46:21.432156Z","steps":["trace[1562146028] 'process raft request' (duration: 215.294216ms)","trace[1562146028] 'compare' (duration: 15.178719ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T18:46:21.613028Z","caller":"traceutil/trace.go:171","msg":"trace[486423021] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"174.696851ms","start":"2026-04-06T18:46:21.438302Z","end":"2026-04-06T18:46:21.612999Z","steps":["trace[486423021] 'process raft request' (duration: 174.396455ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:46:21.613225Z","caller":"traceutil/trace.go:171","msg":"trace[507211159] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"174.819213ms","start":"2026-04-06T18:46:21.438388Z","end":"2026-04-06T18:46:21.613207Z","steps":["trace[507211159] 'process raft request' (duration: 174.522267ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:46:21.613651Z","caller":"traceutil/trace.go:171","msg":"trace[146145013] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"175.088777ms","start":"2026-04-06T18:46:21.438548Z","end":"2026-04-06T18:46:21.613637Z","steps":["trace[146145013] 'process raft request' (duration: 174.599858ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:46:21.613902Z","caller":"traceutil/trace.go:171","msg":"trace[1037779462] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"174.944975ms","start":"2026-04-06T18:46:21.438946Z","end":"2026-04-06T18:46:21.613891Z","steps":["trace[1037779462] 'process raft request' (duration: 174.569408ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:46:21.617818Z","caller":"traceutil/trace.go:171","msg":"trace[745551374] linearizableReadLoop","detail":"{readStateIndex:4847; appliedIndex:4844; }","duration":"179.364876ms","start":"2026-04-06T18:46:21.438435Z","end":"2026-04-06T18:46:21.617799Z","steps":["trace[745551374] 'read index received' (duration: 76.525036ms)","trace[745551374] 'applied index is now lower than readState.Index' (duration: 102.83839ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T18:46:21.618099Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.647221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-c184fb76-e924-4317-bf12-1b5fb377a495\" ","response":"range_response_count:1 size:1435"} {"level":"info","ts":"2026-04-06T18:46:21.618136Z","caller":"traceutil/trace.go:171","msg":"trace[751274301] range","detail":"{range_begin:/registry/persistentvolumes/pvc-c184fb76-e924-4317-bf12-1b5fb377a495; range_end:; response_count:1; response_revision:4668; }","duration":"179.706613ms","start":"2026-04-06T18:46:21.438414Z","end":"2026-04-06T18:46:21.618121Z","steps":["trace[751274301] 'agreement among raft nodes before linearized reading' (duration: 179.57358ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:46:21.62224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.735906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-04-06T18:46:21.622296Z","caller":"traceutil/trace.go:171","msg":"trace[1170342744] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4669; }","duration":"183.812328ms","start":"2026-04-06T18:46:21.438471Z","end":"2026-04-06T18:46:21.622284Z","steps":["trace[1170342744] 'agreement among raft nodes before linearized reading' (duration: 183.619925ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:46:25.771884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.252928ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-06T18:46:25.772053Z","caller":"traceutil/trace.go:171","msg":"trace[788972469] linearizableReadLoop","detail":"{readStateIndex:4913; appliedIndex:4912; }","duration":"145.186211ms","start":"2026-04-06T18:46:25.626848Z","end":"2026-04-06T18:46:25.772034Z","steps":["trace[788972469] 'read index received' (duration: 20.603006ms)","trace[788972469] 'applied index is now lower than readState.Index' (duration: 124.581704ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T18:46:25.772147Z","caller":"traceutil/trace.go:171","msg":"trace[320784578] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"153.096745ms","start":"2026-04-06T18:46:25.619035Z","end":"2026-04-06T18:46:25.772131Z","steps":["trace[320784578] 'process raft request' (duration: 28.512521ms)","trace[320784578] 'compare' (duration: 124.087345ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T18:46:25.772431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.563137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-85c987764c-mpt6g.18a3d8ea96c5ecf8\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-04-06T18:46:25.772506Z","caller":"traceutil/trace.go:171","msg":"trace[153484839] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-85c987764c-mpt6g.18a3d8ea96c5ecf8; range_end:; response_count:1; response_revision:4729; }","duration":"145.68465ms","start":"2026-04-06T18:46:25.626804Z","end":"2026-04-06T18:46:25.772489Z","steps":["trace[153484839] 'agreement among raft nodes before linearized reading' (duration: 145.427765ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:46:25.799368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.049179ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-06T18:46:25.799421Z","caller":"traceutil/trace.go:171","msg":"trace[1111684450] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4730; }","duration":"156.141572ms","start":"2026-04-06T18:46:25.643267Z","end":"2026-04-06T18:46:25.799408Z","steps":["trace[1111684450] 'agreement among raft nodes before linearized reading' (duration: 155.953658ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:46:25.933802Z","caller":"traceutil/trace.go:171","msg":"trace[1995755563] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"107.009214ms","start":"2026-04-06T18:46:25.826772Z","end":"2026-04-06T18:46:25.933781Z","steps":["trace[1995755563] 'process raft request' (duration: 97.803016ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:46:25.934285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.449078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:2643"} {"level":"info","ts":"2026-04-06T18:46:25.934327Z","caller":"traceutil/trace.go:171","msg":"trace[1550469005] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4735; }","duration":"115.52791ms","start":"2026-04-06T18:46:25.818788Z","end":"2026-04-06T18:46:25.934316Z","steps":["trace[1550469005] 'agreement among raft nodes before linearized reading' (duration: 114.670905ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:46:26.421073Z","caller":"traceutil/trace.go:171","msg":"trace[318748954] transaction","detail":"{read_only:false; response_revision:4762; number_of_response:1; }","duration":"114.308053ms","start":"2026-04-06T18:46:26.306747Z","end":"2026-04-06T18:46:26.421055Z","steps":["trace[318748954] 'process raft request' (duration: 101.714173ms)","trace[318748954] 'compare' (duration: 12.330475ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T18:46:35.17855Z","caller":"traceutil/trace.go:171","msg":"trace[53507449] transaction","detail":"{read_only:false; response_revision:4842; number_of_response:1; }","duration":"190.834302ms","start":"2026-04-06T18:46:34.987693Z","end":"2026-04-06T18:46:35.178527Z","steps":["trace[53507449] 'process raft request' (duration: 190.681559ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:46:35.638181Z","caller":"traceutil/trace.go:171","msg":"trace[561632144] transaction","detail":"{read_only:false; response_revision:4850; number_of_response:1; }","duration":"119.793385ms","start":"2026-04-06T18:46:35.51836Z","end":"2026-04-06T18:46:35.638153Z","steps":["trace[561632144] 'process raft request' (duration: 94.45695ms)","trace[561632144] 'compare' (duration: 25.07135ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T18:47:04.606311Z","caller":"traceutil/trace.go:171","msg":"trace[1856752718] linearizableReadLoop","detail":"{readStateIndex:5321; appliedIndex:5320; }","duration":"175.789262ms","start":"2026-04-06T18:47:04.430498Z","end":"2026-04-06T18:47:04.606287Z","steps":["trace[1856752718] 'read index received' (duration: 175.597358ms)","trace[1856752718] 'applied index is now lower than readState.Index' (duration: 190.934µs)"],"step_count":2} {"level":"warn","ts":"2026-04-06T18:47:04.606478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.962285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-06T18:47:04.606467Z","caller":"traceutil/trace.go:171","msg":"trace[1585575050] transaction","detail":"{read_only:false; response_revision:5127; number_of_response:1; }","duration":"262.769337ms","start":"2026-04-06T18:47:04.343644Z","end":"2026-04-06T18:47:04.606413Z","steps":["trace[1585575050] 'process raft request' (duration: 262.470602ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:47:04.606522Z","caller":"traceutil/trace.go:171","msg":"trace[882454738] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5127; }","duration":"176.062586ms","start":"2026-04-06T18:47:04.430447Z","end":"2026-04-06T18:47:04.606509Z","steps":["trace[882454738] 'agreement among raft nodes before linearized reading' (duration: 175.983164ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:47:04.606756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.883641ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-06T18:47:04.60679Z","caller":"traceutil/trace.go:171","msg":"trace[2117509516] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5127; }","duration":"113.930861ms","start":"2026-04-06T18:47:04.492851Z","end":"2026-04-06T18:47:04.606782Z","steps":["trace[2117509516] 'agreement among raft nodes before linearized reading' (duration: 113.83575ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:47:04.606767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.847309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-06T18:47:04.606849Z","caller":"traceutil/trace.go:171","msg":"trace[1498539569] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5127; }","duration":"114.006082ms","start":"2026-04-06T18:47:04.492824Z","end":"2026-04-06T18:47:04.60683Z","steps":["trace[1498539569] 'agreement among raft nodes before linearized reading' (duration: 113.790968ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:47:04.606962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.704323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-04-06T18:47:04.606994Z","caller":"traceutil/trace.go:171","msg":"trace[1212795968] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5127; }","duration":"148.753004ms","start":"2026-04-06T18:47:04.458233Z","end":"2026-04-06T18:47:04.606986Z","steps":["trace[1212795968] 'agreement among raft nodes before linearized reading' (duration: 148.670542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-06T18:47:04.606655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.726937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-06T18:47:04.607144Z","caller":"traceutil/trace.go:171","msg":"trace[1528075410] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5127; }","duration":"170.228735ms","start":"2026-04-06T18:47:04.4369Z","end":"2026-04-06T18:47:04.607129Z","steps":["trace[1528075410] 'agreement among raft nodes before linearized reading' (duration: 169.652185ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:47:04.759839Z","caller":"traceutil/trace.go:171","msg":"trace[1538301209] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"148.466429ms","start":"2026-04-06T18:47:04.611337Z","end":"2026-04-06T18:47:04.759803Z","steps":["trace[1538301209] 'process raft request' (duration: 120.258111ms)","trace[1538301209] 'compare' (duration: 28.011855ms)"],"step_count":2} {"level":"info","ts":"2026-04-06T18:47:04.75989Z","caller":"traceutil/trace.go:171","msg":"trace[2035201533] transaction","detail":"{read_only:false; response_revision:5129; number_of_response:1; }","duration":"148.018371ms","start":"2026-04-06T18:47:04.611848Z","end":"2026-04-06T18:47:04.759866Z","steps":["trace[2035201533] 'process raft request' (duration: 147.891369ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:47:04.759904Z","caller":"traceutil/trace.go:171","msg":"trace[82852763] transaction","detail":"{read_only:false; response_revision:5130; number_of_response:1; }","duration":"147.806847ms","start":"2026-04-06T18:47:04.612072Z","end":"2026-04-06T18:47:04.759879Z","steps":["trace[82852763] 'process raft request' (duration: 147.722766ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:47:04.75986Z","caller":"traceutil/trace.go:171","msg":"trace[1343207072] linearizableReadLoop","detail":"{readStateIndex:5324; appliedIndex:5321; }","duration":"147.701166ms","start":"2026-04-06T18:47:04.612136Z","end":"2026-04-06T18:47:04.759837Z","steps":["trace[1343207072] 'read index received' (duration: 119.472757ms)","trace[1343207072] 'applied index is now lower than readState.Index' (duration: 28.227459ms)"],"step_count":2} {"level":"warn","ts":"2026-04-06T18:47:04.760292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.133883ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-04-06T18:47:04.760349Z","caller":"traceutil/trace.go:171","msg":"trace[1594880545] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5130; }","duration":"148.254945ms","start":"2026-04-06T18:47:04.612082Z","end":"2026-04-06T18:47:04.760337Z","steps":["trace[1594880545] 'agreement among raft nodes before linearized reading' (duration: 147.826458ms)"],"step_count":1} {"level":"info","ts":"2026-04-06T18:48:13.425083Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2884} {"level":"info","ts":"2026-04-06T18:48:13.51391Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2884,"took":"87.357734ms","hash":2090328266,"current-db-size-bytes":54902784,"current-db-size":"55 MB","current-db-size-in-use-bytes":29683712,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-06T18:48:13.514153Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2090328266,"revision":2884,"compact-revision":-1} {"level":"info","ts":"2026-04-06T18:53:13.432563Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5620} {"level":"info","ts":"2026-04-06T18:53:13.506176Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5620,"took":"72.48697ms","hash":3912969519,"current-db-size-bytes":54902784,"current-db-size":"55 MB","current-db-size-in-use-bytes":18235392,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-06T18:53:13.506219Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3912969519,"revision":5620,"compact-revision":2884}