{"level":"warn","ts":"2026-04-20T08:05:39.334857Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T08:05:39.335012Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.243: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.243:2380","--initial-cluster=instance=https://199.19.213.243:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.243:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.243:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-20T08:05:39.335166Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-20T08:05:39.335183Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-04-20T08:05:39.335231Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T08:05:39.33641Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"]} {"level":"info","ts":"2026-04-20T08:05:39.336545Z","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.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.243:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-20T08:05:39.341085Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.149997ms"} {"level":"info","ts":"2026-04-20T08:05:39.356238Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"864e14457b828f9c","cluster-id":"6c0ae60a355554b9"} {"level":"info","ts":"2026-04-20T08:05:39.356327Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=()"} {"level":"info","ts":"2026-04-20T08:05:39.356351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 0"} {"level":"info","ts":"2026-04-20T08:05:39.356365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 864e14457b828f9c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-20T08:05:39.356377Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became follower at term 1"} {"level":"info","ts":"2026-04-20T08:05:39.356448Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"warn","ts":"2026-04-20T08:05:39.369743Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-20T08:05:39.371885Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-20T08:05:39.375296Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-20T08:05:39.378365Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"864e14457b828f9c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-20T08:05:39.378699Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T08:05:39.378753Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T08:05:39.378763Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-20T08:05:39.379133Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"864e14457b828f9c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-20T08:05:39.382207Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c switched to configuration voters=(9677694937923227548)"} {"level":"info","ts":"2026-04-20T08:05:39.382515Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","added-peer-id":"864e14457b828f9c","added-peer-peer-urls":["https://199.19.213.243:2380"]} {"level":"info","ts":"2026-04-20T08:05:39.385164Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-20T08:05:39.385437Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"864e14457b828f9c","initial-advertise-peer-urls":["https://199.19.213.243:2380"],"listen-peer-urls":["https://199.19.213.243:2380"],"advertise-client-urls":["https://199.19.213.243:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.243:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-20T08:05:39.385492Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-20T08:05:39.385456Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-04-20T08:05:39.38561Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.243:2380"} {"level":"info","ts":"2026-04-20T08:05:39.957457Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c is starting a new election at term 1"} {"level":"info","ts":"2026-04-20T08:05:39.957599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became pre-candidate at term 1"} {"level":"info","ts":"2026-04-20T08:05:39.957623Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgPreVoteResp from 864e14457b828f9c at term 1"} {"level":"info","ts":"2026-04-20T08:05:39.957731Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became candidate at term 2"} {"level":"info","ts":"2026-04-20T08:05:39.957757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c received MsgVoteResp from 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-04-20T08:05:39.957796Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"864e14457b828f9c became leader at term 2"} {"level":"info","ts":"2026-04-20T08:05:39.95782Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 864e14457b828f9c elected leader 864e14457b828f9c at term 2"} {"level":"info","ts":"2026-04-20T08:05:39.960201Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"864e14457b828f9c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.243:2379]}","request-path":"/0/members/864e14457b828f9c/attributes","cluster-id":"6c0ae60a355554b9","publish-timeout":"7s"} {"level":"info","ts":"2026-04-20T08:05:39.962121Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:05:39.962313Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-20T08:05:39.962686Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:05:39.963002Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-20T08:05:39.96308Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-20T08:05:39.964332Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.243:2379"} {"level":"info","ts":"2026-04-20T08:05:39.966828Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-20T08:05:39.970622Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"6c0ae60a355554b9","local-member-id":"864e14457b828f9c","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:05:39.97103Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:05:39.971066Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-20T08:05:41.588972Z","caller":"traceutil/trace.go:171","msg":"trace[282966924] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"242.926799ms","start":"2026-04-20T08:05:41.346013Z","end":"2026-04-20T08:05:41.58894Z","steps":["trace[282966924] 'process raft request' (duration: 242.570845ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.767393Z","caller":"traceutil/trace.go:171","msg":"trace[243286909] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"420.285007ms","start":"2026-04-20T08:05:41.347083Z","end":"2026-04-20T08:05:41.767368Z","steps":["trace[243286909] 'process raft request' (duration: 419.998589ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.767408Z","caller":"traceutil/trace.go:171","msg":"trace[1801446484] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"348.608452ms","start":"2026-04-20T08:05:41.41875Z","end":"2026-04-20T08:05:41.767358Z","steps":["trace[1801446484] 'process raft request' (duration: 348.450859ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.767684Z","caller":"traceutil/trace.go:171","msg":"trace[1500749240] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"418.496046ms","start":"2026-04-20T08:05:41.349161Z","end":"2026-04-20T08:05:41.767657Z","steps":["trace[1500749240] 'read index received' (duration: 239.52582ms)","trace[1500749240] 'applied index is now lower than readState.Index' (duration: 178.967996ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:05:41.76782Z","caller":"traceutil/trace.go:171","msg":"trace[937387586] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"344.602838ms","start":"2026-04-20T08:05:41.423204Z","end":"2026-04-20T08:05:41.767807Z","steps":["trace[937387586] 'process raft request' (duration: 344.208666ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.76786Z","caller":"traceutil/trace.go:171","msg":"trace[747939322] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"344.984012ms","start":"2026-04-20T08:05:41.42272Z","end":"2026-04-20T08:05:41.767704Z","steps":["trace[747939322] 'process raft request' (duration: 344.665136ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.767909Z","caller":"traceutil/trace.go:171","msg":"trace[876270473] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"325.852661ms","start":"2026-04-20T08:05:41.442047Z","end":"2026-04-20T08:05:41.7679Z","steps":["trace[876270473] 'process raft request' (duration: 325.569034ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.768001Z","caller":"traceutil/trace.go:171","msg":"trace[1409939396] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"347.730451ms","start":"2026-04-20T08:05:41.420243Z","end":"2026-04-20T08:05:41.767974Z","steps":["trace[1409939396] 'process raft request' (duration: 346.995263ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.768053Z","caller":"traceutil/trace.go:171","msg":"trace[1483894487] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"346.771113ms","start":"2026-04-20T08:05:41.421275Z","end":"2026-04-20T08:05:41.768046Z","steps":["trace[1483894487] 'process raft request' (duration: 345.995157ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.768102Z","caller":"traceutil/trace.go:171","msg":"trace[1021618000] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"329.712112ms","start":"2026-04-20T08:05:41.438383Z","end":"2026-04-20T08:05:41.768095Z","steps":["trace[1021618000] 'process raft request' (duration: 329.196104ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:05:41.768234Z","caller":"traceutil/trace.go:171","msg":"trace[1528840049] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"343.786624ms","start":"2026-04-20T08:05:41.424431Z","end":"2026-04-20T08:05:41.768217Z","steps":["trace[1528840049] 'process raft request' (duration: 343.008499ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.768636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.454863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-04-20T08:05:41.768963Z","caller":"traceutil/trace.go:171","msg":"trace[2112464701] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"419.794239ms","start":"2026-04-20T08:05:41.349148Z","end":"2026-04-20T08:05:41.768942Z","steps":["trace[2112464701] 'agreement among raft nodes before linearized reading' (duration: 419.005483ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.768959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"351.688377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.243\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-20T08:05:41.769011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.349142Z","time spent":"419.859436ms","remote":"127.0.0.1:58764","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3194,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-20T08:05:41.769061Z","caller":"traceutil/trace.go:171","msg":"trace[319956866] range","detail":"{range_begin:/registry/masterleases/199.19.213.243; range_end:; response_count:0; response_revision:11; }","duration":"351.816671ms","start":"2026-04-20T08:05:41.417226Z","end":"2026-04-20T08:05:41.769042Z","steps":["trace[319956866] 'agreement among raft nodes before linearized reading' (duration: 351.704026ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.769117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.417203Z","time spent":"351.906587ms","remote":"127.0.0.1:58608","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":0,"response size":27,"request content":"key:\"/registry/masterleases/199.19.213.243\" "} {"level":"warn","ts":"2026-04-20T08:05:41.768845Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.572695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T08:05:41.769349Z","caller":"traceutil/trace.go:171","msg":"trace[854420558] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"367.383438ms","start":"2026-04-20T08:05:41.401953Z","end":"2026-04-20T08:05:41.769336Z","steps":["trace[854420558] 'agreement among raft nodes before linearized reading' (duration: 366.42378ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.769406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.401806Z","time spent":"367.569791ms","remote":"127.0.0.1:58696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-20T08:05:41.769668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.310627ms","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-04-20T08:05:41.76983Z","caller":"traceutil/trace.go:171","msg":"trace[1315519635] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"350.445302ms","start":"2026-04-20T08:05:41.419313Z","end":"2026-04-20T08:05:41.769758Z","steps":["trace[1315519635] 'agreement among raft nodes before linearized reading' (duration: 349.151019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.770166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.419299Z","time spent":"350.832464ms","remote":"127.0.0.1:58672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" "} {"level":"warn","ts":"2026-04-20T08:05:41.770774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.423176Z","time spent":"344.707694ms","remote":"127.0.0.1:59108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T08:05:41.77081Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.422657Z","time spent":"345.280629ms","remote":"127.0.0.1:59108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T08:05:41.769929Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.364343ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-20T08:05:41.771103Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.347072Z","time spent":"420.399392ms","remote":"127.0.0.1:58640","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-04-20T08:05:41.771121Z","caller":"traceutil/trace.go:171","msg":"trace[1802616801] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"362.57703ms","start":"2026-04-20T08:05:41.408527Z","end":"2026-04-20T08:05:41.771104Z","steps":["trace[1802616801] 'agreement among raft nodes before linearized reading' (duration: 361.335394ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.771164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.418736Z","time spent":"348.826383ms","remote":"127.0.0.1:59108","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-04-20T08:05:41.771243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.408516Z","time spent":"362.708783ms","remote":"127.0.0.1:58696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-20T08:05:41.770503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"362.084001ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-04-20T08:05:41.771507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.420233Z","time spent":"347.804157ms","remote":"127.0.0.1:59028","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":698,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T08:05:41.77147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.442032Z","time spent":"325.980785ms","remote":"127.0.0.1:58850","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-04-20T08:05:41.77151Z","caller":"traceutil/trace.go:171","msg":"trace[100994028] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"363.097667ms","start":"2026-04-20T08:05:41.408371Z","end":"2026-04-20T08:05:41.771468Z","steps":["trace[100994028] 'agreement among raft nodes before linearized reading' (duration: 361.561684ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.773652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.408364Z","time spent":"365.271232ms","remote":"127.0.0.1:58696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-20T08:05:41.769955Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.541145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T08:05:41.773828Z","caller":"traceutil/trace.go:171","msg":"trace[1812898706] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"303.463063ms","start":"2026-04-20T08:05:41.470346Z","end":"2026-04-20T08:05:41.773809Z","steps":["trace[1812898706] 'agreement among raft nodes before linearized reading' (duration: 299.466748ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.773887Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.470334Z","time spent":"303.53653ms","remote":"127.0.0.1:58696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-20T08:05:41.770171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.179257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-04-20T08:05:41.77398Z","caller":"traceutil/trace.go:171","msg":"trace[1827236754] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"353.187732ms","start":"2026-04-20T08:05:41.420783Z","end":"2026-04-20T08:05:41.773971Z","steps":["trace[1827236754] 'agreement among raft nodes before linearized reading' (duration: 349.076562ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:05:41.774013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.420755Z","time spent":"353.249788ms","remote":"127.0.0.1:58696","response type":"/etcdserverpb.KV/Range","request count":0,"request size":34,"response count":0,"response size":27,"request content":"key:\"/registry/namespaces/kube-system\" "} {"level":"warn","ts":"2026-04-20T08:05:41.771667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.421252Z","time spent":"346.817871ms","remote":"127.0.0.1:59108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T08:05:41.771722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.438368Z","time spent":"329.763359ms","remote":"127.0.0.1:58764","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3159,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-20T08:05:41.771761Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:05:41.424414Z","time spent":"343.868191ms","remote":"127.0.0.1:59108","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":971,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-20T08:06:10.121087Z","caller":"traceutil/trace.go:171","msg":"trace[2030442505] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"115.487845ms","start":"2026-04-20T08:06:10.005574Z","end":"2026-04-20T08:06:10.121061Z","steps":["trace[2030442505] 'process raft request' (duration: 109.315972ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:06:10.121336Z","caller":"traceutil/trace.go:171","msg":"trace[2136190678] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"111.32155ms","start":"2026-04-20T08:06:10.00997Z","end":"2026-04-20T08:06:10.121291Z","steps":["trace[2136190678] 'process raft request' (duration: 111.03909ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:06:16.678329Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10348319396932722902,"retry-timeout":"500ms"} {"level":"info","ts":"2026-04-20T08:06:17.111779Z","caller":"traceutil/trace.go:171","msg":"trace[1179488164] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"978.340873ms","start":"2026-04-20T08:06:16.133405Z","end":"2026-04-20T08:06:17.111746Z","steps":["trace[1179488164] 'process raft request' (duration: 978.135362ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:06:17.111934Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:06:16.133368Z","time spent":"978.486894ms","remote":"127.0.0.1:58772","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:06:17.168934Z","caller":"traceutil/trace.go:171","msg":"trace[419590658] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:605; }","duration":"991.610497ms","start":"2026-04-20T08:06:16.177293Z","end":"2026-04-20T08:06:17.168904Z","steps":["trace[419590658] 'read index received' (duration: 934.93272ms)","trace[419590658] 'applied index is now lower than readState.Index' (duration: 56.676477ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:06:17.169023Z","caller":"traceutil/trace.go:171","msg":"trace[966835607] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"1.002025665s","start":"2026-04-20T08:06:16.166971Z","end":"2026-04-20T08:06:17.168997Z","steps":["trace[966835607] 'process raft request' (duration: 1.001120399s)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:06:17.16974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:06:16.166934Z","time spent":"1.00272109s","remote":"127.0.0.1:58684","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036301,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-20T08:06:17.169241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"991.937019ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-04-20T08:06:17.169212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.03752ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:06:17.170023Z","caller":"traceutil/trace.go:171","msg":"trace[210372272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"992.744955ms","start":"2026-04-20T08:06:16.177258Z","end":"2026-04-20T08:06:17.170003Z","steps":["trace[210372272] 'agreement among raft nodes before linearized reading' (duration: 991.842619ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:06:17.170119Z","caller":"traceutil/trace.go:171","msg":"trace[2052209118] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:584; }","duration":"926.986477ms","start":"2026-04-20T08:06:16.243112Z","end":"2026-04-20T08:06:17.170098Z","steps":["trace[2052209118] 'agreement among raft nodes before linearized reading' (duration: 926.01151ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:06:17.170168Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:06:16.243095Z","time spent":"927.056617ms","remote":"127.0.0.1:58578","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-20T08:06:17.170123Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:06:16.177237Z","time spent":"992.869806ms","remote":"127.0.0.1:58866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-20T08:06:17.169286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"802.58034ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-20T08:06:17.170473Z","caller":"traceutil/trace.go:171","msg":"trace[197020972] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:584; }","duration":"803.810408ms","start":"2026-04-20T08:06:16.366645Z","end":"2026-04-20T08:06:17.170455Z","steps":["trace[197020972] 'agreement among raft nodes before linearized reading' (duration: 802.508599ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:06:17.170604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:06:16.366622Z","time spent":"803.93286ms","remote":"127.0.0.1:58866","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-20T08:06:33.313808Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.844105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.243\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-04-20T08:06:33.313872Z","caller":"traceutil/trace.go:171","msg":"trace[1601175407] range","detail":"{range_begin:/registry/masterleases/199.19.213.243; range_end:; response_count:1; response_revision:747; }","duration":"235.969317ms","start":"2026-04-20T08:06:33.07789Z","end":"2026-04-20T08:06:33.313859Z","steps":["trace[1601175407] 'range keys from in-memory index tree' (duration: 235.507547ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:06:33.352235Z","caller":"traceutil/trace.go:171","msg":"trace[718742078] transaction","detail":"{read_only:false; response_revision:748; number_of_response:1; }","duration":"144.085092ms","start":"2026-04-20T08:06:33.20813Z","end":"2026-04-20T08:06:33.352215Z","steps":["trace[718742078] 'process raft request' (duration: 143.916528ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:06:33.352274Z","caller":"traceutil/trace.go:171","msg":"trace[900664737] linearizableReadLoop","detail":"{readStateIndex:774; appliedIndex:774; }","duration":"138.666956ms","start":"2026-04-20T08:06:33.21358Z","end":"2026-04-20T08:06:33.352246Z","steps":["trace[900664737] 'read index received' (duration: 138.643946ms)","trace[900664737] 'applied index is now lower than readState.Index' (duration: 17.52µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:06:33.35263Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.042494ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-sz7ph\" ","response":"range_response_count:1 size:3965"} {"level":"info","ts":"2026-04-20T08:06:33.352684Z","caller":"traceutil/trace.go:171","msg":"trace[1595879790] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-sz7ph; range_end:; response_count:1; response_revision:748; }","duration":"139.146786ms","start":"2026-04-20T08:06:33.213521Z","end":"2026-04-20T08:06:33.352668Z","steps":["trace[1595879790] 'agreement among raft nodes before linearized reading' (duration: 138.84868ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:06:33.373199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.698214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:06:33.373267Z","caller":"traceutil/trace.go:171","msg":"trace[1482542816] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:748; }","duration":"128.799475ms","start":"2026-04-20T08:06:33.244448Z","end":"2026-04-20T08:06:33.373247Z","steps":["trace[1482542816] 'agreement among raft nodes before linearized reading' (duration: 128.702983ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:06:33.373368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.942104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-20T08:06:33.373413Z","caller":"traceutil/trace.go:171","msg":"trace[836109333] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:748; }","duration":"118.057196ms","start":"2026-04-20T08:06:33.255343Z","end":"2026-04-20T08:06:33.3734Z","steps":["trace[836109333] 'agreement among raft nodes before linearized reading' (duration: 117.898862ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:06:33.373437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.959491ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-04-20T08:06:33.373654Z","caller":"traceutil/trace.go:171","msg":"trace[1128635905] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:748; }","duration":"109.202597ms","start":"2026-04-20T08:06:33.264432Z","end":"2026-04-20T08:06:33.373634Z","steps":["trace[1128635905] 'agreement among raft nodes before linearized reading' (duration: 108.761067ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:02.374867Z","caller":"traceutil/trace.go:171","msg":"trace[2109434166] transaction","detail":"{read_only:false; response_revision:1144; number_of_response:1; }","duration":"118.72124ms","start":"2026-04-20T08:07:02.256122Z","end":"2026-04-20T08:07:02.374843Z","steps":["trace[2109434166] 'process raft request' (duration: 118.583155ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:02.572082Z","caller":"traceutil/trace.go:171","msg":"trace[1307075048] transaction","detail":"{read_only:false; response_revision:1145; number_of_response:1; }","duration":"102.229987ms","start":"2026-04-20T08:07:02.469823Z","end":"2026-04-20T08:07:02.572053Z","steps":["trace[1307075048] 'process raft request' (duration: 83.878508ms)","trace[1307075048] 'compare' (duration: 17.954773ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:07:51.351341Z","caller":"traceutil/trace.go:171","msg":"trace[1266829636] linearizableReadLoop","detail":"{readStateIndex:1885; appliedIndex:1884; }","duration":"198.934038ms","start":"2026-04-20T08:07:51.152355Z","end":"2026-04-20T08:07:51.351289Z","steps":["trace[1266829636] 'read index received' (duration: 198.869534ms)","trace[1266829636] 'applied index is now lower than readState.Index' (duration: 61.384µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:07:51.351504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.132409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:07:51.351447Z","caller":"traceutil/trace.go:171","msg":"trace[543997398] transaction","detail":"{read_only:false; response_revision:1814; number_of_response:1; }","duration":"330.214946ms","start":"2026-04-20T08:07:51.021209Z","end":"2026-04-20T08:07:51.351424Z","steps":["trace[543997398] 'process raft request' (duration: 329.919748ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:51.351562Z","caller":"traceutil/trace.go:171","msg":"trace[1040918263] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:1814; }","duration":"199.237576ms","start":"2026-04-20T08:07:51.15231Z","end":"2026-04-20T08:07:51.351548Z","steps":["trace[1040918263] 'agreement among raft nodes before linearized reading' (duration: 199.115129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:51.351759Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:07:51.021186Z","time spent":"330.383995ms","remote":"127.0.0.1:58866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:07:51.56275Z","caller":"traceutil/trace.go:171","msg":"trace[1288914910] transaction","detail":"{read_only:false; response_revision:1815; number_of_response:1; }","duration":"338.346914ms","start":"2026-04-20T08:07:51.224366Z","end":"2026-04-20T08:07:51.562713Z","steps":["trace[1288914910] 'process raft request' (duration: 270.191416ms)","trace[1288914910] 'compare' (duration: 67.898393ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:07:51.563025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:07:51.224345Z","time spent":"338.490122ms","remote":"127.0.0.1:58866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:07:51.75437Z","caller":"traceutil/trace.go:171","msg":"trace[1962811852] linearizableReadLoop","detail":"{readStateIndex:1888; appliedIndex:1887; }","duration":"142.91394ms","start":"2026-04-20T08:07:51.611431Z","end":"2026-04-20T08:07:51.754345Z","steps":["trace[1962811852] 'read index received' (duration: 135.450819ms)","trace[1962811852] 'applied index is now lower than readState.Index' (duration: 7.462061ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:07:51.754558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.103661ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-9d7119ee-2f74-4c16-8574-c6e771ac1811\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-04-20T08:07:51.754526Z","caller":"traceutil/trace.go:171","msg":"trace[661394653] transaction","detail":"{read_only:false; response_revision:1817; number_of_response:1; }","duration":"146.521398ms","start":"2026-04-20T08:07:51.607915Z","end":"2026-04-20T08:07:51.754437Z","steps":["trace[661394653] 'process raft request' (duration: 138.994614ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:51.754619Z","caller":"traceutil/trace.go:171","msg":"trace[20042900] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-9d7119ee-2f74-4c16-8574-c6e771ac1811; range_end:; response_count:1; response_revision:1817; }","duration":"143.213897ms","start":"2026-04-20T08:07:51.611391Z","end":"2026-04-20T08:07:51.754605Z","steps":["trace[20042900] 'agreement among raft nodes before linearized reading' (duration: 143.099181ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:52.061933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.084707ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:07:52.062041Z","caller":"traceutil/trace.go:171","msg":"trace[992710972] linearizableReadLoop","detail":"{readStateIndex:1889; appliedIndex:1888; }","duration":"169.240245ms","start":"2026-04-20T08:07:51.892784Z","end":"2026-04-20T08:07:52.062025Z","steps":["trace[992710972] 'read index received' (duration: 93.916µs)","trace[992710972] 'applied index is now lower than readState.Index' (duration: 169.144859ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:07:52.062141Z","caller":"traceutil/trace.go:171","msg":"trace[2141032611] transaction","detail":"{read_only:false; response_revision:1818; number_of_response:1; }","duration":"207.262662ms","start":"2026-04-20T08:07:51.854862Z","end":"2026-04-20T08:07:52.062125Z","steps":["trace[2141032611] 'process raft request' (duration: 15.768661ms)","trace[2141032611] 'compare' (duration: 190.864994ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:07:52.062568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.783316ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T08:07:52.06261Z","caller":"traceutil/trace.go:171","msg":"trace[355051852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1818; }","duration":"169.865391ms","start":"2026-04-20T08:07:51.892734Z","end":"2026-04-20T08:07:52.0626Z","steps":["trace[355051852] 'agreement among raft nodes before linearized reading' (duration: 169.703682ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:07:52.062905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.898899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T08:07:52.063843Z","caller":"traceutil/trace.go:171","msg":"trace[1941734566] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1818; }","duration":"117.878786ms","start":"2026-04-20T08:07:51.945941Z","end":"2026-04-20T08:07:52.06382Z","steps":["trace[1941734566] 'agreement among raft nodes before linearized reading' (duration: 116.56233ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:52.206593Z","caller":"traceutil/trace.go:171","msg":"trace[464352649] transaction","detail":"{read_only:false; response_revision:1820; number_of_response:1; }","duration":"138.759582ms","start":"2026-04-20T08:07:52.067808Z","end":"2026-04-20T08:07:52.206568Z","steps":["trace[464352649] 'process raft request' (duration: 110.770258ms)","trace[464352649] 'compare' (duration: 27.873407ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:07:58.215401Z","caller":"traceutil/trace.go:171","msg":"trace[1572523735] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"105.195945ms","start":"2026-04-20T08:07:58.110166Z","end":"2026-04-20T08:07:58.215362Z","steps":["trace[1572523735] 'process raft request' (duration: 105.042946ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:07:58.218228Z","caller":"traceutil/trace.go:171","msg":"trace[1699026163] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"107.63474ms","start":"2026-04-20T08:07:58.110576Z","end":"2026-04-20T08:07:58.218211Z","steps":["trace[1699026163] 'process raft request' (duration: 107.563736ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:08:25.046786Z","caller":"traceutil/trace.go:171","msg":"trace[954120983] transaction","detail":"{read_only:false; response_revision:2055; number_of_response:1; }","duration":"125.834987ms","start":"2026-04-20T08:08:24.920925Z","end":"2026-04-20T08:08:25.04676Z","steps":["trace[954120983] 'process raft request' (duration: 125.593811ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:08:25.220003Z","caller":"traceutil/trace.go:171","msg":"trace[857938653] linearizableReadLoop","detail":"{readStateIndex:2135; appliedIndex:2134; }","duration":"106.838903ms","start":"2026-04-20T08:08:25.113142Z","end":"2026-04-20T08:08:25.219981Z","steps":["trace[857938653] 'read index received' (duration: 34.469158ms)","trace[857938653] 'applied index is now lower than readState.Index' (duration: 72.368895ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:08:25.220391Z","caller":"traceutil/trace.go:171","msg":"trace[1054294630] transaction","detail":"{read_only:false; response_revision:2056; number_of_response:1; }","duration":"171.267289ms","start":"2026-04-20T08:08:25.049096Z","end":"2026-04-20T08:08:25.220363Z","steps":["trace[1054294630] 'process raft request' (duration: 98.573724ms)","trace[1054294630] 'compare' (duration: 72.196453ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:08:25.220527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.370476ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12385"} {"level":"info","ts":"2026-04-20T08:08:25.220602Z","caller":"traceutil/trace.go:171","msg":"trace[1306268133] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2056; }","duration":"107.485124ms","start":"2026-04-20T08:08:25.113104Z","end":"2026-04-20T08:08:25.220589Z","steps":["trace[1306268133] 'agreement among raft nodes before linearized reading' (duration: 107.285661ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:08:25.572946Z","caller":"traceutil/trace.go:171","msg":"trace[1554612754] transaction","detail":"{read_only:false; response_revision:2059; number_of_response:1; }","duration":"111.888556ms","start":"2026-04-20T08:08:25.461023Z","end":"2026-04-20T08:08:25.572912Z","steps":["trace[1554612754] 'process raft request' (duration: 91.854252ms)","trace[1554612754] 'compare' (duration: 19.840721ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:08:26.029482Z","caller":"traceutil/trace.go:171","msg":"trace[145884550] linearizableReadLoop","detail":"{readStateIndex:2139; appliedIndex:2138; }","duration":"268.494749ms","start":"2026-04-20T08:08:25.760966Z","end":"2026-04-20T08:08:26.029461Z","steps":["trace[145884550] 'read index received' (duration: 268.35258ms)","trace[145884550] 'applied index is now lower than readState.Index' (duration: 140.979µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:08:26.029723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.731384ms","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-04-20T08:08:26.029807Z","caller":"traceutil/trace.go:171","msg":"trace[1696640271] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2060; }","duration":"268.870043ms","start":"2026-04-20T08:08:25.760917Z","end":"2026-04-20T08:08:26.029788Z","steps":["trace[1696640271] 'agreement among raft nodes before linearized reading' (duration: 268.628678ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:08:26.029552Z","caller":"traceutil/trace.go:171","msg":"trace[1538401128] transaction","detail":"{read_only:false; response_revision:2060; number_of_response:1; }","duration":"338.876496ms","start":"2026-04-20T08:08:25.69063Z","end":"2026-04-20T08:08:26.029506Z","steps":["trace[1538401128] 'process raft request' (duration: 338.690384ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:08:26.030517Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-20T08:08:25.690607Z","time spent":"339.822857ms","remote":"127.0.0.1:58866","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-20T08:08:26.152139Z","caller":"traceutil/trace.go:171","msg":"trace[575798819] transaction","detail":"{read_only:false; response_revision:2062; number_of_response:1; }","duration":"117.551694ms","start":"2026-04-20T08:08:26.034564Z","end":"2026-04-20T08:08:26.152116Z","steps":["trace[575798819] 'process raft request' (duration: 117.47891ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:08:26.152148Z","caller":"traceutil/trace.go:171","msg":"trace[446185071] transaction","detail":"{read_only:false; response_revision:2061; number_of_response:1; }","duration":"234.84346ms","start":"2026-04-20T08:08:25.917286Z","end":"2026-04-20T08:08:26.15213Z","steps":["trace[446185071] 'process raft request' (duration: 216.333622ms)","trace[446185071] 'compare' (duration: 18.239941ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:08:26.537633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.18548ms","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-04-20T08:08:26.537765Z","caller":"traceutil/trace.go:171","msg":"trace[719488338] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2063; }","duration":"246.381033ms","start":"2026-04-20T08:08:26.291358Z","end":"2026-04-20T08:08:26.537739Z","steps":["trace[719488338] 'range keys from in-memory index tree' (duration: 245.935005ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:08:26.538031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.4248ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-04-20T08:08:26.538108Z","caller":"traceutil/trace.go:171","msg":"trace[179325768] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2063; }","duration":"173.539868ms","start":"2026-04-20T08:08:26.36455Z","end":"2026-04-20T08:08:26.53809Z","steps":["trace[179325768] 'range keys from in-memory index tree' (duration: 173.201466ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:08:26.5381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.054637ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T08:08:26.538178Z","caller":"traceutil/trace.go:171","msg":"trace[773295850] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2063; }","duration":"166.189806ms","start":"2026-04-20T08:08:26.371968Z","end":"2026-04-20T08:08:26.538158Z","steps":["trace[773295850] 'range keys from in-memory index tree' (duration: 165.873446ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:08:26.538189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.783461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-20T08:08:26.538263Z","caller":"traceutil/trace.go:171","msg":"trace[110442433] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2063; }","duration":"165.874405ms","start":"2026-04-20T08:08:26.372366Z","end":"2026-04-20T08:08:26.538241Z","steps":["trace[110442433] 'range keys from in-memory index tree' (duration: 165.658912ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:09:30.60459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.826013ms","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-04-20T08:09:30.60467Z","caller":"traceutil/trace.go:171","msg":"trace[871965453] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2510; }","duration":"114.944201ms","start":"2026-04-20T08:09:30.489708Z","end":"2026-04-20T08:09:30.604652Z","steps":["trace[871965453] 'range keys from in-memory index tree' (duration: 114.679453ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:09:30.60472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.537196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-04-20T08:09:30.604793Z","caller":"traceutil/trace.go:171","msg":"trace[1991078293] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2510; }","duration":"169.640243ms","start":"2026-04-20T08:09:30.435135Z","end":"2026-04-20T08:09:30.604775Z","steps":["trace[1991078293] 'range keys from in-memory index tree' (duration: 169.344873ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:10:02.886471Z","caller":"traceutil/trace.go:171","msg":"trace[882784000] transaction","detail":"{read_only:false; response_revision:2688; number_of_response:1; }","duration":"103.196805ms","start":"2026-04-20T08:10:02.783254Z","end":"2026-04-20T08:10:02.88645Z","steps":["trace[882784000] 'process raft request' (duration: 103.0678ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:10:03.375302Z","caller":"traceutil/trace.go:171","msg":"trace[1424720786] linearizableReadLoop","detail":"{readStateIndex:2793; appliedIndex:2790; }","duration":"130.046593ms","start":"2026-04-20T08:10:03.245237Z","end":"2026-04-20T08:10:03.375284Z","steps":["trace[1424720786] 'read index received' (duration: 52.035201ms)","trace[1424720786] 'applied index is now lower than readState.Index' (duration: 78.010612ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:10:03.375577Z","caller":"traceutil/trace.go:171","msg":"trace[1735034737] transaction","detail":"{read_only:false; response_revision:2690; number_of_response:1; }","duration":"241.73023ms","start":"2026-04-20T08:10:03.133835Z","end":"2026-04-20T08:10:03.375565Z","steps":["trace[1735034737] 'process raft request' (duration: 241.333605ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:10:03.375769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.632269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-20T08:10:03.375854Z","caller":"traceutil/trace.go:171","msg":"trace[1852437278] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2691; }","duration":"128.763504ms","start":"2026-04-20T08:10:03.247072Z","end":"2026-04-20T08:10:03.375836Z","steps":["trace[1852437278] 'agreement among raft nodes before linearized reading' (duration: 128.547892ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:10:03.375943Z","caller":"traceutil/trace.go:171","msg":"trace[1584935299] transaction","detail":"{read_only:false; response_revision:2691; number_of_response:1; }","duration":"241.401792ms","start":"2026-04-20T08:10:03.134532Z","end":"2026-04-20T08:10:03.375933Z","steps":["trace[1584935299] 'process raft request' (duration: 240.726258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:10:03.376036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.804045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:10:03.376073Z","caller":"traceutil/trace.go:171","msg":"trace[2037267322] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2691; }","duration":"130.869282ms","start":"2026-04-20T08:10:03.245194Z","end":"2026-04-20T08:10:03.376064Z","steps":["trace[2037267322] 'agreement among raft nodes before linearized reading' (duration: 130.816724ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:55.848157Z","caller":"traceutil/trace.go:171","msg":"trace[1676588049] transaction","detail":"{read_only:false; response_revision:4203; number_of_response:1; }","duration":"132.188697ms","start":"2026-04-20T08:13:55.715941Z","end":"2026-04-20T08:13:55.84813Z","steps":["trace[1676588049] 'process raft request' (duration: 132.135785ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:55.848225Z","caller":"traceutil/trace.go:171","msg":"trace[67117337] transaction","detail":"{read_only:false; response_revision:4202; number_of_response:1; }","duration":"132.338064ms","start":"2026-04-20T08:13:55.71585Z","end":"2026-04-20T08:13:55.848188Z","steps":["trace[67117337] 'process raft request' (duration: 132.170706ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:13:55.84816Z","caller":"traceutil/trace.go:171","msg":"trace[859630658] transaction","detail":"{read_only:false; response_revision:4201; number_of_response:1; }","duration":"148.995555ms","start":"2026-04-20T08:13:55.699126Z","end":"2026-04-20T08:13:55.848122Z","steps":["trace[859630658] 'process raft request' (duration: 74.892359ms)","trace[859630658] 'compare' (duration: 73.73941ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:13:56.502243Z","caller":"traceutil/trace.go:171","msg":"trace[537720538] transaction","detail":"{read_only:false; response_revision:4345; number_of_response:1; }","duration":"106.144456ms","start":"2026-04-20T08:13:56.396073Z","end":"2026-04-20T08:13:56.502218Z","steps":["trace[537720538] 'process raft request' (duration: 46.633514ms)","trace[537720538] 'compare' (duration: 59.209519ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:14:03.701292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.202718ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-20T08:14:03.701726Z","caller":"traceutil/trace.go:171","msg":"trace[745943490] transaction","detail":"{read_only:false; response_revision:4603; number_of_response:1; }","duration":"127.131408ms","start":"2026-04-20T08:14:03.574578Z","end":"2026-04-20T08:14:03.70171Z","steps":["trace[745943490] 'process raft request' (duration: 126.823284ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:03.701726Z","caller":"traceutil/trace.go:171","msg":"trace[339558250] transaction","detail":"{read_only:false; response_revision:4602; number_of_response:1; }","duration":"142.599693ms","start":"2026-04-20T08:14:03.559105Z","end":"2026-04-20T08:14:03.701704Z","steps":["trace[339558250] 'process raft request' (duration: 31.890252ms)","trace[339558250] 'compare' (duration: 109.961967ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:14:05.726341Z","caller":"traceutil/trace.go:171","msg":"trace[1339673327] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"115.515634ms","start":"2026-04-20T08:14:05.610801Z","end":"2026-04-20T08:14:05.726317Z","steps":["trace[1339673327] 'process raft request' (duration: 115.346376ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:06.089087Z","caller":"traceutil/trace.go:171","msg":"trace[1842683691] transaction","detail":"{read_only:false; response_revision:4673; number_of_response:1; }","duration":"100.712165ms","start":"2026-04-20T08:14:05.988355Z","end":"2026-04-20T08:14:06.089067Z","steps":["trace[1842683691] 'process raft request' (duration: 100.520106ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:11.967667Z","caller":"traceutil/trace.go:171","msg":"trace[1410570363] linearizableReadLoop","detail":"{readStateIndex:4913; appliedIndex:4912; }","duration":"197.180143ms","start":"2026-04-20T08:14:11.770462Z","end":"2026-04-20T08:14:11.967643Z","steps":["trace[1410570363] 'read index received' (duration: 196.928941ms)","trace[1410570363] 'applied index is now lower than readState.Index' (duration: 250.232µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:14:11.967756Z","caller":"traceutil/trace.go:171","msg":"trace[1200862762] transaction","detail":"{read_only:false; response_revision:4734; number_of_response:1; }","duration":"247.854285ms","start":"2026-04-20T08:14:11.719882Z","end":"2026-04-20T08:14:11.967736Z","steps":["trace[1200862762] 'process raft request' (duration: 247.52474ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:11.968505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.018662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:14:11.968683Z","caller":"traceutil/trace.go:171","msg":"trace[1753291653] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:0; response_revision:4734; }","duration":"198.1988ms","start":"2026-04-20T08:14:11.770429Z","end":"2026-04-20T08:14:11.968628Z","steps":["trace[1753291653] 'agreement among raft nodes before linearized reading' (duration: 197.33121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:11.977404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.981824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-20T08:14:11.977521Z","caller":"traceutil/trace.go:171","msg":"trace[1213127622] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:4735; }","duration":"147.063038ms","start":"2026-04-20T08:14:11.830405Z","end":"2026-04-20T08:14:11.977468Z","steps":["trace[1213127622] 'agreement among raft nodes before linearized reading' (duration: 146.945862ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:11.977606Z","caller":"traceutil/trace.go:171","msg":"trace[660427547] transaction","detail":"{read_only:false; response_revision:4735; number_of_response:1; }","duration":"190.314719ms","start":"2026-04-20T08:14:11.787268Z","end":"2026-04-20T08:14:11.977583Z","steps":["trace[660427547] 'process raft request' (duration: 189.982304ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:12.568769Z","caller":"traceutil/trace.go:171","msg":"trace[1911368824] linearizableReadLoop","detail":"{readStateIndex:4924; appliedIndex:4923; }","duration":"172.421228ms","start":"2026-04-20T08:14:12.396322Z","end":"2026-04-20T08:14:12.568744Z","steps":["trace[1911368824] 'read index received' (duration: 172.096513ms)","trace[1911368824] 'applied index is now lower than readState.Index' (duration: 323.745µs)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:14:12.56897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.633408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-20T08:14:12.569075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.180808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16233"} {"level":"warn","ts":"2026-04-20T08:14:12.569138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.651264ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-84f557fd5f-zksss.18a8028b907e39bc\" ","response":"range_response_count:1 size:898"} {"level":"info","ts":"2026-04-20T08:14:12.569192Z","caller":"traceutil/trace.go:171","msg":"trace[1972554163] transaction","detail":"{read_only:false; response_revision:4745; number_of_response:1; }","duration":"198.79517ms","start":"2026-04-20T08:14:12.370006Z","end":"2026-04-20T08:14:12.568801Z","steps":["trace[1972554163] 'process raft request' (duration: 198.523137ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:12.569214Z","caller":"traceutil/trace.go:171","msg":"trace[611243038] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-84f557fd5f-zksss.18a8028b907e39bc; range_end:; response_count:1; response_revision:4745; }","duration":"112.755118ms","start":"2026-04-20T08:14:12.456442Z","end":"2026-04-20T08:14:12.569197Z","steps":["trace[611243038] 'agreement among raft nodes before linearized reading' (duration: 112.56866ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:12.569388Z","caller":"traceutil/trace.go:171","msg":"trace[1459465754] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4745; }","duration":"172.786966ms","start":"2026-04-20T08:14:12.396274Z","end":"2026-04-20T08:14:12.569061Z","steps":["trace[1459465754] 'agreement among raft nodes before linearized reading' (duration: 172.567406ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:12.570321Z","caller":"traceutil/trace.go:171","msg":"trace[347087947] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4745; }","duration":"113.293243ms","start":"2026-04-20T08:14:12.455817Z","end":"2026-04-20T08:14:12.56911Z","steps":["trace[347087947] 'agreement among raft nodes before linearized reading' (duration: 113.110995ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:12.747215Z","caller":"traceutil/trace.go:171","msg":"trace[1296805937] transaction","detail":"{read_only:false; response_revision:4750; number_of_response:1; }","duration":"142.166129ms","start":"2026-04-20T08:14:12.60502Z","end":"2026-04-20T08:14:12.747186Z","steps":["trace[1296805937] 'process raft request' (duration: 141.041857ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:12.747329Z","caller":"traceutil/trace.go:171","msg":"trace[1965367047] transaction","detail":"{read_only:false; response_revision:4751; number_of_response:1; }","duration":"142.020762ms","start":"2026-04-20T08:14:12.605274Z","end":"2026-04-20T08:14:12.747294Z","steps":["trace[1965367047] 'process raft request' (duration: 141.832543ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:12.747361Z","caller":"traceutil/trace.go:171","msg":"trace[202565781] linearizableReadLoop","detail":"{readStateIndex:4932; appliedIndex:4928; }","duration":"103.506784ms","start":"2026-04-20T08:14:12.643823Z","end":"2026-04-20T08:14:12.74733Z","steps":["trace[202565781] 'read index received' (duration: 102.360861ms)","trace[202565781] 'applied index is now lower than readState.Index' (duration: 1.144493ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:14:12.747604Z","caller":"traceutil/trace.go:171","msg":"trace[1367658237] transaction","detail":"{read_only:false; response_revision:4752; number_of_response:1; }","duration":"142.132458ms","start":"2026-04-20T08:14:12.605415Z","end":"2026-04-20T08:14:12.747548Z","steps":["trace[1367658237] 'process raft request' (duration: 141.797072ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:12.74784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.715571ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-04-20T08:14:12.747906Z","caller":"traceutil/trace.go:171","msg":"trace[908048298] 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:4753; }","duration":"143.816705ms","start":"2026-04-20T08:14:12.604068Z","end":"2026-04-20T08:14:12.747885Z","steps":["trace[908048298] 'agreement among raft nodes before linearized reading' (duration: 143.582425ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:12.748596Z","caller":"traceutil/trace.go:171","msg":"trace[723842164] transaction","detail":"{read_only:false; response_revision:4753; number_of_response:1; }","duration":"142.043644ms","start":"2026-04-20T08:14:12.605441Z","end":"2026-04-20T08:14:12.747485Z","steps":["trace[723842164] 'process raft request' (duration: 141.821354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:12.747854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.022332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-20T08:14:12.749424Z","caller":"traceutil/trace.go:171","msg":"trace[946513646] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4753; }","duration":"126.614276ms","start":"2026-04-20T08:14:12.622755Z","end":"2026-04-20T08:14:12.749369Z","steps":["trace[946513646] 'agreement among raft nodes before linearized reading' (duration: 124.883376ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:12.754053Z","caller":"traceutil/trace.go:171","msg":"trace[606557506] transaction","detail":"{read_only:false; response_revision:4757; number_of_response:1; }","duration":"102.400572ms","start":"2026-04-20T08:14:12.65163Z","end":"2026-04-20T08:14:12.754031Z","steps":["trace[606557506] 'process raft request' (duration: 102.364941ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:12.754402Z","caller":"traceutil/trace.go:171","msg":"trace[1293282531] transaction","detail":"{read_only:false; response_revision:4754; number_of_response:1; }","duration":"105.191621ms","start":"2026-04-20T08:14:12.649202Z","end":"2026-04-20T08:14:12.754394Z","steps":["trace[1293282531] 'process raft request' (duration: 104.543551ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:12.754516Z","caller":"traceutil/trace.go:171","msg":"trace[1625380423] transaction","detail":"{read_only:false; response_revision:4756; number_of_response:1; }","duration":"103.269383ms","start":"2026-04-20T08:14:12.651191Z","end":"2026-04-20T08:14:12.75446Z","steps":["trace[1625380423] 'process raft request' (duration: 102.75804ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:12.754783Z","caller":"traceutil/trace.go:171","msg":"trace[1757189025] transaction","detail":"{read_only:false; response_revision:4755; number_of_response:1; }","duration":"104.911508ms","start":"2026-04-20T08:14:12.649846Z","end":"2026-04-20T08:14:12.754757Z","steps":["trace[1757189025] 'process raft request' (duration: 104.042318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:14:12.755281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.696946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-04-20T08:14:12.75532Z","caller":"traceutil/trace.go:171","msg":"trace[1746507063] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4757; }","duration":"107.78275ms","start":"2026-04-20T08:14:12.647529Z","end":"2026-04-20T08:14:12.755312Z","steps":["trace[1746507063] 'agreement among raft nodes before linearized reading' (duration: 107.436004ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:14:25.318066Z","caller":"traceutil/trace.go:171","msg":"trace[566338130] transaction","detail":"{read_only:false; response_revision:4959; number_of_response:1; }","duration":"276.436361ms","start":"2026-04-20T08:14:25.041605Z","end":"2026-04-20T08:14:25.318041Z","steps":["trace[566338130] 'process raft request' (duration: 195.477813ms)","trace[566338130] 'compare' (duration: 80.834662ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:14:25.499789Z","caller":"traceutil/trace.go:171","msg":"trace[2012113861] transaction","detail":"{read_only:false; response_revision:4960; number_of_response:1; }","duration":"136.410949ms","start":"2026-04-20T08:14:25.363343Z","end":"2026-04-20T08:14:25.499754Z","steps":["trace[2012113861] 'process raft request' (duration: 82.439098ms)","trace[2012113861] 'compare' (duration: 53.845765ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:15:39.994536Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2884} {"level":"info","ts":"2026-04-20T08:15:40.078104Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2884,"took":"82.402199ms","hash":2929638463,"current-db-size-bytes":53014528,"current-db-size":"53 MB","current-db-size-in-use-bytes":29081600,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-04-20T08:15:40.078182Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2929638463,"revision":2884,"compact-revision":-1} {"level":"info","ts":"2026-04-20T08:17:56.157273Z","caller":"traceutil/trace.go:171","msg":"trace[1474093586] transaction","detail":"{read_only:false; response_revision:6515; number_of_response:1; }","duration":"151.330333ms","start":"2026-04-20T08:17:56.005915Z","end":"2026-04-20T08:17:56.157246Z","steps":["trace[1474093586] 'process raft request' (duration: 151.081349ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:56.158029Z","caller":"traceutil/trace.go:171","msg":"trace[626634098] linearizableReadLoop","detail":"{readStateIndex:6751; appliedIndex:6749; }","duration":"123.499903ms","start":"2026-04-20T08:17:56.034506Z","end":"2026-04-20T08:17:56.158005Z","steps":["trace[626634098] 'read index received' (duration: 122.479794ms)","trace[626634098] 'applied index is now lower than readState.Index' (duration: 1.019179ms)"],"step_count":2} {"level":"warn","ts":"2026-04-20T08:17:56.158276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.748427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-20T08:17:56.15824Z","caller":"traceutil/trace.go:171","msg":"trace[438539781] transaction","detail":"{read_only:false; response_revision:6516; number_of_response:1; }","duration":"152.13544ms","start":"2026-04-20T08:17:56.005975Z","end":"2026-04-20T08:17:56.158111Z","steps":["trace[438539781] 'process raft request' (duration: 151.893026ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:17:56.15834Z","caller":"traceutil/trace.go:171","msg":"trace[1873121904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6516; }","duration":"123.853953ms","start":"2026-04-20T08:17:56.034451Z","end":"2026-04-20T08:17:56.158305Z","steps":["trace[1873121904] 'agreement among raft nodes before linearized reading' (duration: 123.687964ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:17:56.158421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.735066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-20T08:17:56.158531Z","caller":"traceutil/trace.go:171","msg":"trace[938048045] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6516; }","duration":"123.997281ms","start":"2026-04-20T08:17:56.034515Z","end":"2026-04-20T08:17:56.158512Z","steps":["trace[938048045] 'agreement among raft nodes before linearized reading' (duration: 123.647231ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:18:02.360599Z","caller":"traceutil/trace.go:171","msg":"trace[322472560] transaction","detail":"{read_only:false; response_revision:6548; number_of_response:1; }","duration":"100.407776ms","start":"2026-04-20T08:18:02.260166Z","end":"2026-04-20T08:18:02.360574Z","steps":["trace[322472560] 'process raft request' (duration: 69.207118ms)","trace[322472560] 'compare' (duration: 30.8991ms)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:18:26.627858Z","caller":"traceutil/trace.go:171","msg":"trace[1730465486] linearizableReadLoop","detail":"{readStateIndex:6971; appliedIndex:6970; }","duration":"102.094773ms","start":"2026-04-20T08:18:26.525729Z","end":"2026-04-20T08:18:26.627824Z","steps":["trace[1730465486] 'read index received' (duration: 101.503389ms)","trace[1730465486] 'applied index is now lower than readState.Index' (duration: 587.054µs)"],"step_count":2} {"level":"info","ts":"2026-04-20T08:18:26.627973Z","caller":"traceutil/trace.go:171","msg":"trace[496942577] transaction","detail":"{read_only:false; response_revision:6727; number_of_response:1; }","duration":"264.806964ms","start":"2026-04-20T08:18:26.363125Z","end":"2026-04-20T08:18:26.627932Z","steps":["trace[496942577] 'process raft request' (duration: 264.477695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:18:26.628223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.461854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-20T08:18:26.628288Z","caller":"traceutil/trace.go:171","msg":"trace[745555719] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:6727; }","duration":"102.579811ms","start":"2026-04-20T08:18:26.525693Z","end":"2026-04-20T08:18:26.628273Z","steps":["trace[745555719] 'agreement among raft nodes before linearized reading' (duration: 102.328587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-20T08:18:26.628645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.645978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-04-20T08:18:26.628804Z","caller":"traceutil/trace.go:171","msg":"trace[726690829] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:6727; }","duration":"101.832959ms","start":"2026-04-20T08:18:26.526958Z","end":"2026-04-20T08:18:26.628791Z","steps":["trace[726690829] 'agreement among raft nodes before linearized reading' (duration: 101.072444ms)"],"step_count":1} {"level":"info","ts":"2026-04-20T08:20:40.000068Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5572} {"level":"info","ts":"2026-04-20T08:20:40.073399Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5572,"took":"72.074612ms","hash":1177133821,"current-db-size-bytes":53014528,"current-db-size":"53 MB","current-db-size-in-use-bytes":18264064,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-04-20T08:20:40.073454Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1177133821,"revision":5572,"compact-revision":2884}