{"level":"warn","ts":"2026-08-24T15:39:48.501527Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:39:48.501627Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.57:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.57:2380","--initial-cluster=instance=https://199.19.213.57:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.57:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.57: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-08-24T15:39:48.501708Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-24T15:39:48.50172Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.57:2380"]} {"level":"info","ts":"2026-08-24T15:39:48.501755Z","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-08-24T15:39:48.502434Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"]} {"level":"info","ts":"2026-08-24T15:39:48.502524Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.57:2380"],"listen-peer-urls":["https://199.19.213.57:2380"],"advertise-client-urls":["https://199.19.213.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.57: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-08-24T15:39:48.507312Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.572882ms"} {"level":"info","ts":"2026-08-24T15:39:48.513247Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"ef5ef2a9544a39ef","cluster-id":"58d35553a3d286c0"} {"level":"info","ts":"2026-08-24T15:39:48.513437Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=()"} {"level":"info","ts":"2026-08-24T15:39:48.513543Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became follower at term 0"} {"level":"info","ts":"2026-08-24T15:39:48.513569Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft ef5ef2a9544a39ef [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-24T15:39:48.513594Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became follower at term 1"} {"level":"info","ts":"2026-08-24T15:39:48.513756Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=(17248490431953123823)"} {"level":"warn","ts":"2026-08-24T15:39:48.52276Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-24T15:39:48.524443Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-24T15:39:48.525833Z","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-08-24T15:39:48.529827Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"ef5ef2a9544a39ef","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-24T15:39:48.530196Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"ef5ef2a9544a39ef","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-24T15:39:48.530485Z","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-08-24T15:39:48.530635Z","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-08-24T15:39:48.530657Z","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-08-24T15:39:48.53537Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef switched to configuration voters=(17248490431953123823)"} {"level":"info","ts":"2026-08-24T15:39:48.535595Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"58d35553a3d286c0","local-member-id":"ef5ef2a9544a39ef","added-peer-id":"ef5ef2a9544a39ef","added-peer-peer-urls":["https://199.19.213.57:2380"]} {"level":"info","ts":"2026-08-24T15:39:48.537335Z","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-08-24T15:39:48.537472Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.57:2380"} {"level":"info","ts":"2026-08-24T15:39:48.537568Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.57:2380"} {"level":"info","ts":"2026-08-24T15:39:48.537671Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"ef5ef2a9544a39ef","initial-advertise-peer-urls":["https://199.19.213.57:2380"],"listen-peer-urls":["https://199.19.213.57:2380"],"advertise-client-urls":["https://199.19.213.57:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.57:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-24T15:39:48.537719Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-24T15:39:48.814755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef is starting a new election at term 1"} {"level":"info","ts":"2026-08-24T15:39:48.814853Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became pre-candidate at term 1"} {"level":"info","ts":"2026-08-24T15:39:48.814887Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef received MsgPreVoteResp from ef5ef2a9544a39ef at term 1"} {"level":"info","ts":"2026-08-24T15:39:48.815048Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became candidate at term 2"} {"level":"info","ts":"2026-08-24T15:39:48.815156Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef received MsgVoteResp from ef5ef2a9544a39ef at term 2"} {"level":"info","ts":"2026-08-24T15:39:48.815304Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"ef5ef2a9544a39ef became leader at term 2"} {"level":"info","ts":"2026-08-24T15:39:48.81533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: ef5ef2a9544a39ef elected leader ef5ef2a9544a39ef at term 2"} {"level":"info","ts":"2026-08-24T15:39:49.061555Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:39:49.117192Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:39:49.117193Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"ef5ef2a9544a39ef","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.57:2379]}","request-path":"/0/members/ef5ef2a9544a39ef/attributes","cluster-id":"58d35553a3d286c0","publish-timeout":"7s"} {"level":"info","ts":"2026-08-24T15:39:49.117262Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-24T15:39:49.117735Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-24T15:39:49.117807Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-24T15:39:49.120993Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-24T15:39:49.121554Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.57:2379"} {"level":"info","ts":"2026-08-24T15:39:49.129847Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"58d35553a3d286c0","local-member-id":"ef5ef2a9544a39ef","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:39:49.129961Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-24T15:39:49.130146Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-08-24T15:39:50.910506Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174731526632088183,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:39:51.41133Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174731526632088183,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-24T15:39:51.417493Z","caller":"traceutil/trace.go:171","msg":"trace[769092794] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"1.009180107s","start":"2026-08-24T15:39:50.408292Z","end":"2026-08-24T15:39:51.417473Z","steps":["trace[769092794] 'process raft request' (duration: 1.008964113s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:51.418084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:50.408282Z","time spent":"1.009269738s","remote":"127.0.0.1:48808","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":100,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:39:52.482306Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.279506ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:14"} {"level":"info","ts":"2026-08-24T15:39:52.482636Z","caller":"traceutil/trace.go:171","msg":"trace[1605290489] linearizableReadLoop","detail":"{readStateIndex:13; appliedIndex:4; }","duration":"2.072632509s","start":"2026-08-24T15:39:50.409987Z","end":"2026-08-24T15:39:52.482619Z","steps":["trace[1605290489] 'read index received' (duration: 1.007400884s)","trace[1605290489] 'applied index is now lower than readState.Index' (duration: 1.065230785s)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:52.482713Z","caller":"traceutil/trace.go:171","msg":"trace[45214044] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"2.008113667s","start":"2026-08-24T15:39:50.47459Z","end":"2026-08-24T15:39:52.482704Z","steps":["trace[45214044] 'process raft request' (duration: 2.007874782s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:52.482772Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:50.474554Z","time spent":"2.008191148s","remote":"127.0.0.1:49222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:39:52.482789Z","caller":"traceutil/trace.go:171","msg":"trace[1467497573] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"2.007460455s","start":"2026-08-24T15:39:50.475276Z","end":"2026-08-24T15:39:52.482737Z","steps":["trace[1467497573] 'process raft request' (duration: 2.007289302s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:52.482842Z","caller":"traceutil/trace.go:171","msg":"trace[1440421401] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"2.008343241s","start":"2026-08-24T15:39:50.47444Z","end":"2026-08-24T15:39:52.482783Z","steps":["trace[1440421401] 'process raft request' (duration: 2.007979785s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:52.482924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:50.475267Z","time spent":"2.007586128s","remote":"127.0.0.1:49316","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-08-24T15:39:52.482964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:50.474423Z","time spent":"2.008491903s","remote":"127.0.0.1:49316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:39:52.483008Z","caller":"traceutil/trace.go:171","msg":"trace[479595082] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"2.007870813s","start":"2026-08-24T15:39:50.475096Z","end":"2026-08-24T15:39:52.482967Z","steps":["trace[479595082] 'process raft request' (duration: 2.007421515s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:52.483017Z","caller":"traceutil/trace.go:171","msg":"trace[182797698] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"2.074538233s","start":"2026-08-24T15:39:50.408405Z","end":"2026-08-24T15:39:52.482943Z","steps":["trace[182797698] 'process raft request' (duration: 1.890393162s)","trace[182797698] 'compare' (duration: 183.142833ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:52.483115Z","caller":"traceutil/trace.go:171","msg":"trace[974892819] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"1.995525942s","start":"2026-08-24T15:39:50.487578Z","end":"2026-08-24T15:39:52.483104Z","steps":["trace[974892819] 'process raft request' (duration: 1.995009143s)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:52.48313Z","caller":"traceutil/trace.go:171","msg":"trace[513937114] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"2.007943394s","start":"2026-08-24T15:39:50.47515Z","end":"2026-08-24T15:39:52.483093Z","steps":["trace[513937114] 'process raft request' (duration: 2.007388055s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:52.48317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:50.487562Z","time spent":"1.995583422s","remote":"127.0.0.1:48924","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-08-24T15:39:52.483171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:50.475088Z","time spent":"2.008019286s","remote":"127.0.0.1:49316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:39:52.483212Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:50.475135Z","time spent":"2.008033146s","remote":"127.0.0.1:49316","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-08-24T15:39:52.483222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:50.408393Z","time spent":"2.074740286s","remote":"127.0.0.1:48810","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:39:52.483236Z","caller":"traceutil/trace.go:171","msg":"trace[1444633841] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"2.008168218s","start":"2026-08-24T15:39:50.475002Z","end":"2026-08-24T15:39:52.48317Z","steps":["trace[1444633841] 'process raft request' (duration: 2.007489886s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:52.483467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:50.474976Z","time spent":"2.008355412s","remote":"127.0.0.1:49316","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-08-24T15:39:52.483483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.073426863s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T15:39:52.483543Z","caller":"traceutil/trace.go:171","msg":"trace[864975641] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:10; }","duration":"2.073580806s","start":"2026-08-24T15:39:50.409945Z","end":"2026-08-24T15:39:52.483526Z","steps":["trace[864975641] 'agreement among raft nodes before linearized reading' (duration: 2.073368272s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:52.483599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:50.409932Z","time spent":"2.073658187s","remote":"127.0.0.1:49162","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":0,"response size":27,"request content":"key:\"/registry/csinodes/instance\" "} {"level":"warn","ts":"2026-08-24T15:39:53.22017Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.42363ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-08-24T15:39:53.220476Z","caller":"traceutil/trace.go:171","msg":"trace[468355381] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"1.056664729s","start":"2026-08-24T15:39:52.163775Z","end":"2026-08-24T15:39:53.22044Z","steps":["trace[468355381] 'process raft request' (duration: 746.853312ms)","trace[468355381] 'compare' (duration: 309.015654ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:53.220625Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:52.163755Z","time spent":"1.056799391s","remote":"127.0.0.1:49006","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:39:53.220719Z","caller":"traceutil/trace.go:171","msg":"trace[748979464] linearizableReadLoop","detail":"{readStateIndex:14; appliedIndex:13; }","duration":"738.021028ms","start":"2026-08-24T15:39:52.482674Z","end":"2026-08-24T15:39:53.220695Z","steps":["trace[748979464] 'read index received' (duration: 427.963796ms)","trace[748979464] 'applied index is now lower than readState.Index' (duration: 310.055132ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:53.220936Z","caller":"traceutil/trace.go:171","msg":"trace[632607664] transaction","detail":"{read_only:false; response_revision:15; number_of_response:1; }","duration":"732.024253ms","start":"2026-08-24T15:39:52.488904Z","end":"2026-08-24T15:39:53.220928Z","steps":["trace[632607664] 'process raft request' (duration: 731.789688ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.220993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.426438903s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-24T15:39:53.221022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:52.488872Z","time spent":"732.113915ms","remote":"127.0.0.1:49316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:39:53.221045Z","caller":"traceutil/trace.go:171","msg":"trace[554104329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:17; }","duration":"1.426544805s","start":"2026-08-24T15:39:51.794487Z","end":"2026-08-24T15:39:53.221032Z","steps":["trace[554104329] 'agreement among raft nodes before linearized reading' (duration: 1.426380421s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.221085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:51.794466Z","time spent":"1.426610646s","remote":"127.0.0.1:48732","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-24T15:39:53.221128Z","caller":"traceutil/trace.go:171","msg":"trace[730713532] transaction","detail":"{read_only:false; response_revision:12; number_of_response:1; }","duration":"732.303427ms","start":"2026-08-24T15:39:52.488816Z","end":"2026-08-24T15:39:53.22112Z","steps":["trace[730713532] 'process raft request' (duration: 731.633144ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.22119Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:52.488788Z","time spent":"732.366629ms","remote":"127.0.0.1:49316","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-08-24T15:39:53.221297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.848287234s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T15:39:53.22134Z","caller":"traceutil/trace.go:171","msg":"trace[1057224801] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:17; }","duration":"1.848331765s","start":"2026-08-24T15:39:51.372999Z","end":"2026-08-24T15:39:53.22133Z","steps":["trace[1057224801] 'agreement among raft nodes before linearized reading' (duration: 1.848265144s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.221375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:51.372885Z","time spent":"1.848483857s","remote":"127.0.0.1:49130","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":27,"request content":"key:\"/registry/priorityclasses/system-node-critical\" "} {"level":"info","ts":"2026-08-24T15:39:53.22153Z","caller":"traceutil/trace.go:171","msg":"trace[721757468] transaction","detail":"{read_only:false; response_revision:16; number_of_response:1; }","duration":"732.398249ms","start":"2026-08-24T15:39:52.489123Z","end":"2026-08-24T15:39:53.221521Z","steps":["trace[721757468] 'process raft request' (duration: 731.622065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.221593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:52.489111Z","time spent":"732.45757ms","remote":"127.0.0.1:49222","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:39:53.221743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.856377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3490"} {"level":"info","ts":"2026-08-24T15:39:53.221787Z","caller":"traceutil/trace.go:171","msg":"trace[339097783] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:18; }","duration":"732.931728ms","start":"2026-08-24T15:39:52.488846Z","end":"2026-08-24T15:39:53.221777Z","steps":["trace[339097783] 'agreement among raft nodes before linearized reading' (duration: 732.837717ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.221814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:52.488804Z","time spent":"733.00399ms","remote":"127.0.0.1:48924","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3513,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-08-24T15:39:53.221309Z","caller":"traceutil/trace.go:171","msg":"trace[381282929] transaction","detail":"{read_only:false; response_revision:13; number_of_response:1; }","duration":"732.45587ms","start":"2026-08-24T15:39:52.488843Z","end":"2026-08-24T15:39:53.221299Z","steps":["trace[381282929] 'process raft request' (duration: 731.663915ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.222019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:52.488816Z","time spent":"733.144341ms","remote":"127.0.0.1:49316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":965,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:39:53.222097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.849088198s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T15:39:53.222148Z","caller":"traceutil/trace.go:171","msg":"trace[731726394] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:18; }","duration":"1.849216141s","start":"2026-08-24T15:39:51.37292Z","end":"2026-08-24T15:39:53.222136Z","steps":["trace[731726394] 'agreement among raft nodes before linearized reading' (duration: 1.84858696s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.222184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.696280534s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T15:39:53.22134Z","caller":"traceutil/trace.go:171","msg":"trace[1528986881] transaction","detail":"{read_only:false; response_revision:14; number_of_response:1; }","duration":"732.44677ms","start":"2026-08-24T15:39:52.488886Z","end":"2026-08-24T15:39:53.221332Z","steps":["trace[1528986881] 'process raft request' (duration: 731.727116ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:53.222222Z","caller":"traceutil/trace.go:171","msg":"trace[425781708] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"2.696338015s","start":"2026-08-24T15:39:50.525873Z","end":"2026-08-24T15:39:53.222211Z","steps":["trace[425781708] 'agreement among raft nodes before linearized reading' (duration: 2.695656953s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.222247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:52.488867Z","time spent":"733.349546ms","remote":"127.0.0.1:49316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":923,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:39:53.222262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:50.52586Z","time spent":"2.696392376s","remote":"127.0.0.1:48848","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-08-24T15:39:53.222388Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.746669764s","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":"info","ts":"2026-08-24T15:39:53.222426Z","caller":"traceutil/trace.go:171","msg":"trace[268153438] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:18; }","duration":"2.746725254s","start":"2026-08-24T15:39:50.475689Z","end":"2026-08-24T15:39:53.222415Z","steps":["trace[268153438] 'agreement among raft nodes before linearized reading' (duration: 2.745847269s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.222455Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:50.47568Z","time spent":"2.746768365s","remote":"127.0.0.1:48826","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-08-24T15:39:53.222587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.748405795s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T15:39:53.222623Z","caller":"traceutil/trace.go:171","msg":"trace[371927647] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"2.748481316s","start":"2026-08-24T15:39:50.474133Z","end":"2026-08-24T15:39:53.222615Z","steps":["trace[371927647] 'agreement among raft nodes before linearized reading' (duration: 2.747410987s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.222648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:50.47412Z","time spent":"2.748522857s","remote":"127.0.0.1:48848","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-08-24T15:39:53.222767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.750319279s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.57\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T15:39:53.2228Z","caller":"traceutil/trace.go:171","msg":"trace[987344902] range","detail":"{range_begin:/registry/masterleases/199.19.213.57; range_end:; response_count:0; response_revision:18; }","duration":"2.750380141s","start":"2026-08-24T15:39:50.472412Z","end":"2026-08-24T15:39:53.222792Z","steps":["trace[987344902] 'agreement among raft nodes before linearized reading' (duration: 2.749140499s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.222826Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:50.472397Z","time spent":"2.750423601s","remote":"127.0.0.1:48792","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.57\" "} {"level":"info","ts":"2026-08-24T15:39:53.222974Z","caller":"traceutil/trace.go:171","msg":"trace[1864440773] transaction","detail":"{read_only:false; response_revision:18; number_of_response:1; }","duration":"726.813789ms","start":"2026-08-24T15:39:52.49615Z","end":"2026-08-24T15:39:53.222963Z","steps":["trace[1864440773] 'process raft request' (duration: 724.916206ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.223044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:52.496138Z","time spent":"726.865869ms","remote":"127.0.0.1:48924","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3478,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:39:53.22219Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:51.372911Z","time spent":"1.849271671s","remote":"127.0.0.1:49108","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":27,"request content":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" "} {"level":"info","ts":"2026-08-24T15:39:53.223451Z","caller":"traceutil/trace.go:171","msg":"trace[1926947707] transaction","detail":"{read_only:false; response_revision:17; number_of_response:1; }","duration":"733.624209ms","start":"2026-08-24T15:39:52.489799Z","end":"2026-08-24T15:39:53.223423Z","steps":["trace[1926947707] 'process raft request' (duration: 731.050203ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.222157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.669617228s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-24T15:39:53.223539Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:52.489787Z","time spent":"733.716871ms","remote":"127.0.0.1:49316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":941,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:39:53.223569Z","caller":"traceutil/trace.go:171","msg":"trace[387601874] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:18; }","duration":"2.671055212s","start":"2026-08-24T15:39:50.552503Z","end":"2026-08-24T15:39:53.223558Z","steps":["trace[387601874] 'agreement among raft nodes before linearized reading' (duration: 2.669017827s)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.223611Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:39:50.552488Z","time spent":"2.671113893s","remote":"127.0.0.1:48848","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":"info","ts":"2026-08-24T15:39:53.456521Z","caller":"traceutil/trace.go:171","msg":"trace[835817125] transaction","detail":"{read_only:false; response_revision:38; number_of_response:1; }","duration":"137.421232ms","start":"2026-08-24T15:39:53.319069Z","end":"2026-08-24T15:39:53.45649Z","steps":["trace[835817125] 'process raft request' (duration: 137.378441ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:53.456596Z","caller":"traceutil/trace.go:171","msg":"trace[458918405] linearizableReadLoop","detail":"{readStateIndex:39; appliedIndex:37; }","duration":"141.536446ms","start":"2026-08-24T15:39:53.315032Z","end":"2026-08-24T15:39:53.456569Z","steps":["trace[458918405] 'read index received' (duration: 87.719526ms)","trace[458918405] 'applied index is now lower than readState.Index' (duration: 53.813999ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:53.45658Z","caller":"traceutil/trace.go:171","msg":"trace[1490166390] transaction","detail":"{read_only:false; response_revision:34; number_of_response:1; }","duration":"190.950137ms","start":"2026-08-24T15:39:53.265568Z","end":"2026-08-24T15:39:53.456518Z","steps":["trace[1490166390] 'process raft request' (duration: 137.113517ms)","trace[1490166390] 'compare' (duration: 53.36408ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:53.456742Z","caller":"traceutil/trace.go:171","msg":"trace[1535317290] transaction","detail":"{read_only:false; response_revision:36; number_of_response:1; }","duration":"138.912869ms","start":"2026-08-24T15:39:53.317799Z","end":"2026-08-24T15:39:53.456712Z","steps":["trace[1535317290] 'process raft request' (duration: 138.551503ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:53.456839Z","caller":"traceutil/trace.go:171","msg":"trace[2036295094] transaction","detail":"{read_only:false; response_revision:37; number_of_response:1; }","duration":"137.86739ms","start":"2026-08-24T15:39:53.31896Z","end":"2026-08-24T15:39:53.456828Z","steps":["trace[2036295094] 'process raft request' (duration: 137.442523ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:53.457112Z","caller":"traceutil/trace.go:171","msg":"trace[994738262] transaction","detail":"{read_only:false; response_revision:35; number_of_response:1; }","duration":"169.400997ms","start":"2026-08-24T15:39:53.287179Z","end":"2026-08-24T15:39:53.45658Z","steps":["trace[994738262] 'process raft request' (duration: 169.105042ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.457384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.545082ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/kube-system/extension-apiserver-authentication\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T15:39:53.45855Z","caller":"traceutil/trace.go:171","msg":"trace[1084557208] range","detail":"{range_begin:/registry/configmaps/kube-system/extension-apiserver-authentication; range_end:; response_count:0; response_revision:38; }","duration":"139.768953ms","start":"2026-08-24T15:39:53.318762Z","end":"2026-08-24T15:39:53.458531Z","steps":["trace[1084557208] 'agreement among raft nodes before linearized reading' (duration: 138.488801ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.458162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.502793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-admin\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T15:39:53.458692Z","caller":"traceutil/trace.go:171","msg":"trace[161434594] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-admin; range_end:; response_count:0; response_revision:38; }","duration":"193.072613ms","start":"2026-08-24T15:39:53.26561Z","end":"2026-08-24T15:39:53.458683Z","steps":["trace[161434594] 'agreement among raft nodes before linearized reading' (duration: 191.502936ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:53.600915Z","caller":"traceutil/trace.go:171","msg":"trace[109213332] linearizableReadLoop","detail":"{readStateIndex:44; appliedIndex:42; }","duration":"138.716634ms","start":"2026-08-24T15:39:53.462157Z","end":"2026-08-24T15:39:53.600873Z","steps":["trace[109213332] 'read index received' (duration: 69.095957ms)","trace[109213332] 'applied index is now lower than readState.Index' (duration: 69.619217ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:53.601101Z","caller":"traceutil/trace.go:171","msg":"trace[1056615360] transaction","detail":"{read_only:false; response_revision:41; number_of_response:1; }","duration":"138.566951ms","start":"2026-08-24T15:39:53.462497Z","end":"2026-08-24T15:39:53.601064Z","steps":["trace[1056615360] 'process raft request' (duration: 138.329797ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:53.601003Z","caller":"traceutil/trace.go:171","msg":"trace[788674756] transaction","detail":"{read_only:false; response_revision:40; number_of_response:1; }","duration":"139.166343ms","start":"2026-08-24T15:39:53.461748Z","end":"2026-08-24T15:39:53.600914Z","steps":["trace[788674756] 'process raft request' (duration: 131.363905ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.601243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.05557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-node-lease/\" range_end:\"/registry/resourcequotas/kube-node-lease0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T15:39:53.601451Z","caller":"traceutil/trace.go:171","msg":"trace[478183283] range","detail":"{range_begin:/registry/resourcequotas/kube-node-lease/; range_end:/registry/resourcequotas/kube-node-lease0; response_count:0; response_revision:41; }","duration":"139.339415ms","start":"2026-08-24T15:39:53.462097Z","end":"2026-08-24T15:39:53.601436Z","steps":["trace[478183283] 'agreement among raft nodes before linearized reading' (duration: 138.946788ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.616715Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.793204ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T15:39:53.617193Z","caller":"traceutil/trace.go:171","msg":"trace[1057742010] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:42; }","duration":"152.923046ms","start":"2026-08-24T15:39:53.463863Z","end":"2026-08-24T15:39:53.616786Z","steps":["trace[1057742010] 'agreement among raft nodes before linearized reading' (duration: 152.689172ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:53.738616Z","caller":"traceutil/trace.go:171","msg":"trace[1280376158] transaction","detail":"{read_only:false; response_revision:45; number_of_response:1; }","duration":"113.562731ms","start":"2026-08-24T15:39:53.625032Z","end":"2026-08-24T15:39:53.738595Z","steps":["trace[1280376158] 'process raft request' (duration: 103.652767ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:39:53.739059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.17116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T15:39:53.739221Z","caller":"traceutil/trace.go:171","msg":"trace[1858963294] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-view; range_end:; response_count:0; response_revision:45; }","duration":"115.382074ms","start":"2026-08-24T15:39:53.62381Z","end":"2026-08-24T15:39:53.739192Z","steps":["trace[1858963294] 'agreement among raft nodes before linearized reading' (duration: 114.762363ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:53.908322Z","caller":"traceutil/trace.go:171","msg":"trace[1738821673] transaction","detail":"{read_only:false; response_revision:53; number_of_response:1; }","duration":"113.647913ms","start":"2026-08-24T15:39:53.794651Z","end":"2026-08-24T15:39:53.908299Z","steps":["trace[1738821673] 'process raft request' (duration: 72.958876ms)","trace[1738821673] 'compare' (duration: 40.338241ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:54.141317Z","caller":"traceutil/trace.go:171","msg":"trace[1488527653] transaction","detail":"{read_only:false; response_revision:63; number_of_response:1; }","duration":"129.276458ms","start":"2026-08-24T15:39:54.012014Z","end":"2026-08-24T15:39:54.141291Z","steps":["trace[1488527653] 'process raft request' (duration: 129.238037ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:54.141524Z","caller":"traceutil/trace.go:171","msg":"trace[12865129] transaction","detail":"{read_only:false; response_revision:61; number_of_response:1; }","duration":"153.501045ms","start":"2026-08-24T15:39:53.987983Z","end":"2026-08-24T15:39:54.141484Z","steps":["trace[12865129] 'process raft request' (duration: 97.892479ms)","trace[12865129] 'compare' (duration: 55.195019ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:54.141646Z","caller":"traceutil/trace.go:171","msg":"trace[420923438] transaction","detail":"{read_only:false; response_revision:62; number_of_response:1; }","duration":"153.584137ms","start":"2026-08-24T15:39:53.988056Z","end":"2026-08-24T15:39:54.14164Z","steps":["trace[420923438] 'process raft request' (duration: 153.1476ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:54.365703Z","caller":"traceutil/trace.go:171","msg":"trace[1077036550] transaction","detail":"{read_only:false; response_revision:69; number_of_response:1; }","duration":"149.625415ms","start":"2026-08-24T15:39:54.216048Z","end":"2026-08-24T15:39:54.365674Z","steps":["trace[1077036550] 'process raft request' (duration: 123.830642ms)","trace[1077036550] 'compare' (duration: 25.462017ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:54.365778Z","caller":"traceutil/trace.go:171","msg":"trace[1661047990] linearizableReadLoop","detail":"{readStateIndex:75; appliedIndex:72; }","duration":"105.537972ms","start":"2026-08-24T15:39:54.260205Z","end":"2026-08-24T15:39:54.365743Z","steps":["trace[1661047990] 'read index received' (duration: 79.675428ms)","trace[1661047990] 'applied index is now lower than readState.Index' (duration: 25.861304ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:54.36605Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.812187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T15:39:54.366087Z","caller":"traceutil/trace.go:171","msg":"trace[541886332] transaction","detail":"{read_only:false; response_revision:70; number_of_response:1; }","duration":"150.032912ms","start":"2026-08-24T15:39:54.216044Z","end":"2026-08-24T15:39:54.366077Z","steps":["trace[541886332] 'process raft request' (duration: 149.532923ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:54.366106Z","caller":"traceutil/trace.go:171","msg":"trace[488070503] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:71; }","duration":"105.941009ms","start":"2026-08-24T15:39:54.260148Z","end":"2026-08-24T15:39:54.366089Z","steps":["trace[488070503] 'agreement among raft nodes before linearized reading' (duration: 105.716755ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:54.366114Z","caller":"traceutil/trace.go:171","msg":"trace[433723791] transaction","detail":"{read_only:false; response_revision:71; number_of_response:1; }","duration":"124.442583ms","start":"2026-08-24T15:39:54.241654Z","end":"2026-08-24T15:39:54.366097Z","steps":["trace[433723791] 'process raft request' (duration: 123.991006ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:54.522578Z","caller":"traceutil/trace.go:171","msg":"trace[1041910713] transaction","detail":"{read_only:false; response_revision:75; number_of_response:1; }","duration":"111.831212ms","start":"2026-08-24T15:39:54.410709Z","end":"2026-08-24T15:39:54.52254Z","steps":["trace[1041910713] 'process raft request' (duration: 111.789881ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:54.52271Z","caller":"traceutil/trace.go:171","msg":"trace[1075446152] transaction","detail":"{read_only:false; response_revision:73; number_of_response:1; }","duration":"150.687704ms","start":"2026-08-24T15:39:54.371973Z","end":"2026-08-24T15:39:54.522661Z","steps":["trace[1075446152] 'process raft request' (duration: 129.237198ms)","trace[1075446152] 'compare' (duration: 21.030289ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:54.522827Z","caller":"traceutil/trace.go:171","msg":"trace[213462434] transaction","detail":"{read_only:false; response_revision:74; number_of_response:1; }","duration":"149.451361ms","start":"2026-08-24T15:39:54.373338Z","end":"2026-08-24T15:39:54.52279Z","steps":["trace[213462434] 'process raft request' (duration: 149.099955ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:54.735226Z","caller":"traceutil/trace.go:171","msg":"trace[6477403] linearizableReadLoop","detail":"{readStateIndex:82; appliedIndex:81; }","duration":"169.976593ms","start":"2026-08-24T15:39:54.565206Z","end":"2026-08-24T15:39:54.735183Z","steps":["trace[6477403] 'read index received' (duration: 98.746774ms)","trace[6477403] 'applied index is now lower than readState.Index' (duration: 71.228569ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:54.735411Z","caller":"traceutil/trace.go:171","msg":"trace[2057662560] transaction","detail":"{read_only:false; response_revision:78; number_of_response:1; }","duration":"185.573966ms","start":"2026-08-24T15:39:54.549818Z","end":"2026-08-24T15:39:54.735392Z","steps":["trace[2057662560] 'process raft request' (duration: 114.203394ms)","trace[2057662560] 'compare' (duration: 71.068446ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:54.735568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.133245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/view\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T15:39:54.735714Z","caller":"traceutil/trace.go:171","msg":"trace[1655874261] range","detail":"{range_begin:/registry/clusterroles/view; range_end:; response_count:0; response_revision:78; }","duration":"170.562043ms","start":"2026-08-24T15:39:54.565133Z","end":"2026-08-24T15:39:54.735695Z","steps":["trace[1655874261] 'agreement among raft nodes before linearized reading' (duration: 170.163166ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:39:54.985596Z","caller":"traceutil/trace.go:171","msg":"trace[1657183117] linearizableReadLoop","detail":"{readStateIndex:86; appliedIndex:85; }","duration":"162.199765ms","start":"2026-08-24T15:39:54.823355Z","end":"2026-08-24T15:39:54.985554Z","steps":["trace[1657183117] 'read index received' (duration: 106.06955ms)","trace[1657183117] 'applied index is now lower than readState.Index' (duration: 56.128495ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:39:54.9858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.415558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:aggregate-to-edit\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-24T15:39:54.985812Z","caller":"traceutil/trace.go:171","msg":"trace[1736559416] transaction","detail":"{read_only:false; response_revision:82; number_of_response:1; }","duration":"181.764318ms","start":"2026-08-24T15:39:54.803843Z","end":"2026-08-24T15:39:54.985607Z","steps":["trace[1736559416] 'process raft request' (duration: 125.532181ms)","trace[1736559416] 'compare' (duration: 55.974692ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:39:54.986071Z","caller":"traceutil/trace.go:171","msg":"trace[306997674] range","detail":"{range_begin:/registry/clusterroles/system:aggregate-to-edit; range_end:; response_count:0; response_revision:82; }","duration":"162.549072ms","start":"2026-08-24T15:39:54.823302Z","end":"2026-08-24T15:39:54.985851Z","steps":["trace[306997674] 'agreement among raft nodes before linearized reading' (duration: 162.392838ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:28.889326Z","caller":"traceutil/trace.go:171","msg":"trace[1246468978] transaction","detail":"{read_only:false; response_revision:565; number_of_response:1; }","duration":"110.056506ms","start":"2026-08-24T15:40:28.779227Z","end":"2026-08-24T15:40:28.889283Z","steps":["trace[1246468978] 'process raft request' (duration: 109.688671ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:29.66569Z","caller":"traceutil/trace.go:171","msg":"trace[2101422906] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"114.315888ms","start":"2026-08-24T15:40:29.551346Z","end":"2026-08-24T15:40:29.665662Z","steps":["trace[2101422906] 'process raft request' (duration: 113.845971ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:30.856729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.673635ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:40:30.85753Z","caller":"traceutil/trace.go:171","msg":"trace[1071708882] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:650; }","duration":"785.127476ms","start":"2026-08-24T15:40:30.072368Z","end":"2026-08-24T15:40:30.857495Z","steps":["trace[1071708882] 'read index received' (duration: 245.719419ms)","trace[1071708882] 'applied index is now lower than readState.Index' (duration: 539.405267ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:40:30.857551Z","caller":"traceutil/trace.go:171","msg":"trace[1639024912] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"838.728922ms","start":"2026-08-24T15:40:30.01879Z","end":"2026-08-24T15:40:30.857518Z","steps":["trace[1639024912] 'process raft request' (duration: 299.186713ms)","trace[1639024912] 'compare' (duration: 538.551643ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:40:30.857774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"785.39228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" ","response":"range_response_count:1 size:29570"} {"level":"warn","ts":"2026-08-24T15:40:30.857817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:30.018755Z","time spent":"838.873904ms","remote":"127.0.0.1:48806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10353,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:40:30.857865Z","caller":"traceutil/trace.go:171","msg":"trace[2099273431] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io; range_end:; response_count:1; response_revision:630; }","duration":"785.513381ms","start":"2026-08-24T15:40:30.072331Z","end":"2026-08-24T15:40:30.857845Z","steps":["trace[2099273431] 'agreement among raft nodes before linearized reading' (duration: 785.256338ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:30.857972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:30.072312Z","time spent":"785.648483ms","remote":"127.0.0.1:48806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":29593,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/backendtlspolicies.gateway.networking.k8s.io\" "} {"level":"info","ts":"2026-08-24T15:40:30.868401Z","caller":"traceutil/trace.go:171","msg":"trace[76740638] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"727.607688ms","start":"2026-08-24T15:40:30.140776Z","end":"2026-08-24T15:40:30.868384Z","steps":["trace[76740638] 'process raft request' (duration: 727.378964ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:30.868494Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:30.140729Z","time spent":"727.73434ms","remote":"127.0.0.1:48806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":12244,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-24T15:40:30.870033Z","caller":"traceutil/trace.go:171","msg":"trace[2079891388] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"666.946753ms","start":"2026-08-24T15:40:30.203059Z","end":"2026-08-24T15:40:30.870006Z","steps":["trace[2079891388] 'process raft request' (duration: 666.08298ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:30.870729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:30.20302Z","time spent":"667.085315ms","remote":"127.0.0.1:48806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":570090,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:40:30.873542Z","caller":"traceutil/trace.go:171","msg":"trace[1833720577] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"331.156045ms","start":"2026-08-24T15:40:30.542339Z","end":"2026-08-24T15:40:30.873495Z","steps":["trace[1833720577] 'process raft request' (duration: 330.839101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:30.873722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:30.542311Z","time spent":"331.300968ms","remote":"127.0.0.1:48806","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23277,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-24T15:40:30.874211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.429251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:40:30.874287Z","caller":"traceutil/trace.go:171","msg":"trace[71550512] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io; range_end:; response_count:0; response_revision:634; }","duration":"536.538453ms","start":"2026-08-24T15:40:30.33772Z","end":"2026-08-24T15:40:30.874258Z","steps":["trace[71550512] 'agreement among raft nodes before linearized reading' (duration: 536.420101ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:30.874329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:30.3377Z","time spent":"536.618964ms","remote":"127.0.0.1:48806","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":28,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumloadbalancerippools.cilium.io\" "} {"level":"warn","ts":"2026-08-24T15:40:30.874372Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.746124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:40:30.874452Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"557.656408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:40:30.874469Z","caller":"traceutil/trace.go:171","msg":"trace[900664509] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:634; }","duration":"135.889735ms","start":"2026-08-24T15:40:30.738544Z","end":"2026-08-24T15:40:30.874434Z","steps":["trace[900664509] 'agreement among raft nodes before linearized reading' (duration: 135.691803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:30.874363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.00483ms","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-08-24T15:40:30.874614Z","caller":"traceutil/trace.go:171","msg":"trace[1011375649] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:634; }","duration":"299.291375ms","start":"2026-08-24T15:40:30.57531Z","end":"2026-08-24T15:40:30.874602Z","steps":["trace[1011375649] 'agreement among raft nodes before linearized reading' (duration: 298.96014ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:30.874515Z","caller":"traceutil/trace.go:171","msg":"trace[504662504] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"557.757609ms","start":"2026-08-24T15:40:30.316739Z","end":"2026-08-24T15:40:30.874497Z","steps":["trace[504662504] 'agreement among raft nodes before linearized reading' (duration: 557.684098ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:30.874659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:30.316717Z","time spent":"557.929592ms","remote":"127.0.0.1:48732","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-08-24T15:40:36.963171Z","caller":"traceutil/trace.go:171","msg":"trace[788958019] transaction","detail":"{read_only:false; response_revision:684; number_of_response:1; }","duration":"110.83803ms","start":"2026-08-24T15:40:36.852295Z","end":"2026-08-24T15:40:36.963133Z","steps":["trace[788958019] 'process raft request' (duration: 110.614707ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:37.922063Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4174731526632089582,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-24T15:40:38.195531Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"859.811539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T15:40:38.195723Z","caller":"traceutil/trace.go:171","msg":"trace[1101707064] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:686; }","duration":"860.025512ms","start":"2026-08-24T15:40:37.335667Z","end":"2026-08-24T15:40:38.195693Z","steps":["trace[1101707064] 'range keys from in-memory index tree' (duration: 859.654176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:38.19581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:37.335658Z","time spent":"860.137913ms","remote":"127.0.0.1:49020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-24T15:40:38.195568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.273381ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T15:40:38.195957Z","caller":"traceutil/trace.go:171","msg":"trace[1967790194] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:686; }","duration":"863.677327ms","start":"2026-08-24T15:40:37.33223Z","end":"2026-08-24T15:40:38.195907Z","steps":["trace[1967790194] 'range keys from in-memory index tree' (duration: 863.076478ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:38.196086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:37.332209Z","time spent":"863.832439ms","remote":"127.0.0.1:49020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-24T15:40:38.20158Z","caller":"traceutil/trace.go:171","msg":"trace[1276881246] linearizableReadLoop","detail":"{readStateIndex:710; appliedIndex:709; }","duration":"780.160613ms","start":"2026-08-24T15:40:37.421395Z","end":"2026-08-24T15:40:38.201555Z","steps":["trace[1276881246] 'read index received' (duration: 779.511793ms)","trace[1276881246] 'applied index is now lower than readState.Index' (duration: 647.73µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:40:38.201591Z","caller":"traceutil/trace.go:171","msg":"trace[1971547349] transaction","detail":"{read_only:false; response_revision:687; number_of_response:1; }","duration":"821.384767ms","start":"2026-08-24T15:40:37.380189Z","end":"2026-08-24T15:40:38.201574Z","steps":["trace[1971547349] 'process raft request' (duration: 820.844779ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:38.201898Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:37.38015Z","time spent":"821.64548ms","remote":"127.0.0.1:48832","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036225,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:40:38.201916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"780.519668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vn2qg\" ","response":"range_response_count:1 size:16961"} {"level":"info","ts":"2026-08-24T15:40:38.202125Z","caller":"traceutil/trace.go:171","msg":"trace[862059450] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vn2qg; range_end:; response_count:1; response_revision:687; }","duration":"780.61216ms","start":"2026-08-24T15:40:37.421346Z","end":"2026-08-24T15:40:38.201958Z","steps":["trace[862059450] 'agreement among raft nodes before linearized reading' (duration: 780.289295ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:38.202178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:37.42132Z","time spent":"780.846013ms","remote":"127.0.0.1:48936","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-vn2qg\" "} {"level":"warn","ts":"2026-08-24T15:40:38.203013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"487.112821ms","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-08-24T15:40:38.203086Z","caller":"traceutil/trace.go:171","msg":"trace[292100824] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:690; }","duration":"487.232153ms","start":"2026-08-24T15:40:37.715833Z","end":"2026-08-24T15:40:38.203065Z","steps":["trace[292100824] 'agreement among raft nodes before linearized reading' (duration: 486.98356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:38.203131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:40:37.7158Z","time spent":"487.320114ms","remote":"127.0.0.1:49020","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-24T15:40:53.255968Z","caller":"traceutil/trace.go:171","msg":"trace[1362025410] transaction","detail":"{read_only:false; response_revision:807; number_of_response:1; }","duration":"202.173856ms","start":"2026-08-24T15:40:53.053766Z","end":"2026-08-24T15:40:53.25594Z","steps":["trace[1362025410] 'process raft request' (duration: 202.031234ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:40:53.489272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.373054ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:40:53.489365Z","caller":"traceutil/trace.go:171","msg":"trace[211230372] linearizableReadLoop","detail":"{readStateIndex:835; appliedIndex:834; }","duration":"172.374902ms","start":"2026-08-24T15:40:53.316975Z","end":"2026-08-24T15:40:53.48935Z","steps":["trace[211230372] 'read index received' (duration: 5.788334ms)","trace[211230372] 'applied index is now lower than readState.Index' (duration: 166.585748ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:40:53.48943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.452533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:40:53.489455Z","caller":"traceutil/trace.go:171","msg":"trace[1715929804] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:808; }","duration":"172.540214ms","start":"2026-08-24T15:40:53.316907Z","end":"2026-08-24T15:40:53.489447Z","steps":["trace[1715929804] 'agreement among raft nodes before linearized reading' (duration: 172.480623ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:40:53.489677Z","caller":"traceutil/trace.go:171","msg":"trace[1619789877] transaction","detail":"{read_only:false; response_revision:808; number_of_response:1; }","duration":"204.851905ms","start":"2026-08-24T15:40:53.284779Z","end":"2026-08-24T15:40:53.489631Z","steps":["trace[1619789877] 'process raft request' (duration: 38.032533ms)","trace[1619789877] 'compare' (duration: 166.10242ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:41:42.72114Z","caller":"traceutil/trace.go:171","msg":"trace[1114785621] transaction","detail":"{read_only:false; response_revision:1459; number_of_response:1; }","duration":"144.535286ms","start":"2026-08-24T15:41:42.576584Z","end":"2026-08-24T15:41:42.721119Z","steps":["trace[1114785621] 'process raft request' (duration: 144.395394ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:41:42.726093Z","caller":"traceutil/trace.go:171","msg":"trace[789071060] linearizableReadLoop","detail":"{readStateIndex:1503; appliedIndex:1501; }","duration":"146.707245ms","start":"2026-08-24T15:41:42.579364Z","end":"2026-08-24T15:41:42.726071Z","steps":["trace[789071060] 'read index received' (duration: 141.626637ms)","trace[789071060] 'applied index is now lower than readState.Index' (duration: 5.079668ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:41:42.7262Z","caller":"traceutil/trace.go:171","msg":"trace[1562028710] transaction","detail":"{read_only:false; response_revision:1460; number_of_response:1; }","duration":"147.167702ms","start":"2026-08-24T15:41:42.579018Z","end":"2026-08-24T15:41:42.726186Z","steps":["trace[1562028710] 'process raft request' (duration: 146.938669ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:41:42.726314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.963309ms","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-08-24T15:41:42.726352Z","caller":"traceutil/trace.go:171","msg":"trace[231703916] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1460; }","duration":"147.04386ms","start":"2026-08-24T15:41:42.579296Z","end":"2026-08-24T15:41:42.72634Z","steps":["trace[231703916] 'agreement among raft nodes before linearized reading' (duration: 146.863167ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:07.128683Z","caller":"traceutil/trace.go:171","msg":"trace[1665765439] linearizableReadLoop","detail":"{readStateIndex:1936; appliedIndex:1935; }","duration":"246.371792ms","start":"2026-08-24T15:42:06.882282Z","end":"2026-08-24T15:42:07.128654Z","steps":["trace[1665765439] 'read index received' (duration: 245.875314ms)","trace[1665765439] 'applied index is now lower than readState.Index' (duration: 494.898µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:07.12875Z","caller":"traceutil/trace.go:171","msg":"trace[1029785462] transaction","detail":"{read_only:false; response_revision:1865; number_of_response:1; }","duration":"247.095583ms","start":"2026-08-24T15:42:06.881627Z","end":"2026-08-24T15:42:07.128722Z","steps":["trace[1029785462] 'process raft request' (duration: 246.804399ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:07.128998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.666575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T15:42:07.129071Z","caller":"traceutil/trace.go:171","msg":"trace[808945152] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1865; }","duration":"246.798837ms","start":"2026-08-24T15:42:06.882256Z","end":"2026-08-24T15:42:07.129055Z","steps":["trace[808945152] 'agreement among raft nodes before linearized reading' (duration: 246.577464ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:07.129173Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.596553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"warn","ts":"2026-08-24T15:42:07.12918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.616775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T15:42:07.129235Z","caller":"traceutil/trace.go:171","msg":"trace[103810769] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1865; }","duration":"239.668274ms","start":"2026-08-24T15:42:06.889531Z","end":"2026-08-24T15:42:07.129199Z","steps":["trace[103810769] 'agreement among raft nodes before linearized reading' (duration: 239.560192ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:07.129264Z","caller":"traceutil/trace.go:171","msg":"trace[1017826365] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1865; }","duration":"246.683936ms","start":"2026-08-24T15:42:06.882537Z","end":"2026-08-24T15:42:07.129221Z","steps":["trace[1017826365] 'agreement among raft nodes before linearized reading' (duration: 246.546364ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:07.129431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.881006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-15f56a82-bb28-4a99-bb9a-b8f3e665ea54\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-08-24T15:42:07.129476Z","caller":"traceutil/trace.go:171","msg":"trace[407613865] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-15f56a82-bb28-4a99-bb9a-b8f3e665ea54; range_end:; response_count:1; response_revision:1865; }","duration":"242.935846ms","start":"2026-08-24T15:42:06.886531Z","end":"2026-08-24T15:42:07.129467Z","steps":["trace[407613865] 'agreement among raft nodes before linearized reading' (duration: 242.802414ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:07.366544Z","caller":"traceutil/trace.go:171","msg":"trace[426370246] transaction","detail":"{read_only:false; response_revision:1866; number_of_response:1; }","duration":"233.108305ms","start":"2026-08-24T15:42:07.133384Z","end":"2026-08-24T15:42:07.366492Z","steps":["trace[426370246] 'process raft request' (duration: 218.971937ms)","trace[426370246] 'compare' (duration: 13.61395ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:07.403765Z","caller":"traceutil/trace.go:171","msg":"trace[512451185] transaction","detail":"{read_only:false; response_revision:1867; number_of_response:1; }","duration":"269.399485ms","start":"2026-08-24T15:42:07.134341Z","end":"2026-08-24T15:42:07.40374Z","steps":["trace[512451185] 'process raft request' (duration: 269.184942ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:07.403865Z","caller":"traceutil/trace.go:171","msg":"trace[824161699] linearizableReadLoop","detail":"{readStateIndex:1940; appliedIndex:1936; }","duration":"193.281618ms","start":"2026-08-24T15:42:07.210565Z","end":"2026-08-24T15:42:07.403847Z","steps":["trace[824161699] 'read index received' (duration: 141.803817ms)","trace[824161699] 'applied index is now lower than readState.Index' (duration: 51.476151ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:07.403765Z","caller":"traceutil/trace.go:171","msg":"trace[31174273] transaction","detail":"{read_only:false; response_revision:1868; number_of_response:1; }","duration":"269.336404ms","start":"2026-08-24T15:42:07.134408Z","end":"2026-08-24T15:42:07.403745Z","steps":["trace[31174273] 'process raft request' (duration: 269.239913ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:07.404165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.579362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-24T15:42:07.404248Z","caller":"traceutil/trace.go:171","msg":"trace[647185594] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1869; }","duration":"193.714573ms","start":"2026-08-24T15:42:07.210514Z","end":"2026-08-24T15:42:07.404228Z","steps":["trace[647185594] 'agreement among raft nodes before linearized reading' (duration: 193.409709ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:07.404741Z","caller":"traceutil/trace.go:171","msg":"trace[433378531] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"266.578307ms","start":"2026-08-24T15:42:07.138144Z","end":"2026-08-24T15:42:07.404722Z","steps":["trace[433378531] 'process raft request' (duration: 265.548864ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:16.295298Z","caller":"traceutil/trace.go:171","msg":"trace[472623605] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"165.442956ms","start":"2026-08-24T15:42:16.129821Z","end":"2026-08-24T15:42:16.295264Z","steps":["trace[472623605] 'process raft request' (duration: 97.217509ms)","trace[472623605] 'compare' (duration: 67.736211ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:42:20.425178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.964086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:42:20.425262Z","caller":"traceutil/trace.go:171","msg":"trace[1931166465] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1980; }","duration":"108.096708ms","start":"2026-08-24T15:42:20.317147Z","end":"2026-08-24T15:42:20.425244Z","steps":["trace[1931166465] 'range keys from in-memory index tree' (duration: 107.858415ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:42.300981Z","caller":"traceutil/trace.go:171","msg":"trace[1873171150] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"128.387017ms","start":"2026-08-24T15:42:42.172544Z","end":"2026-08-24T15:42:42.300931Z","steps":["trace[1873171150] 'process raft request' (duration: 101.935264ms)","trace[1873171150] 'compare' (duration: 26.317151ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:42.748726Z","caller":"traceutil/trace.go:171","msg":"trace[574755809] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"145.474168ms","start":"2026-08-24T15:42:42.603209Z","end":"2026-08-24T15:42:42.748683Z","steps":["trace[574755809] 'process raft request' (duration: 145.228025ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:43.367002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.545139ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-08-24T15:42:43.3671Z","caller":"traceutil/trace.go:171","msg":"trace[510220790] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2117; }","duration":"105.702391ms","start":"2026-08-24T15:42:43.261378Z","end":"2026-08-24T15:42:43.367081Z","steps":["trace[510220790] 'range keys from in-memory index tree' (duration: 105.316826ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:43.479534Z","caller":"traceutil/trace.go:171","msg":"trace[543487355] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"107.286542ms","start":"2026-08-24T15:42:43.372214Z","end":"2026-08-24T15:42:43.4795Z","steps":["trace[543487355] 'process raft request' (duration: 107.061199ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:43.518337Z","caller":"traceutil/trace.go:171","msg":"trace[816835845] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"145.859172ms","start":"2026-08-24T15:42:43.372444Z","end":"2026-08-24T15:42:43.518303Z","steps":["trace[816835845] 'process raft request' (duration: 145.70597ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:42:43.827762Z","caller":"traceutil/trace.go:171","msg":"trace[1632249766] linearizableReadLoop","detail":"{readStateIndex:2199; appliedIndex:2198; }","duration":"244.182387ms","start":"2026-08-24T15:42:43.583562Z","end":"2026-08-24T15:42:43.827745Z","steps":["trace[1632249766] 'read index received' (duration: 225.397203ms)","trace[1632249766] 'applied index is now lower than readState.Index' (duration: 18.784244ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:42:43.827805Z","caller":"traceutil/trace.go:171","msg":"trace[554216784] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"285.518504ms","start":"2026-08-24T15:42:43.542253Z","end":"2026-08-24T15:42:43.827772Z","steps":["trace[554216784] 'process raft request' (duration: 266.779851ms)","trace[554216784] 'compare' (duration: 18.390309ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:42:43.827962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.39796ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-24T15:42:43.827987Z","caller":"traceutil/trace.go:171","msg":"trace[847063786] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2120; }","duration":"244.484841ms","start":"2026-08-24T15:42:43.583496Z","end":"2026-08-24T15:42:43.827981Z","steps":["trace[847063786] 'agreement among raft nodes before linearized reading' (duration: 244.341029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:42:43.828061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.969708ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-24T15:42:43.828132Z","caller":"traceutil/trace.go:171","msg":"trace[2110012189] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2120; }","duration":"235.110161ms","start":"2026-08-24T15:42:43.593004Z","end":"2026-08-24T15:42:43.828115Z","steps":["trace[2110012189] 'agreement among raft nodes before linearized reading' (duration: 234.903538ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:43:46.545722Z","caller":"traceutil/trace.go:171","msg":"trace[1814499700] transaction","detail":"{read_only:false; response_revision:2563; number_of_response:1; }","duration":"130.251631ms","start":"2026-08-24T15:43:46.415444Z","end":"2026-08-24T15:43:46.545695Z","steps":["trace[1814499700] 'process raft request' (duration: 130.099389ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:44:19.17237Z","caller":"traceutil/trace.go:171","msg":"trace[1719190249] linearizableReadLoop","detail":"{readStateIndex:2844; appliedIndex:2843; }","duration":"182.752747ms","start":"2026-08-24T15:44:18.989589Z","end":"2026-08-24T15:44:19.172342Z","steps":["trace[1719190249] 'read index received' (duration: 182.576951ms)","trace[1719190249] 'applied index is now lower than readState.Index' (duration: 174.756µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:44:19.172568Z","caller":"traceutil/trace.go:171","msg":"trace[1316387072] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"195.35148ms","start":"2026-08-24T15:44:18.977199Z","end":"2026-08-24T15:44:19.17255Z","steps":["trace[1316387072] 'process raft request' (duration: 194.986867ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:44:19.172602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.942224ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:44:19.172699Z","caller":"traceutil/trace.go:171","msg":"trace[1359249022] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2743; }","duration":"183.144922ms","start":"2026-08-24T15:44:18.989535Z","end":"2026-08-24T15:44:19.17268Z","steps":["trace[1359249022] 'agreement among raft nodes before linearized reading' (duration: 182.947264ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:44:19.181696Z","caller":"traceutil/trace.go:171","msg":"trace[539772606] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"106.416046ms","start":"2026-08-24T15:44:19.075256Z","end":"2026-08-24T15:44:19.181672Z","steps":["trace[539772606] 'process raft request' (duration: 106.287161ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:15.428757Z","caller":"traceutil/trace.go:171","msg":"trace[1159666191] linearizableReadLoop","detail":"{readStateIndex:3967; appliedIndex:3964; }","duration":"110.731454ms","start":"2026-08-24T15:47:15.31801Z","end":"2026-08-24T15:47:15.428742Z","steps":["trace[1159666191] 'read index received' (duration: 16.493937ms)","trace[1159666191] 'applied index is now lower than readState.Index' (duration: 94.237027ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:47:15.429495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.467619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:47:15.429559Z","caller":"traceutil/trace.go:171","msg":"trace[1583621839] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3825; }","duration":"111.588702ms","start":"2026-08-24T15:47:15.317955Z","end":"2026-08-24T15:47:15.429544Z","steps":["trace[1583621839] 'agreement among raft nodes before linearized reading' (duration: 111.46674ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:15.429594Z","caller":"traceutil/trace.go:171","msg":"trace[905444732] transaction","detail":"{read_only:false; response_revision:3824; number_of_response:1; }","duration":"112.069322ms","start":"2026-08-24T15:47:15.317496Z","end":"2026-08-24T15:47:15.429565Z","steps":["trace[905444732] 'process raft request' (duration: 111.164734ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:47:15.429599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.541236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-08-24T15:47:15.429678Z","caller":"traceutil/trace.go:171","msg":"trace[703294509] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3825; }","duration":"101.669029ms","start":"2026-08-24T15:47:15.327986Z","end":"2026-08-24T15:47:15.429655Z","steps":["trace[703294509] 'agreement among raft nodes before linearized reading' (duration: 101.438334ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:47:15.429663Z","caller":"traceutil/trace.go:171","msg":"trace[304992725] transaction","detail":"{read_only:false; response_revision:3823; number_of_response:1; }","duration":"112.746986ms","start":"2026-08-24T15:47:15.316825Z","end":"2026-08-24T15:47:15.429572Z","steps":["trace[304992725] 'process raft request' (duration: 94.32513ms)","trace[304992725] 'compare' (duration: 17.260143ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:47:15.429885Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.309882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-24T15:47:15.429973Z","caller":"traceutil/trace.go:171","msg":"trace[1792760919] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3825; }","duration":"101.426914ms","start":"2026-08-24T15:47:15.328531Z","end":"2026-08-24T15:47:15.429958Z","steps":["trace[1792760919] 'agreement among raft nodes before linearized reading' (duration: 101.22154ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:03.182724Z","caller":"traceutil/trace.go:171","msg":"trace[524713659] transaction","detail":"{read_only:false; response_revision:4543; number_of_response:1; }","duration":"102.135445ms","start":"2026-08-24T15:48:03.080564Z","end":"2026-08-24T15:48:03.182699Z","steps":["trace[524713659] 'process raft request' (duration: 74.729178ms)","trace[524713659] 'compare' (duration: 26.920248ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:48:12.228611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.728099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18cec8717a73a78e\" ","response":"range_response_count:1 size:740"} {"level":"warn","ts":"2026-08-24T15:48:12.228686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.555374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"warn","ts":"2026-08-24T15:48:12.228874Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.467159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-08-24T15:48:12.228914Z","caller":"traceutil/trace.go:171","msg":"trace[1821522882] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:4769; }","duration":"150.540539ms","start":"2026-08-24T15:48:12.078364Z","end":"2026-08-24T15:48:12.228904Z","steps":["trace[1821522882] 'range keys from in-memory index tree' (duration: 150.307215ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:12.228786Z","caller":"traceutil/trace.go:171","msg":"trace[1279859686] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4769; }","duration":"306.712857ms","start":"2026-08-24T15:48:11.922053Z","end":"2026-08-24T15:48:12.228766Z","steps":["trace[1279859686] 'range keys from in-memory index tree' (duration: 306.458202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:12.229037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.715633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-24T15:48:12.229116Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:11.922038Z","time spent":"307.062283ms","remote":"127.0.0.1:49236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9660,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"info","ts":"2026-08-24T15:48:12.22872Z","caller":"traceutil/trace.go:171","msg":"trace[221535910] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18cec8717a73a78e; range_end:; response_count:1; response_revision:4769; }","duration":"311.895563ms","start":"2026-08-24T15:48:11.916804Z","end":"2026-08-24T15:48:12.228699Z","steps":["trace[221535910] 'range keys from in-memory index tree' (duration: 311.604947ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:12.22965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.4292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-08-24T15:48:12.229726Z","caller":"traceutil/trace.go:171","msg":"trace[1956233722] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4769; }","duration":"292.525472ms","start":"2026-08-24T15:48:11.937185Z","end":"2026-08-24T15:48:12.22971Z","steps":["trace[1956233722] 'range keys from in-memory index tree' (duration: 291.520534ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:12.229111Z","caller":"traceutil/trace.go:171","msg":"trace[496499009] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:4769; }","duration":"279.805655ms","start":"2026-08-24T15:48:11.949289Z","end":"2026-08-24T15:48:12.229094Z","steps":["trace[496499009] 'count revisions from in-memory index tree' (duration: 279.637632ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:12.229386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:11.916785Z","time spent":"312.584915ms","remote":"127.0.0.1:48812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":63,"response count":1,"response size":763,"request content":"key:\"/registry/events/openstack/rabbitmq-keystone.18cec8717a73a78e\" "} {"level":"info","ts":"2026-08-24T15:48:12.44737Z","caller":"traceutil/trace.go:171","msg":"trace[371194840] linearizableReadLoop","detail":"{readStateIndex:4948; appliedIndex:4947; }","duration":"214.685053ms","start":"2026-08-24T15:48:12.232638Z","end":"2026-08-24T15:48:12.447323Z","steps":["trace[371194840] 'read index received' (duration: 208.768524ms)","trace[371194840] 'applied index is now lower than readState.Index' (duration: 5.914849ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:48:12.447838Z","caller":"traceutil/trace.go:171","msg":"trace[782026917] transaction","detail":"{read_only:false; response_revision:4771; number_of_response:1; }","duration":"296.791719ms","start":"2026-08-24T15:48:12.151008Z","end":"2026-08-24T15:48:12.447799Z","steps":["trace[782026917] 'process raft request' (duration: 290.484552ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:12.448393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.737692ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dd494192-e4ab-438c-8c1b-2872c392ef98\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-24T15:48:12.448447Z","caller":"traceutil/trace.go:171","msg":"trace[166771874] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dd494192-e4ab-438c-8c1b-2872c392ef98; range_end:; response_count:1; response_revision:4771; }","duration":"215.864745ms","start":"2026-08-24T15:48:12.232569Z","end":"2026-08-24T15:48:12.448433Z","steps":["trace[166771874] 'agreement among raft nodes before linearized reading' (duration: 215.548779ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:12.44933Z","caller":"traceutil/trace.go:171","msg":"trace[382360071] transaction","detail":"{read_only:false; response_revision:4772; number_of_response:1; }","duration":"215.879665ms","start":"2026-08-24T15:48:12.233432Z","end":"2026-08-24T15:48:12.449311Z","steps":["trace[382360071] 'process raft request' (duration: 215.62168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:12.449538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.637553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-08-24T15:48:12.449644Z","caller":"traceutil/trace.go:171","msg":"trace[1266073971] transaction","detail":"{read_only:false; response_revision:4773; number_of_response:1; }","duration":"205.022553ms","start":"2026-08-24T15:48:12.244607Z","end":"2026-08-24T15:48:12.44963Z","steps":["trace[1266073971] 'process raft request' (duration: 204.640666ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:12.449668Z","caller":"traceutil/trace.go:171","msg":"trace[2138844059] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4773; }","duration":"184.877088ms","start":"2026-08-24T15:48:12.264767Z","end":"2026-08-24T15:48:12.449644Z","steps":["trace[2138844059] 'agreement among raft nodes before linearized reading' (duration: 184.555242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:12.449984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.570087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:48:12.450014Z","caller":"traceutil/trace.go:171","msg":"trace[819843178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4773; }","duration":"131.600487ms","start":"2026-08-24T15:48:12.318407Z","end":"2026-08-24T15:48:12.450007Z","steps":["trace[819843178] 'agreement among raft nodes before linearized reading' (duration: 131.554387ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:13.054514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.46729ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:48:13.054846Z","caller":"traceutil/trace.go:171","msg":"trace[241837266] transaction","detail":"{read_only:false; response_revision:4778; number_of_response:1; }","duration":"348.305577ms","start":"2026-08-24T15:48:12.706477Z","end":"2026-08-24T15:48:13.054783Z","steps":["trace[241837266] 'process raft request' (duration: 85.4712ms)","trace[241837266] 'compare' (duration: 261.96964ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:48:13.054975Z","caller":"traceutil/trace.go:171","msg":"trace[2104841385] transaction","detail":"{read_only:false; response_revision:4780; number_of_response:1; }","duration":"348.175983ms","start":"2026-08-24T15:48:12.70678Z","end":"2026-08-24T15:48:13.054956Z","steps":["trace[2104841385] 'process raft request' (duration: 348.00324ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:13.055044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:12.706462Z","time spent":"348.462939ms","remote":"127.0.0.1:49034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1454,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:48:13.055072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:12.706766Z","time spent":"348.258825ms","remote":"127.0.0.1:48910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":905,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:48:13.055107Z","caller":"traceutil/trace.go:171","msg":"trace[1880427718] transaction","detail":"{read_only:false; response_revision:4779; number_of_response:1; }","duration":"348.402168ms","start":"2026-08-24T15:48:12.706673Z","end":"2026-08-24T15:48:13.055075Z","steps":["trace[1880427718] 'process raft request' (duration: 347.96283ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:13.055125Z","caller":"traceutil/trace.go:171","msg":"trace[589204185] transaction","detail":"{read_only:false; response_revision:4781; number_of_response:1; }","duration":"348.173563ms","start":"2026-08-24T15:48:12.706914Z","end":"2026-08-24T15:48:13.055087Z","steps":["trace[589204185] 'process raft request' (duration: 347.937339ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:13.055197Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:12.706645Z","time spent":"348.50717ms","remote":"127.0.0.1:49034","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1868,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:48:13.055256Z","caller":"traceutil/trace.go:171","msg":"trace[618405415] linearizableReadLoop","detail":"{readStateIndex:4958; appliedIndex:4954; }","duration":"347.057073ms","start":"2026-08-24T15:48:12.708181Z","end":"2026-08-24T15:48:13.055238Z","steps":["trace[618405415] 'read index received' (duration: 83.934191ms)","trace[618405415] 'applied index is now lower than readState.Index' (duration: 263.121342ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:48:13.055325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:12.706906Z","time spent":"348.315846ms","remote":"127.0.0.1:48910","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1282,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-24T15:48:13.055345Z","caller":"traceutil/trace.go:171","msg":"trace[2129055042] transaction","detail":"{read_only:false; response_revision:4782; number_of_response:1; }","duration":"338.426362ms","start":"2026-08-24T15:48:12.71688Z","end":"2026-08-24T15:48:13.055307Z","steps":["trace[2129055042] 'process raft request' (duration: 338.019565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:13.055544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:12.716864Z","time spent":"338.574606ms","remote":"127.0.0.1:48936","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16537,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:48:13.055543Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.328138ms","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":"warn","ts":"2026-08-24T15:48:13.055569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.62303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"warn","ts":"2026-08-24T15:48:13.055598Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.950285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-dd494192-e4ab-438c-8c1b-2872c392ef98\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-08-24T15:48:13.05564Z","caller":"traceutil/trace.go:171","msg":"trace[2050092116] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-dd494192-e4ab-438c-8c1b-2872c392ef98; range_end:; response_count:1; response_revision:4782; }","duration":"244.029607ms","start":"2026-08-24T15:48:12.811599Z","end":"2026-08-24T15:48:13.055628Z","steps":["trace[2050092116] 'agreement among raft nodes before linearized reading' (duration: 243.869784ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:13.055641Z","caller":"traceutil/trace.go:171","msg":"trace[807754156] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4782; }","duration":"179.728731ms","start":"2026-08-24T15:48:12.875895Z","end":"2026-08-24T15:48:13.055623Z","steps":["trace[807754156] 'agreement among raft nodes before linearized reading' (duration: 179.548858ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:13.055655Z","caller":"traceutil/trace.go:171","msg":"trace[236679304] 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:4782; }","duration":"347.44303ms","start":"2026-08-24T15:48:12.708158Z","end":"2026-08-24T15:48:13.055601Z","steps":["trace[236679304] 'agreement among raft nodes before linearized reading' (duration: 347.178235ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:13.055763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:12.70815Z","time spent":"347.600842ms","remote":"127.0.0.1:48898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":139,"response count":1,"response size":2105,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" "} {"level":"warn","ts":"2026-08-24T15:48:13.056851Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.061727ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-08-24T15:48:13.056928Z","caller":"traceutil/trace.go:171","msg":"trace[824999557] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4782; }","duration":"287.193341ms","start":"2026-08-24T15:48:12.769715Z","end":"2026-08-24T15:48:13.056908Z","steps":["trace[824999557] 'agreement among raft nodes before linearized reading' (duration: 285.662442ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:24.699233Z","caller":"traceutil/trace.go:171","msg":"trace[1752922151] linearizableReadLoop","detail":"{readStateIndex:5114; appliedIndex:5113; }","duration":"396.304662ms","start":"2026-08-24T15:48:24.302897Z","end":"2026-08-24T15:48:24.699202Z","steps":["trace[1752922151] 'read index received' (duration: 396.253241ms)","trace[1752922151] 'applied index is now lower than readState.Index' (duration: 50.241µs)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:48:24.699319Z","caller":"traceutil/trace.go:171","msg":"trace[735745346] transaction","detail":"{read_only:false; response_revision:4932; number_of_response:1; }","duration":"433.166396ms","start":"2026-08-24T15:48:24.266111Z","end":"2026-08-24T15:48:24.699277Z","steps":["trace[735745346] 'process raft request' (duration: 432.947422ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:24.699479Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:24.266084Z","time spent":"433.31135ms","remote":"127.0.0.1:49020","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-24T15:48:24.699737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.802992ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-8knc6.18cec87004f862c7\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-08-24T15:48:24.699912Z","caller":"traceutil/trace.go:171","msg":"trace[1944534978] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-8knc6.18cec87004f862c7; range_end:; response_count:1; response_revision:4932; }","duration":"396.936934ms","start":"2026-08-24T15:48:24.302889Z","end":"2026-08-24T15:48:24.699826Z","steps":["trace[1944534978] 'agreement among raft nodes before linearized reading' (duration: 396.439354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:24.700404Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:24.302865Z","time spent":"397.524795ms","remote":"127.0.0.1:48812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":929,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-8knc6.18cec87004f862c7\" "} {"level":"info","ts":"2026-08-24T15:48:24.831498Z","caller":"traceutil/trace.go:171","msg":"trace[1211334065] linearizableReadLoop","detail":"{readStateIndex:5115; appliedIndex:5114; }","duration":"132.179529ms","start":"2026-08-24T15:48:24.699301Z","end":"2026-08-24T15:48:24.831481Z","steps":["trace[1211334065] 'read index received' (duration: 55.491275ms)","trace[1211334065] 'applied index is now lower than readState.Index' (duration: 76.687454ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:48:24.83165Z","caller":"traceutil/trace.go:171","msg":"trace[528079615] transaction","detail":"{read_only:false; response_revision:4933; number_of_response:1; }","duration":"267.302833ms","start":"2026-08-24T15:48:24.564285Z","end":"2026-08-24T15:48:24.831588Z","steps":["trace[528079615] 'process raft request' (duration: 190.493637ms)","trace[528079615] 'compare' (duration: 76.523561ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:48:24.831656Z","caller":"traceutil/trace.go:171","msg":"trace[792704689] transaction","detail":"{read_only:false; response_revision:4934; number_of_response:1; }","duration":"128.628884ms","start":"2026-08-24T15:48:24.703007Z","end":"2026-08-24T15:48:24.831636Z","steps":["trace[792704689] 'process raft request' (duration: 128.436171ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:24.831941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.282064ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-24T15:48:24.831974Z","caller":"traceutil/trace.go:171","msg":"trace[1139426319] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:4934; }","duration":"495.342625ms","start":"2026-08-24T15:48:24.33662Z","end":"2026-08-24T15:48:24.831963Z","steps":["trace[1139426319] 'agreement among raft nodes before linearized reading' (duration: 495.217653ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:24.831996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:24.336611Z","time spent":"495.377915ms","remote":"127.0.0.1:59718","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":28,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-08-24T15:48:24.832042Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.333188ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-24T15:48:24.832139Z","caller":"traceutil/trace.go:171","msg":"trace[1450970749] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4934; }","duration":"517.46604ms","start":"2026-08-24T15:48:24.314653Z","end":"2026-08-24T15:48:24.832119Z","steps":["trace[1450970749] 'agreement among raft nodes before linearized reading' (duration: 517.315457ms)"],"step_count":1} {"level":"warn","ts":"2026-08-24T15:48:24.832181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-24T15:48:24.314645Z","time spent":"517.526251ms","remote":"127.0.0.1:48732","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-08-24T15:48:24.996347Z","caller":"traceutil/trace.go:171","msg":"trace[1131683168] transaction","detail":"{read_only:false; response_revision:4938; number_of_response:1; }","duration":"101.697262ms","start":"2026-08-24T15:48:24.894619Z","end":"2026-08-24T15:48:24.996316Z","steps":["trace[1131683168] 'process raft request' (duration: 83.446477ms)","trace[1131683168] 'compare' (duration: 17.990499ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:48:24.996526Z","caller":"traceutil/trace.go:171","msg":"trace[1553984892] transaction","detail":"{read_only:false; response_revision:4940; number_of_response:1; }","duration":"101.702462ms","start":"2026-08-24T15:48:24.894799Z","end":"2026-08-24T15:48:24.996502Z","steps":["trace[1553984892] 'process raft request' (duration: 101.449336ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:24.997033Z","caller":"traceutil/trace.go:171","msg":"trace[105012562] transaction","detail":"{read_only:false; response_revision:4939; number_of_response:1; }","duration":"102.303233ms","start":"2026-08-24T15:48:24.894689Z","end":"2026-08-24T15:48:24.996992Z","steps":["trace[105012562] 'process raft request' (duration: 101.507728ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:25.235334Z","caller":"traceutil/trace.go:171","msg":"trace[1845535537] transaction","detail":"{read_only:false; response_revision:4949; number_of_response:1; }","duration":"143.123026ms","start":"2026-08-24T15:48:25.092188Z","end":"2026-08-24T15:48:25.235311Z","steps":["trace[1845535537] 'process raft request' (duration: 143.053325ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:48:25.235426Z","caller":"traceutil/trace.go:171","msg":"trace[803636303] transaction","detail":"{read_only:false; response_revision:4948; number_of_response:1; }","duration":"144.455861ms","start":"2026-08-24T15:48:25.090932Z","end":"2026-08-24T15:48:25.235388Z","steps":["trace[803636303] 'process raft request' (duration: 80.805917ms)","trace[803636303] 'compare' (duration: 63.378069ms)"],"step_count":2} {"level":"warn","ts":"2026-08-24T15:49:01.174239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.148411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-24T15:49:01.174361Z","caller":"traceutil/trace.go:171","msg":"trace[141996709] transaction","detail":"{read_only:false; response_revision:5280; number_of_response:1; }","duration":"165.949401ms","start":"2026-08-24T15:49:01.00839Z","end":"2026-08-24T15:49:01.174339Z","steps":["trace[141996709] 'process raft request' (duration: 62.640429ms)","trace[141996709] 'compare' (duration: 103.020687ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:49:03.04067Z","caller":"traceutil/trace.go:171","msg":"trace[1077838763] transaction","detail":"{read_only:false; response_revision:5289; number_of_response:1; }","duration":"112.44024ms","start":"2026-08-24T15:49:02.928202Z","end":"2026-08-24T15:49:03.040642Z","steps":["trace[1077838763] 'process raft request' (duration: 112.285057ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:49:49.14029Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2901} {"level":"info","ts":"2026-08-24T15:49:49.242503Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2901,"took":"100.415352ms","hash":4144812020,"current-db-size-bytes":54013952,"current-db-size":"54 MB","current-db-size-in-use-bytes":29548544,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-24T15:49:49.242566Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4144812020,"revision":2901,"compact-revision":-1} {"level":"info","ts":"2026-08-24T15:51:23.074693Z","caller":"traceutil/trace.go:171","msg":"trace[1951665241] transaction","detail":"{read_only:false; response_revision:6174; number_of_response:1; }","duration":"125.330982ms","start":"2026-08-24T15:51:22.949343Z","end":"2026-08-24T15:51:23.074674Z","steps":["trace[1951665241] 'process raft request' (duration: 125.20419ms)"],"step_count":1} {"level":"info","ts":"2026-08-24T15:52:20.697371Z","caller":"traceutil/trace.go:171","msg":"trace[2013185121] transaction","detail":"{read_only:false; response_revision:6687; number_of_response:1; }","duration":"130.004602ms","start":"2026-08-24T15:52:20.567336Z","end":"2026-08-24T15:52:20.697341Z","steps":["trace[2013185121] 'process raft request' (duration: 119.11048ms)","trace[2013185121] 'compare' (duration: 10.650648ms)"],"step_count":2} {"level":"info","ts":"2026-08-24T15:54:49.147303Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5638} {"level":"info","ts":"2026-08-24T15:54:49.226826Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5638,"took":"77.628826ms","hash":3341255129,"current-db-size-bytes":54013952,"current-db-size":"54 MB","current-db-size-in-use-bytes":18452480,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-08-24T15:54:49.226958Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3341255129,"revision":5638,"compact-revision":2901}