{"level":"warn","ts":"2026-05-05T22:11:05.037607Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T22:11:05.037719Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.83: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.83:2380","--initial-cluster=instance=https://199.19.213.83:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.83:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.83: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-05-05T22:11:05.03781Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T22:11:05.03782Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.83:2380"]} {"level":"info","ts":"2026-05-05T22:11:05.03784Z","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-05-05T22:11:05.038702Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"]} {"level":"info","ts":"2026-05-05T22:11:05.038825Z","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.83:2380"],"listen-peer-urls":["https://199.19.213.83:2380"],"advertise-client-urls":["https://199.19.213.83:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.83: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-05-05T22:11:05.042073Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.985276ms"} {"level":"info","ts":"2026-05-05T22:11:05.04885Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5887d25060a34f1b","cluster-id":"8c4d9ec591b76d84"} {"level":"info","ts":"2026-05-05T22:11:05.048918Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T22:11:05.048942Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became follower at term 0"} {"level":"info","ts":"2026-05-05T22:11:05.048957Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5887d25060a34f1b [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T22:11:05.048969Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became follower at term 1"} {"level":"info","ts":"2026-05-05T22:11:05.049014Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=(6379298639854128923)"} {"level":"warn","ts":"2026-05-05T22:11:05.054251Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T22:11:05.055395Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T22:11:05.056379Z","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-05-05T22:11:05.059624Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5887d25060a34f1b","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T22:11:05.059775Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5887d25060a34f1b","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T22:11:05.061419Z","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-05-05T22:11:05.061594Z","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-05-05T22:11:05.061614Z","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-05-05T22:11:05.063277Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b switched to configuration voters=(6379298639854128923)"} {"level":"info","ts":"2026-05-05T22:11:05.063401Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8c4d9ec591b76d84","local-member-id":"5887d25060a34f1b","added-peer-id":"5887d25060a34f1b","added-peer-peer-urls":["https://199.19.213.83:2380"]} {"level":"info","ts":"2026-05-05T22:11:05.064059Z","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-05-05T22:11:05.064152Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.83:2380"} {"level":"info","ts":"2026-05-05T22:11:05.06418Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.83:2380"} {"level":"info","ts":"2026-05-05T22:11:05.064259Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5887d25060a34f1b","initial-advertise-peer-urls":["https://199.19.213.83:2380"],"listen-peer-urls":["https://199.19.213.83:2380"],"advertise-client-urls":["https://199.19.213.83:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.83:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T22:11:05.064295Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T22:11:05.949947Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T22:11:05.95001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T22:11:05.950063Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b received MsgPreVoteResp from 5887d25060a34f1b at term 1"} {"level":"info","ts":"2026-05-05T22:11:05.950084Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became candidate at term 2"} {"level":"info","ts":"2026-05-05T22:11:05.950101Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b received MsgVoteResp from 5887d25060a34f1b at term 2"} {"level":"info","ts":"2026-05-05T22:11:05.950118Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5887d25060a34f1b became leader at term 2"} {"level":"info","ts":"2026-05-05T22:11:05.950137Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5887d25060a34f1b elected leader 5887d25060a34f1b at term 2"} {"level":"info","ts":"2026-05-05T22:11:05.951185Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5887d25060a34f1b","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.83:2379]}","request-path":"/0/members/5887d25060a34f1b/attributes","cluster-id":"8c4d9ec591b76d84","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T22:11:05.951246Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T22:11:05.951382Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:11:05.951627Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T22:11:05.951749Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T22:11:05.95182Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T22:11:05.952559Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8c4d9ec591b76d84","local-member-id":"5887d25060a34f1b","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:11:05.95277Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:11:05.952823Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T22:11:05.956047Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.83:2379"} {"level":"info","ts":"2026-05-05T22:11:05.957158Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T22:11:07.412987Z","caller":"traceutil/trace.go:171","msg":"trace[274261250] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"149.202068ms","start":"2026-05-05T22:11:07.263763Z","end":"2026-05-05T22:11:07.412965Z","steps":["trace[274261250] 'process raft request' (duration: 148.992501ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:07.684144Z","caller":"traceutil/trace.go:171","msg":"trace[494571554] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"419.07837ms","start":"2026-05-05T22:11:07.265032Z","end":"2026-05-05T22:11:07.684111Z","steps":["trace[494571554] 'process raft request' (duration: 418.807808ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:07.684176Z","caller":"traceutil/trace.go:171","msg":"trace[1895680260] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"344.634274ms","start":"2026-05-05T22:11:07.33951Z","end":"2026-05-05T22:11:07.684144Z","steps":["trace[1895680260] 'process raft request' (duration: 344.564128ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:07.684215Z","caller":"traceutil/trace.go:171","msg":"trace[1704833111] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"418.22201ms","start":"2026-05-05T22:11:07.265978Z","end":"2026-05-05T22:11:07.6842Z","steps":["trace[1704833111] 'read index received' (duration: 146.707766ms)","trace[1704833111] 'applied index is now lower than readState.Index' (duration: 271.513424ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:11:07.684292Z","caller":"traceutil/trace.go:171","msg":"trace[1719880833] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"345.486093ms","start":"2026-05-05T22:11:07.338794Z","end":"2026-05-05T22:11:07.68428Z","steps":["trace[1719880833] 'process raft request' (duration: 345.21148ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:07.684468Z","caller":"traceutil/trace.go:171","msg":"trace[2085056051] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"328.332489ms","start":"2026-05-05T22:11:07.356126Z","end":"2026-05-05T22:11:07.684459Z","steps":["trace[2085056051] 'process raft request' (duration: 328.015634ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:07.684652Z","caller":"traceutil/trace.go:171","msg":"trace[889262959] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"340.678752ms","start":"2026-05-05T22:11:07.343954Z","end":"2026-05-05T22:11:07.684633Z","steps":["trace[889262959] 'process raft request' (duration: 340.15927ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:07.684718Z","caller":"traceutil/trace.go:171","msg":"trace[1727504047] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"324.514879ms","start":"2026-05-05T22:11:07.360192Z","end":"2026-05-05T22:11:07.684707Z","steps":["trace[1727504047] 'process raft request' (duration: 323.980176ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:07.68491Z","caller":"traceutil/trace.go:171","msg":"trace[1481869338] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"346.266256ms","start":"2026-05-05T22:11:07.338619Z","end":"2026-05-05T22:11:07.684885Z","steps":["trace[1481869338] 'process raft request' (duration: 345.344181ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:07.685115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.370969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T22:11:07.68514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.116342ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:11:07.685168Z","caller":"traceutil/trace.go:171","msg":"trace[398818165] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"341.730207ms","start":"2026-05-05T22:11:07.343429Z","end":"2026-05-05T22:11:07.68516Z","steps":["trace[398818165] 'agreement among raft nodes before linearized reading' (duration: 341.344476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:07.685178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:07.33877Z","time spent":"345.545428ms","remote":"127.0.0.1:47426","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-05-05T22:11:07.685194Z","caller":"traceutil/trace.go:171","msg":"trace[2050819082] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"369.179517ms","start":"2026-05-05T22:11:07.316001Z","end":"2026-05-05T22:11:07.68518Z","steps":["trace[2050819082] 'agreement among raft nodes before linearized reading' (duration: 368.759593ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:07.685199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:07.343419Z","time spent":"341.769451ms","remote":"127.0.0.1:47084","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-05-05T22:11:07.68523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:07.315963Z","time spent":"369.258983ms","remote":"127.0.0.1:47084","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-05-05T22:11:07.685278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:07.265023Z","time spent":"419.202739ms","remote":"127.0.0.1:47026","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":105,"response count":0,"response size":37,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:11:07.685383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:07.35611Z","time spent":"328.414937ms","remote":"127.0.0.1:47154","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-05-05T22:11:07.685415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.304931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.83\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:11:07.685443Z","caller":"traceutil/trace.go:171","msg":"trace[665850221] range","detail":"{range_begin:/registry/masterleases/199.19.213.83; range_end:; response_count:0; response_revision:11; }","duration":"348.352966ms","start":"2026-05-05T22:11:07.337082Z","end":"2026-05-05T22:11:07.685435Z","steps":["trace[665850221] 'agreement among raft nodes before linearized reading' (duration: 348.310282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:07.685467Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:07.337069Z","time spent":"348.392619ms","remote":"127.0.0.1:46980","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.83\" "} {"level":"warn","ts":"2026-05-05T22:11:07.68553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:07.343947Z","time spent":"340.742567ms","remote":"127.0.0.1:47378","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-05-05T22:11:07.685697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.37145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:11:07.685782Z","caller":"traceutil/trace.go:171","msg":"trace[1283655627] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"367.447086ms","start":"2026-05-05T22:11:07.318276Z","end":"2026-05-05T22:11:07.685723Z","steps":["trace[1283655627] 'agreement among raft nodes before linearized reading' (duration: 367.37398ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:07.685793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:07.360179Z","time spent":"324.564693ms","remote":"127.0.0.1:47236","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":849,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T22:11:07.685865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:07.31825Z","time spent":"367.605839ms","remote":"127.0.0.1:47084","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-05-05T22:11:07.686103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.993323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:11:07.686148Z","caller":"traceutil/trace.go:171","msg":"trace[1948214941] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"370.065939ms","start":"2026-05-05T22:11:07.316067Z","end":"2026-05-05T22:11:07.686133Z","steps":["trace[1948214941] 'agreement among raft nodes before linearized reading' (duration: 370.004434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:07.686182Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:07.316046Z","time spent":"370.127334ms","remote":"127.0.0.1:47084","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-05-05T22:11:07.686262Z","caller":"traceutil/trace.go:171","msg":"trace[2089293356] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"346.623625ms","start":"2026-05-05T22:11:07.339583Z","end":"2026-05-05T22:11:07.686207Z","steps":["trace[2089293356] 'process raft request' (duration: 344.511844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:07.686353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:07.339572Z","time spent":"346.726224ms","remote":"127.0.0.1:47426","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-05-05T22:11:07.686553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.834061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T22:11:07.686572Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.302524ms","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-05-05T22:11:07.686594Z","caller":"traceutil/trace.go:171","msg":"trace[1615846211] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"295.895785ms","start":"2026-05-05T22:11:07.390686Z","end":"2026-05-05T22:11:07.686582Z","steps":["trace[1615846211] 'agreement among raft nodes before linearized reading' (duration: 295.820939ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:07.686606Z","caller":"traceutil/trace.go:171","msg":"trace[146296916] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"342.339807ms","start":"2026-05-05T22:11:07.344256Z","end":"2026-05-05T22:11:07.686595Z","steps":["trace[146296916] 'agreement among raft nodes before linearized reading' (duration: 342.269601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:07.686638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:07.344238Z","time spent":"342.393142ms","remote":"127.0.0.1:47048","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-05-05T22:11:07.686688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.705712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T22:11:07.686795Z","caller":"traceutil/trace.go:171","msg":"trace[1697241120] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:11; }","duration":"420.815721ms","start":"2026-05-05T22:11:07.265967Z","end":"2026-05-05T22:11:07.686783Z","steps":["trace[1697241120] 'agreement among raft nodes before linearized reading' (duration: 420.700432ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:07.685057Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:07.339505Z","time spent":"344.748253ms","remote":"127.0.0.1:47426","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-05-05T22:11:07.686829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:07.265956Z","time spent":"420.864095ms","remote":"127.0.0.1:47326","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-05-05T22:11:07.68498Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:07.338609Z","time spent":"346.344322ms","remote":"127.0.0.1:47426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":920,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T22:11:07.687319Z","caller":"traceutil/trace.go:171","msg":"trace[1474798119] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"348.079983ms","start":"2026-05-05T22:11:07.33921Z","end":"2026-05-05T22:11:07.68729Z","steps":["trace[1474798119] 'process raft request' (duration: 344.816808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:07.687405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:11:07.339204Z","time spent":"348.160089ms","remote":"127.0.0.1:47426","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":968,"response count":0,"response size":37,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T22:11:09.408461Z","caller":"traceutil/trace.go:171","msg":"trace[1427570193] transaction","detail":"{read_only:false; response_revision:243; number_of_response:1; }","duration":"104.880806ms","start":"2026-05-05T22:11:09.30355Z","end":"2026-05-05T22:11:09.408431Z","steps":["trace[1427570193] 'process raft request' (duration: 19.543765ms)","trace[1427570193] 'compare' (duration: 85.212281ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:11:36.361851Z","caller":"traceutil/trace.go:171","msg":"trace[1809524757] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"107.24268ms","start":"2026-05-05T22:11:36.254586Z","end":"2026-05-05T22:11:36.361829Z","steps":["trace[1809524757] 'read index received' (duration: 85.734221ms)","trace[1809524757] 'applied index is now lower than readState.Index' (duration: 21.507759ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:11:36.362133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.524181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io\" ","response":"range_response_count:1 size:23081"} {"level":"info","ts":"2026-05-05T22:11:36.362181Z","caller":"traceutil/trace.go:171","msg":"trace[441468186] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/xbackendtrafficpolicies.gateway.networking.x-k8s.io; range_end:; response_count:1; response_revision:602; }","duration":"107.607544ms","start":"2026-05-05T22:11:36.254558Z","end":"2026-05-05T22:11:36.362165Z","steps":["trace[441468186] 'agreement among raft nodes before linearized reading' (duration: 107.452868ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:36.361956Z","caller":"traceutil/trace.go:171","msg":"trace[345779845] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"116.714517ms","start":"2026-05-05T22:11:36.245232Z","end":"2026-05-05T22:11:36.361947Z","steps":["trace[345779845] 'process raft request' (duration: 95.079044ms)","trace[345779845] 'compare' (duration: 21.418405ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:11:40.610501Z","caller":"traceutil/trace.go:171","msg":"trace[872403488] linearizableReadLoop","detail":"{readStateIndex:675; appliedIndex:674; }","duration":"123.251007ms","start":"2026-05-05T22:11:40.487229Z","end":"2026-05-05T22:11:40.61048Z","steps":["trace[872403488] 'read index received' (duration: 123.150083ms)","trace[872403488] 'applied index is now lower than readState.Index' (duration: 99.714µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:11:40.610651Z","caller":"traceutil/trace.go:171","msg":"trace[41683647] transaction","detail":"{read_only:false; response_revision:652; number_of_response:1; }","duration":"164.52278ms","start":"2026-05-05T22:11:40.44609Z","end":"2026-05-05T22:11:40.610613Z","steps":["trace[41683647] 'process raft request' (duration: 164.217089ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:40.610675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.423793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:11:40.61082Z","caller":"traceutil/trace.go:171","msg":"trace[224240448] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:652; }","duration":"123.61695ms","start":"2026-05-05T22:11:40.487185Z","end":"2026-05-05T22:11:40.610801Z","steps":["trace[224240448] 'agreement among raft nodes before linearized reading' (duration: 123.430663ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:40.74381Z","caller":"traceutil/trace.go:171","msg":"trace[1301865623] linearizableReadLoop","detail":"{readStateIndex:676; appliedIndex:675; }","duration":"133.206971ms","start":"2026-05-05T22:11:40.610581Z","end":"2026-05-05T22:11:40.743788Z","steps":["trace[1301865623] 'read index received' (duration: 133.17291ms)","trace[1301865623] 'applied index is now lower than readState.Index' (duration: 32.091µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:11:40.743986Z","caller":"traceutil/trace.go:171","msg":"trace[1781601129] transaction","detail":"{read_only:false; response_revision:653; number_of_response:1; }","duration":"152.515599ms","start":"2026-05-05T22:11:40.591442Z","end":"2026-05-05T22:11:40.743958Z","steps":["trace[1781601129] 'process raft request' (duration: 152.226669ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:40.744224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.904882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:11:40.744217Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.483612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:11:40.744344Z","caller":"traceutil/trace.go:171","msg":"trace[397379951] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:653; }","duration":"148.641567ms","start":"2026-05-05T22:11:40.595683Z","end":"2026-05-05T22:11:40.744324Z","steps":["trace[397379951] 'agreement among raft nodes before linearized reading' (duration: 148.166919ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:40.744384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.469604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:11:40.744425Z","caller":"traceutil/trace.go:171","msg":"trace[1818436532] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:653; }","duration":"147.524656ms","start":"2026-05-05T22:11:40.59689Z","end":"2026-05-05T22:11:40.744414Z","steps":["trace[1818436532] 'agreement among raft nodes before linearized reading' (duration: 147.461334ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:40.744412Z","caller":"traceutil/trace.go:171","msg":"trace[1493109762] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:653; }","duration":"255.033766ms","start":"2026-05-05T22:11:40.489259Z","end":"2026-05-05T22:11:40.744293Z","steps":["trace[1493109762] 'agreement among raft nodes before linearized reading' (duration: 254.788217ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:40.744599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.766251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:11:40.744646Z","caller":"traceutil/trace.go:171","msg":"trace[592681936] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:653; }","duration":"148.845825ms","start":"2026-05-05T22:11:40.595785Z","end":"2026-05-05T22:11:40.744631Z","steps":["trace[592681936] 'agreement among raft nodes before linearized reading' (duration: 148.779663ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:40.744756Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.472445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:11:40.744798Z","caller":"traceutil/trace.go:171","msg":"trace[235259401] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:653; }","duration":"257.516777ms","start":"2026-05-05T22:11:40.487271Z","end":"2026-05-05T22:11:40.744787Z","steps":["trace[235259401] 'agreement among raft nodes before linearized reading' (duration: 257.458535ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:40.744812Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.800758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:11:40.744893Z","caller":"traceutil/trace.go:171","msg":"trace[1478516565] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:653; }","duration":"242.927593ms","start":"2026-05-05T22:11:40.501947Z","end":"2026-05-05T22:11:40.744875Z","steps":["trace[1478516565] 'agreement among raft nodes before linearized reading' (duration: 241.982568ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:40.745084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.1377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:11:40.74513Z","caller":"traceutil/trace.go:171","msg":"trace[1670509804] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:653; }","duration":"243.203703ms","start":"2026-05-05T22:11:40.501916Z","end":"2026-05-05T22:11:40.74512Z","steps":["trace[1670509804] 'agreement among raft nodes before linearized reading' (duration: 241.983728ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:40.74581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.369599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:11:40.745868Z","caller":"traceutil/trace.go:171","msg":"trace[394528698] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:653; }","duration":"243.457882ms","start":"2026-05-05T22:11:40.502397Z","end":"2026-05-05T22:11:40.745855Z","steps":["trace[394528698] 'agreement among raft nodes before linearized reading' (duration: 241.49112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:40.76286Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.537575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:11:40.762945Z","caller":"traceutil/trace.go:171","msg":"trace[813530572] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:654; }","duration":"100.641288ms","start":"2026-05-05T22:11:40.662285Z","end":"2026-05-05T22:11:40.762926Z","steps":["trace[813530572] 'agreement among raft nodes before linearized reading' (duration: 99.963254ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:40.762999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.674199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:11:40.763059Z","caller":"traceutil/trace.go:171","msg":"trace[1973768096] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:654; }","duration":"100.740122ms","start":"2026-05-05T22:11:40.662305Z","end":"2026-05-05T22:11:40.763045Z","steps":["trace[1973768096] 'agreement among raft nodes before linearized reading' (duration: 99.941883ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:11:43.320431Z","caller":"traceutil/trace.go:171","msg":"trace[907215725] linearizableReadLoop","detail":"{readStateIndex:698; appliedIndex:697; }","duration":"145.620137ms","start":"2026-05-05T22:11:43.17479Z","end":"2026-05-05T22:11:43.32041Z","steps":["trace[907215725] 'read index received' (duration: 145.41623ms)","trace[907215725] 'applied index is now lower than readState.Index' (duration: 202.907µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:11:43.320503Z","caller":"traceutil/trace.go:171","msg":"trace[2128861675] transaction","detail":"{read_only:false; response_revision:675; number_of_response:1; }","duration":"260.535888ms","start":"2026-05-05T22:11:43.059936Z","end":"2026-05-05T22:11:43.320471Z","steps":["trace[2128861675] 'process raft request' (duration: 260.297369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:43.320643Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.843166ms","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-05-05T22:11:43.320684Z","caller":"traceutil/trace.go:171","msg":"trace[656698118] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:675; }","duration":"145.938329ms","start":"2026-05-05T22:11:43.174735Z","end":"2026-05-05T22:11:43.320674Z","steps":["trace[656698118] 'agreement among raft nodes before linearized reading' (duration: 145.795144ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:11:43.530418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.94122ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:11:43.530613Z","caller":"traceutil/trace.go:171","msg":"trace[1837413626] transaction","detail":"{read_only:false; response_revision:676; number_of_response:1; }","duration":"204.86271ms","start":"2026-05-05T22:11:43.325719Z","end":"2026-05-05T22:11:43.530582Z","steps":["trace[1837413626] 'process raft request' (duration: 42.392161ms)","trace[1837413626] 'compare' (duration: 161.730653ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:11:43.530806Z","caller":"traceutil/trace.go:171","msg":"trace[1123665432] transaction","detail":"{read_only:false; response_revision:677; number_of_response:1; }","duration":"203.491563ms","start":"2026-05-05T22:11:43.327287Z","end":"2026-05-05T22:11:43.530779Z","steps":["trace[1123665432] 'process raft request' (duration: 203.231414ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:12:07.361231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.022444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gbqhh\" ","response":"range_response_count:1 size:3964"} {"level":"info","ts":"2026-05-05T22:12:07.361384Z","caller":"traceutil/trace.go:171","msg":"trace[1356199544] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-gbqhh; range_end:; response_count:1; response_revision:811; }","duration":"206.217119ms","start":"2026-05-05T22:12:07.155145Z","end":"2026-05-05T22:12:07.361362Z","steps":["trace[1356199544] 'range keys from in-memory index tree' (duration: 205.86198ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:12:35.952508Z","caller":"traceutil/trace.go:171","msg":"trace[683074912] linearizableReadLoop","detail":"{readStateIndex:1244; appliedIndex:1243; }","duration":"102.994497ms","start":"2026-05-05T22:12:35.849495Z","end":"2026-05-05T22:12:35.952489Z","steps":["trace[683074912] 'read index received' (duration: 102.812654ms)","trace[683074912] 'applied index is now lower than readState.Index' (duration: 181.163µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:12:35.952581Z","caller":"traceutil/trace.go:171","msg":"trace[1384543337] transaction","detail":"{read_only:false; response_revision:1206; number_of_response:1; }","duration":"138.311388ms","start":"2026-05-05T22:12:35.81424Z","end":"2026-05-05T22:12:35.952551Z","steps":["trace[1384543337] 'process raft request' (duration: 138.132055ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:12:35.952683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.17356ms","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-05-05T22:12:35.952723Z","caller":"traceutil/trace.go:171","msg":"trace[1304040871] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1206; }","duration":"103.244061ms","start":"2026-05-05T22:12:35.849472Z","end":"2026-05-05T22:12:35.952716Z","steps":["trace[1304040871] 'agreement among raft nodes before linearized reading' (duration: 103.122459ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:13:21.397419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.906028ms","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-05-05T22:13:21.397823Z","caller":"traceutil/trace.go:171","msg":"trace[921128149] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1878; }","duration":"280.370054ms","start":"2026-05-05T22:13:21.117437Z","end":"2026-05-05T22:13:21.397807Z","steps":["trace[921128149] 'range keys from in-memory index tree' (duration: 279.664725ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:13:21.72391Z","caller":"traceutil/trace.go:171","msg":"trace[670849508] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"180.604806ms","start":"2026-05-05T22:13:21.543282Z","end":"2026-05-05T22:13:21.723887Z","steps":["trace[670849508] 'process raft request' (duration: 180.433093ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:13:21.848748Z","caller":"traceutil/trace.go:171","msg":"trace[429217776] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1953; }","duration":"119.883003ms","start":"2026-05-05T22:13:21.728835Z","end":"2026-05-05T22:13:21.848718Z","steps":["trace[429217776] 'read index received' (duration: 81.900209ms)","trace[429217776] 'applied index is now lower than readState.Index' (duration: 37.981614ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:13:21.849008Z","caller":"traceutil/trace.go:171","msg":"trace[1075328895] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"157.687914ms","start":"2026-05-05T22:13:21.691244Z","end":"2026-05-05T22:13:21.848932Z","steps":["trace[1075328895] 'process raft request' (duration: 119.846562ms)","trace[1075328895] 'compare' (duration: 37.465167ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:13:21.849077Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.217457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T22:13:21.849151Z","caller":"traceutil/trace.go:171","msg":"trace[732968470] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1881; }","duration":"120.352719ms","start":"2026-05-05T22:13:21.728777Z","end":"2026-05-05T22:13:21.84913Z","steps":["trace[732968470] 'agreement among raft nodes before linearized reading' (duration: 120.141886ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:13:21.855524Z","caller":"traceutil/trace.go:171","msg":"trace[781813090] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"124.238393ms","start":"2026-05-05T22:13:21.731265Z","end":"2026-05-05T22:13:21.855504Z","steps":["trace[781813090] 'process raft request' (duration: 124.06975ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:13:33.675803Z","caller":"traceutil/trace.go:171","msg":"trace[1115318668] transaction","detail":"{read_only:false; response_revision:1982; number_of_response:1; }","duration":"151.325022ms","start":"2026-05-05T22:13:33.524447Z","end":"2026-05-05T22:13:33.675772Z","steps":["trace[1115318668] 'process raft request' (duration: 151.13267ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:14:01.028228Z","caller":"traceutil/trace.go:171","msg":"trace[852384105] transaction","detail":"{read_only:false; response_revision:2148; number_of_response:1; }","duration":"164.470663ms","start":"2026-05-05T22:14:00.863736Z","end":"2026-05-05T22:14:01.028207Z","steps":["trace[852384105] 'process raft request' (duration: 164.354087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:14:01.395968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.998354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllers/\" range_end:\"/registry/controllers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:14:01.396058Z","caller":"traceutil/trace.go:171","msg":"trace[689504771] range","detail":"{range_begin:/registry/controllers/; range_end:/registry/controllers0; response_count:0; response_revision:2148; }","duration":"247.12149ms","start":"2026-05-05T22:14:01.148919Z","end":"2026-05-05T22:14:01.39604Z","steps":["trace[689504771] 'count revisions from in-memory index tree' (duration: 246.949031ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:14:01.396125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"293.125564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-05-05T22:14:01.396225Z","caller":"traceutil/trace.go:171","msg":"trace[1469671623] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2148; }","duration":"293.292243ms","start":"2026-05-05T22:14:01.102907Z","end":"2026-05-05T22:14:01.396199Z","steps":["trace[1469671623] 'range keys from in-memory index tree' (duration: 292.897591ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:00.877864Z","caller":"traceutil/trace.go:171","msg":"trace[465389547] linearizableReadLoop","detail":"{readStateIndex:2669; appliedIndex:2668; }","duration":"189.974037ms","start":"2026-05-05T22:15:00.687865Z","end":"2026-05-05T22:15:00.877839Z","steps":["trace[465389547] 'read index received' (duration: 178.304152ms)","trace[465389547] 'applied index is now lower than readState.Index' (duration: 11.668675ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:15:00.878018Z","caller":"traceutil/trace.go:171","msg":"trace[1639576142] transaction","detail":"{read_only:false; response_revision:2573; number_of_response:1; }","duration":"195.323083ms","start":"2026-05-05T22:15:00.682669Z","end":"2026-05-05T22:15:00.877992Z","steps":["trace[1639576142] 'process raft request' (duration: 183.477382ms)","trace[1639576142] 'compare' (duration: 11.563271ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:15:00.87811Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.223485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T22:15:00.878188Z","caller":"traceutil/trace.go:171","msg":"trace[1202693229] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2573; }","duration":"190.333539ms","start":"2026-05-05T22:15:00.68784Z","end":"2026-05-05T22:15:00.878173Z","steps":["trace[1202693229] 'agreement among raft nodes before linearized reading' (duration: 190.109111ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:33.167685Z","caller":"traceutil/trace.go:171","msg":"trace[1547004508] transaction","detail":"{read_only:false; response_revision:2752; number_of_response:1; }","duration":"113.898197ms","start":"2026-05-05T22:15:33.053757Z","end":"2026-05-05T22:15:33.167655Z","steps":["trace[1547004508] 'process raft request' (duration: 113.842486ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:15:33.167746Z","caller":"traceutil/trace.go:171","msg":"trace[1472828326] transaction","detail":"{read_only:false; response_revision:2751; number_of_response:1; }","duration":"130.401679ms","start":"2026-05-05T22:15:33.037309Z","end":"2026-05-05T22:15:33.167711Z","steps":["trace[1472828326] 'process raft request' (duration: 129.147095ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:08.373761Z","caller":"traceutil/trace.go:171","msg":"trace[86820053] transaction","detail":"{read_only:false; response_revision:4505; number_of_response:1; }","duration":"195.954807ms","start":"2026-05-05T22:19:08.17778Z","end":"2026-05-05T22:19:08.373735Z","steps":["trace[86820053] 'process raft request' (duration: 166.673943ms)","trace[86820053] 'compare' (duration: 29.1829ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:19:08.39818Z","caller":"traceutil/trace.go:171","msg":"trace[2126955824] linearizableReadLoop","detail":"{readStateIndex:4679; appliedIndex:4676; }","duration":"106.782677ms","start":"2026-05-05T22:19:08.291377Z","end":"2026-05-05T22:19:08.39816Z","steps":["trace[2126955824] 'read index received' (duration: 53.087358ms)","trace[2126955824] 'applied index is now lower than readState.Index' (duration: 53.694439ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:19:08.398557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.045649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T22:19:08.398636Z","caller":"traceutil/trace.go:171","msg":"trace[135173967] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4507; }","duration":"103.157954ms","start":"2026-05-05T22:19:08.295463Z","end":"2026-05-05T22:19:08.39862Z","steps":["trace[135173967] 'agreement among raft nodes before linearized reading' (duration: 102.957546ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:19:08.398541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.129639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T22:19:08.398792Z","caller":"traceutil/trace.go:171","msg":"trace[47189479] transaction","detail":"{read_only:false; response_revision:4506; number_of_response:1; }","duration":"186.309173ms","start":"2026-05-05T22:19:08.212464Z","end":"2026-05-05T22:19:08.398773Z","steps":["trace[47189479] 'process raft request' (duration: 185.063128ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:08.398813Z","caller":"traceutil/trace.go:171","msg":"trace[856419843] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:0; response_revision:4507; }","duration":"107.457141ms","start":"2026-05-05T22:19:08.291345Z","end":"2026-05-05T22:19:08.398802Z","steps":["trace[856419843] 'agreement among raft nodes before linearized reading' (duration: 107.035036ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:08.398894Z","caller":"traceutil/trace.go:171","msg":"trace[346128771] transaction","detail":"{read_only:false; response_revision:4507; number_of_response:1; }","duration":"179.061007ms","start":"2026-05-05T22:19:08.219813Z","end":"2026-05-05T22:19:08.398874Z","steps":["trace[346128771] 'process raft request' (duration: 178.292129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:19:15.742504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.249428ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T22:19:15.742756Z","caller":"traceutil/trace.go:171","msg":"trace[650891714] linearizableReadLoop","detail":"{readStateIndex:4846; appliedIndex:4843; }","duration":"168.803586ms","start":"2026-05-05T22:19:15.573935Z","end":"2026-05-05T22:19:15.742739Z","steps":["trace[650891714] 'read index received' (duration: 48.133973ms)","trace[650891714] 'applied index is now lower than readState.Index' (duration: 120.668652ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:19:15.742783Z","caller":"traceutil/trace.go:171","msg":"trace[1908219666] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"172.536369ms","start":"2026-05-05T22:19:15.570207Z","end":"2026-05-05T22:19:15.742744Z","steps":["trace[1908219666] 'process raft request' (duration: 51.849676ms)","trace[1908219666] 'compare' (duration: 120.075551ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:19:15.743014Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.81452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-05-05T22:19:15.743061Z","caller":"traceutil/trace.go:171","msg":"trace[1855320525] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4669; }","duration":"167.872483ms","start":"2026-05-05T22:19:15.575177Z","end":"2026-05-05T22:19:15.74305Z","steps":["trace[1855320525] 'agreement among raft nodes before linearized reading' (duration: 167.672135ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:19:15.743104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.143798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9318"} {"level":"info","ts":"2026-05-05T22:19:15.743191Z","caller":"traceutil/trace.go:171","msg":"trace[964078109] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4669; }","duration":"169.252342ms","start":"2026-05-05T22:19:15.573918Z","end":"2026-05-05T22:19:15.743171Z","steps":["trace[964078109] 'agreement among raft nodes before linearized reading' (duration: 168.91674ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:15.743175Z","caller":"traceutil/trace.go:171","msg":"trace[1407142007] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"169.624735ms","start":"2026-05-05T22:19:15.573463Z","end":"2026-05-05T22:19:15.743088Z","steps":["trace[1407142007] 'process raft request' (duration: 169.21624ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:15.743193Z","caller":"traceutil/trace.go:171","msg":"trace[1872141124] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"171.200741ms","start":"2026-05-05T22:19:15.571972Z","end":"2026-05-05T22:19:15.743173Z","steps":["trace[1872141124] 'process raft request' (duration: 170.643631ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:19:15.743422Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.20432ms","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-05-05T22:19:15.743474Z","caller":"traceutil/trace.go:171","msg":"trace[869251461] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4669; }","duration":"150.287563ms","start":"2026-05-05T22:19:15.593171Z","end":"2026-05-05T22:19:15.743458Z","steps":["trace[869251461] 'agreement among raft nodes before linearized reading' (duration: 150.077956ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:19:16.338593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.454649ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12972"} {"level":"warn","ts":"2026-05-05T22:19:16.338655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.043567ms","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-05-05T22:19:16.338732Z","caller":"traceutil/trace.go:171","msg":"trace[786917341] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4690; }","duration":"201.164471ms","start":"2026-05-05T22:19:16.137549Z","end":"2026-05-05T22:19:16.338713Z","steps":["trace[786917341] 'range keys from in-memory index tree' (duration: 200.857431ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:19:16.338831Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.790593ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2194894"} {"level":"info","ts":"2026-05-05T22:19:16.338925Z","caller":"traceutil/trace.go:171","msg":"trace[1276152302] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4690; }","duration":"250.929038ms","start":"2026-05-05T22:19:16.087975Z","end":"2026-05-05T22:19:16.338904Z","steps":["trace[1276152302] 'range keys from in-memory index tree' (duration: 249.686074ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:16.338681Z","caller":"traceutil/trace.go:171","msg":"trace[1045482197] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4690; }","duration":"160.602423ms","start":"2026-05-05T22:19:16.178056Z","end":"2026-05-05T22:19:16.338659Z","steps":["trace[1045482197] 'range keys from in-memory index tree' (duration: 160.20962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:19:16.33909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.428439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumcidrgroups/\" range_end:\"/registry/cilium.io/ciliumcidrgroups0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:19:16.339299Z","caller":"traceutil/trace.go:171","msg":"trace[191542150] range","detail":"{range_begin:/registry/cilium.io/ciliumcidrgroups/; range_end:/registry/cilium.io/ciliumcidrgroups0; response_count:0; response_revision:4690; }","duration":"135.010599ms","start":"2026-05-05T22:19:16.20427Z","end":"2026-05-05T22:19:16.33928Z","steps":["trace[191542150] 'count revisions from in-memory index tree' (duration: 134.367057ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:19:16.339171Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.204105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-6fc59db46b-gbfk7.18accb5d757a24b3\" ","response":"range_response_count:1 size:896"} {"level":"info","ts":"2026-05-05T22:19:16.339827Z","caller":"traceutil/trace.go:171","msg":"trace[1382864692] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-6fc59db46b-gbfk7.18accb5d757a24b3; range_end:; response_count:1; response_revision:4690; }","duration":"161.775275ms","start":"2026-05-05T22:19:16.177918Z","end":"2026-05-05T22:19:16.339693Z","steps":["trace[1382864692] 'range keys from in-memory index tree' (duration: 161.06883ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:16.475109Z","caller":"traceutil/trace.go:171","msg":"trace[674565557] transaction","detail":"{read_only:false; response_revision:4692; number_of_response:1; }","duration":"130.207269ms","start":"2026-05-05T22:19:16.344879Z","end":"2026-05-05T22:19:16.475086Z","steps":["trace[674565557] 'process raft request' (duration: 130.004432ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:16.475324Z","caller":"traceutil/trace.go:171","msg":"trace[1175243607] transaction","detail":"{read_only:false; response_revision:4693; number_of_response:1; }","duration":"123.869033ms","start":"2026-05-05T22:19:16.351433Z","end":"2026-05-05T22:19:16.475302Z","steps":["trace[1175243607] 'process raft request' (duration: 123.582982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:19:16.475614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.567371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"warn","ts":"2026-05-05T22:19:16.475713Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.543016ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-943263fa-4ee1-4213-9a7e-f5625ffb1f22\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-05-05T22:19:16.475767Z","caller":"traceutil/trace.go:171","msg":"trace[1671875581] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4693; }","duration":"107.759287ms","start":"2026-05-05T22:19:16.367987Z","end":"2026-05-05T22:19:16.475746Z","steps":["trace[1671875581] 'agreement among raft nodes before linearized reading' (duration: 107.346523ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:16.475787Z","caller":"traceutil/trace.go:171","msg":"trace[307239733] range","detail":"{range_begin:/registry/persistentvolumes/pvc-943263fa-4ee1-4213-9a7e-f5625ffb1f22; range_end:; response_count:1; response_revision:4693; }","duration":"131.638049ms","start":"2026-05-05T22:19:16.344131Z","end":"2026-05-05T22:19:16.475769Z","steps":["trace[307239733] 'agreement among raft nodes before linearized reading' (duration: 131.435872ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:16.681285Z","caller":"traceutil/trace.go:171","msg":"trace[1191141630] transaction","detail":"{read_only:false; response_revision:4700; number_of_response:1; }","duration":"122.655628ms","start":"2026-05-05T22:19:16.558606Z","end":"2026-05-05T22:19:16.681261Z","steps":["trace[1191141630] 'process raft request' (duration: 122.556225ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:17.116563Z","caller":"traceutil/trace.go:171","msg":"trace[502542363] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"132.244208ms","start":"2026-05-05T22:19:16.984279Z","end":"2026-05-05T22:19:17.116523Z","steps":["trace[502542363] 'process raft request' (duration: 131.988209ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:17.300654Z","caller":"traceutil/trace.go:171","msg":"trace[1812477609] transaction","detail":"{read_only:false; response_revision:4706; number_of_response:1; }","duration":"108.75881ms","start":"2026-05-05T22:19:17.191866Z","end":"2026-05-05T22:19:17.300624Z","steps":["trace[1812477609] 'process raft request' (duration: 20.402706ms)","trace[1812477609] 'compare' (duration: 87.838625ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:19:29.447095Z","caller":"traceutil/trace.go:171","msg":"trace[812174637] linearizableReadLoop","detail":"{readStateIndex:5053; appliedIndex:5052; }","duration":"117.034617ms","start":"2026-05-05T22:19:29.33004Z","end":"2026-05-05T22:19:29.447075Z","steps":["trace[812174637] 'read index received' (duration: 39.303436ms)","trace[812174637] 'applied index is now lower than readState.Index' (duration: 77.730231ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:19:29.447337Z","caller":"traceutil/trace.go:171","msg":"trace[397684758] transaction","detail":"{read_only:false; response_revision:4872; number_of_response:1; }","duration":"102.945735ms","start":"2026-05-05T22:19:29.34438Z","end":"2026-05-05T22:19:29.447326Z","steps":["trace[397684758] 'process raft request' (duration: 102.824591ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:19:29.447378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.321087ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10391"} {"level":"info","ts":"2026-05-05T22:19:29.44738Z","caller":"traceutil/trace.go:171","msg":"trace[624579019] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"103.278676ms","start":"2026-05-05T22:19:29.344067Z","end":"2026-05-05T22:19:29.447345Z","steps":["trace[624579019] 'process raft request' (duration: 102.969786ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:29.44746Z","caller":"traceutil/trace.go:171","msg":"trace[918792642] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4871; }","duration":"117.440981ms","start":"2026-05-05T22:19:29.330001Z","end":"2026-05-05T22:19:29.447442Z","steps":["trace[918792642] 'agreement among raft nodes before linearized reading' (duration: 117.203543ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:29.447087Z","caller":"traceutil/trace.go:171","msg":"trace[1682237262] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"213.983386ms","start":"2026-05-05T22:19:29.233066Z","end":"2026-05-05T22:19:29.447049Z","steps":["trace[1682237262] 'process raft request' (duration: 136.340158ms)","trace[1682237262] 'compare' (duration: 77.525354ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:19:29.447637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.110942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:19:29.447728Z","caller":"traceutil/trace.go:171","msg":"trace[341393979] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4872; }","duration":"108.260927ms","start":"2026-05-05T22:19:29.339459Z","end":"2026-05-05T22:19:29.44772Z","steps":["trace[341393979] 'agreement among raft nodes before linearized reading' (duration: 107.909305ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:29.651142Z","caller":"traceutil/trace.go:171","msg":"trace[446099222] transaction","detail":"{read_only:false; response_revision:4876; number_of_response:1; }","duration":"100.199521ms","start":"2026-05-05T22:19:29.550919Z","end":"2026-05-05T22:19:29.651119Z","steps":["trace[446099222] 'process raft request' (duration: 100.054386ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:29.651424Z","caller":"traceutil/trace.go:171","msg":"trace[1449171562] transaction","detail":"{read_only:false; response_revision:4878; number_of_response:1; }","duration":"100.314145ms","start":"2026-05-05T22:19:29.551085Z","end":"2026-05-05T22:19:29.6514Z","steps":["trace[1449171562] 'process raft request' (duration: 100.202311ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:19:29.651436Z","caller":"traceutil/trace.go:171","msg":"trace[1315890869] transaction","detail":"{read_only:false; response_revision:4877; number_of_response:1; }","duration":"100.348345ms","start":"2026-05-05T22:19:29.55103Z","end":"2026-05-05T22:19:29.651379Z","steps":["trace[1315890869] 'process raft request' (duration: 100.047335ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:20:00.166242Z","caller":"traceutil/trace.go:171","msg":"trace[771471349] transaction","detail":"{read_only:false; response_revision:5160; number_of_response:1; }","duration":"109.765466ms","start":"2026-05-05T22:20:00.056445Z","end":"2026-05-05T22:20:00.166211Z","steps":["trace[771471349] 'process raft request' (duration: 109.592411ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:21:05.989554Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2922} {"level":"info","ts":"2026-05-05T22:21:06.081037Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2922,"took":"90.028891ms","hash":603278500,"current-db-size-bytes":54370304,"current-db-size":"54 MB","current-db-size-in-use-bytes":29577216,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-05-05T22:21:06.081119Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":603278500,"revision":2922,"compact-revision":-1} {"level":"info","ts":"2026-05-05T22:21:58.66982Z","caller":"traceutil/trace.go:171","msg":"trace[721767487] linearizableReadLoop","detail":"{readStateIndex:6122; appliedIndex:6121; }","duration":"361.724302ms","start":"2026-05-05T22:21:58.30807Z","end":"2026-05-05T22:21:58.669794Z","steps":["trace[721767487] 'read index received' (duration: 361.607759ms)","trace[721767487] 'applied index is now lower than readState.Index' (duration: 115.593µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:21:58.669885Z","caller":"traceutil/trace.go:171","msg":"trace[1122154178] transaction","detail":"{read_only:false; response_revision:5907; number_of_response:1; }","duration":"532.111829ms","start":"2026-05-05T22:21:58.137743Z","end":"2026-05-05T22:21:58.669854Z","steps":["trace[1122154178] 'process raft request' (duration: 531.898854ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:21:58.670053Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"361.957079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-05-05T22:21:58.670038Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:21:58.137732Z","time spent":"532.214471ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:21:58.67012Z","caller":"traceutil/trace.go:171","msg":"trace[1180463795] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:5907; }","duration":"362.078221ms","start":"2026-05-05T22:21:58.308025Z","end":"2026-05-05T22:21:58.670104Z","steps":["trace[1180463795] 'agreement among raft nodes before linearized reading' (duration: 361.897317ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:21:58.670174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:21:58.308003Z","time spent":"362.154433ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":553,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-05-05T22:21:58.671036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"330.953838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:21:58.671122Z","caller":"traceutil/trace.go:171","msg":"trace[2059406156] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5908; }","duration":"331.092291ms","start":"2026-05-05T22:21:58.34001Z","end":"2026-05-05T22:21:58.671103Z","steps":["trace[2059406156] 'agreement among raft nodes before linearized reading' (duration: 330.955428ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:21:58.671169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:21:58.339982Z","time spent":"331.178583ms","remote":"127.0.0.1:46960","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-05-05T22:21:58.671923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.368012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-05T22:21:58.671974Z","caller":"traceutil/trace.go:171","msg":"trace[1690411066] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5908; }","duration":"198.463864ms","start":"2026-05-05T22:21:58.473497Z","end":"2026-05-05T22:21:58.671961Z","steps":["trace[1690411066] 'agreement among raft nodes before linearized reading' (duration: 198.28668ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:00.736701Z","caller":"traceutil/trace.go:171","msg":"trace[1551655361] transaction","detail":"{read_only:false; response_revision:5917; number_of_response:1; }","duration":"783.491077ms","start":"2026-05-05T22:21:59.953167Z","end":"2026-05-05T22:22:00.736658Z","steps":["trace[1551655361] 'process raft request' (duration: 783.325273ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:00.737777Z","caller":"traceutil/trace.go:171","msg":"trace[1668542606] linearizableReadLoop","detail":"{readStateIndex:6134; appliedIndex:6133; }","duration":"397.65879ms","start":"2026-05-05T22:22:00.340089Z","end":"2026-05-05T22:22:00.737747Z","steps":["trace[1668542606] 'read index received' (duration: 396.82293ms)","trace[1668542606] 'applied index is now lower than readState.Index' (duration: 834.72µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:22:00.738194Z","caller":"traceutil/trace.go:171","msg":"trace[175178832] transaction","detail":"{read_only:false; response_revision:5918; number_of_response:1; }","duration":"780.951374ms","start":"2026-05-05T22:21:59.957225Z","end":"2026-05-05T22:22:00.738176Z","steps":["trace[175178832] 'process raft request' (duration: 780.306418ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:00.738457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:21:59.957212Z","time spent":"781.032207ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:22:00.739154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:21:59.953146Z","time spent":"783.646712ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:22:00.739273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"399.174288ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:22:00.739386Z","caller":"traceutil/trace.go:171","msg":"trace[1039163217] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5920; }","duration":"399.2952ms","start":"2026-05-05T22:22:00.340024Z","end":"2026-05-05T22:22:00.739319Z","steps":["trace[1039163217] 'agreement among raft nodes before linearized reading' (duration: 399.205348ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:00.739477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:00.340006Z","time spent":"399.457695ms","remote":"127.0.0.1:46960","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-05-05T22:22:00.739648Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"396.152742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-05-05T22:22:00.739711Z","caller":"traceutil/trace.go:171","msg":"trace[1872341726] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:5920; }","duration":"396.234925ms","start":"2026-05-05T22:22:00.343459Z","end":"2026-05-05T22:22:00.739694Z","steps":["trace[1872341726] 'agreement among raft nodes before linearized reading' (duration: 396.04844ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:00.739758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:00.343448Z","time spent":"396.299366ms","remote":"127.0.0.1:47168","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2009,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"warn","ts":"2026-05-05T22:22:02.327516Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700323451271450140,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T22:22:02.481941Z","caller":"traceutil/trace.go:171","msg":"trace[255863644] linearizableReadLoop","detail":"{readStateIndex:6141; appliedIndex:6140; }","duration":"654.929172ms","start":"2026-05-05T22:22:01.826994Z","end":"2026-05-05T22:22:02.481923Z","steps":["trace[255863644] 'read index received' (duration: 654.806119ms)","trace[255863644] 'applied index is now lower than readState.Index' (duration: 122.423µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:22:02.482016Z","caller":"traceutil/trace.go:171","msg":"trace[1396453274] transaction","detail":"{read_only:false; response_revision:5925; number_of_response:1; }","duration":"736.291876ms","start":"2026-05-05T22:22:01.745716Z","end":"2026-05-05T22:22:02.482008Z","steps":["trace[1396453274] 'process raft request' (duration: 736.098972ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:02.482062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.527909ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:22:02.482091Z","caller":"traceutil/trace.go:171","msg":"trace[579186747] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5925; }","duration":"143.598371ms","start":"2026-05-05T22:22:02.338485Z","end":"2026-05-05T22:22:02.482083Z","steps":["trace[579186747] 'agreement among raft nodes before linearized reading' (duration: 143.54528ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:02.48209Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:01.745693Z","time spent":"736.345388ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:22:02.4822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"655.204469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:22:02.482217Z","caller":"traceutil/trace.go:171","msg":"trace[476721550] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:5925; }","duration":"655.25473ms","start":"2026-05-05T22:22:01.826955Z","end":"2026-05-05T22:22:02.48221Z","steps":["trace[476721550] 'agreement among raft nodes before linearized reading' (duration: 655.22889ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:02.482231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:01.826938Z","time spent":"655.288571ms","remote":"127.0.0.1:47202","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:22:02.965986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"390.845006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T22:22:02.966043Z","caller":"traceutil/trace.go:171","msg":"trace[71402515] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5925; }","duration":"390.940609ms","start":"2026-05-05T22:22:02.57509Z","end":"2026-05-05T22:22:02.96603Z","steps":["trace[71402515] 'range keys from in-memory index tree' (duration: 390.722132ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:02.96607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:02.575073Z","time spent":"390.99129ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T22:22:02.966233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.703898ms","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-05-05T22:22:02.966264Z","caller":"traceutil/trace.go:171","msg":"trace[136952753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5925; }","duration":"389.750739ms","start":"2026-05-05T22:22:02.576504Z","end":"2026-05-05T22:22:02.966255Z","steps":["trace[136952753] 'range keys from in-memory index tree' (duration: 389.633836ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:02.966289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:02.576496Z","time spent":"389.78755ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T22:22:02.966522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.481712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:22:02.966588Z","caller":"traceutil/trace.go:171","msg":"trace[1965921235] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:5925; }","duration":"389.576484ms","start":"2026-05-05T22:22:02.576997Z","end":"2026-05-05T22:22:02.966573Z","steps":["trace[1965921235] 'count revisions from in-memory index tree' (duration: 389.442231ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:02.966623Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:02.576991Z","time spent":"389.624266ms","remote":"127.0.0.1:43212","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:22:02.966789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"375.208719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T22:22:02.966841Z","caller":"traceutil/trace.go:171","msg":"trace[110448389] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:5925; }","duration":"375.27809ms","start":"2026-05-05T22:22:02.591549Z","end":"2026-05-05T22:22:02.966827Z","steps":["trace[110448389] 'count revisions from in-memory index tree' (duration: 375.119187ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:02.966873Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.856942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-api-bffc8f656-hlsrc\" ","response":"range_response_count:1 size:12280"} {"level":"warn","ts":"2026-05-05T22:22:02.966875Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:02.59154Z","time spent":"375.325632ms","remote":"127.0.0.1:47236","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":30,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"info","ts":"2026-05-05T22:22:02.966905Z","caller":"traceutil/trace.go:171","msg":"trace[902092117] range","detail":"{range_begin:/registry/pods/openstack/keystone-api-bffc8f656-hlsrc; range_end:; response_count:1; response_revision:5925; }","duration":"387.904363ms","start":"2026-05-05T22:22:02.578993Z","end":"2026-05-05T22:22:02.966898Z","steps":["trace[902092117] 'range keys from in-memory index tree' (duration: 387.78179ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:02.966924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:02.578987Z","time spent":"387.932944ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":12303,"request content":"key:\"/registry/pods/openstack/keystone-api-bffc8f656-hlsrc\" "} {"level":"info","ts":"2026-05-05T22:22:02.96705Z","caller":"traceutil/trace.go:171","msg":"trace[265711757] transaction","detail":"{read_only:false; response_revision:5926; number_of_response:1; }","duration":"221.18135ms","start":"2026-05-05T22:22:02.745859Z","end":"2026-05-05T22:22:02.967041Z","steps":["trace[265711757] 'process raft request' (duration: 216.076133ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:02.96727Z","caller":"traceutil/trace.go:171","msg":"trace[1204211157] linearizableReadLoop","detail":"{readStateIndex:6144; appliedIndex:6141; }","duration":"213.189013ms","start":"2026-05-05T22:22:02.754068Z","end":"2026-05-05T22:22:02.967257Z","steps":["trace[1204211157] 'read index received' (duration: 207.80915ms)","trace[1204211157] 'applied index is now lower than readState.Index' (duration: 5.379153ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:22:02.967357Z","caller":"traceutil/trace.go:171","msg":"trace[774209937] transaction","detail":"{read_only:false; response_revision:5928; number_of_response:1; }","duration":"219.56813ms","start":"2026-05-05T22:22:02.747769Z","end":"2026-05-05T22:22:02.967338Z","steps":["trace[774209937] 'process raft request' (duration: 219.440597ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:02.967401Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.334916ms","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-05-05T22:22:02.967431Z","caller":"traceutil/trace.go:171","msg":"trace[716900570] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5928; }","duration":"213.394258ms","start":"2026-05-05T22:22:02.754028Z","end":"2026-05-05T22:22:02.967422Z","steps":["trace[716900570] 'agreement among raft nodes before linearized reading' (duration: 213.311416ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:02.967483Z","caller":"traceutil/trace.go:171","msg":"trace[1623317238] transaction","detail":"{read_only:false; response_revision:5927; number_of_response:1; }","duration":"220.076902ms","start":"2026-05-05T22:22:02.747399Z","end":"2026-05-05T22:22:02.967476Z","steps":["trace[1623317238] 'process raft request' (duration: 219.57411ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:04.071079Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.336849ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"warn","ts":"2026-05-05T22:22:04.071137Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"730.917039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:22:04.071171Z","caller":"traceutil/trace.go:171","msg":"trace[1833118883] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5938; }","duration":"316.490193ms","start":"2026-05-05T22:22:03.754662Z","end":"2026-05-05T22:22:04.071152Z","steps":["trace[1833118883] 'range keys from in-memory index tree' (duration: 316.046692ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:04.071201Z","caller":"traceutil/trace.go:171","msg":"trace[1029688822] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5938; }","duration":"730.98822ms","start":"2026-05-05T22:22:03.340194Z","end":"2026-05-05T22:22:04.071183Z","steps":["trace[1029688822] 'range keys from in-memory index tree' (duration: 730.854477ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:04.071221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:03.754637Z","time spent":"316.570905ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-05T22:22:04.071241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:03.34016Z","time spent":"731.073133ms","remote":"127.0.0.1:46960","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-05-05T22:22:04.071515Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"739.594062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T22:22:04.071548Z","caller":"traceutil/trace.go:171","msg":"trace[1412674665] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5938; }","duration":"739.666884ms","start":"2026-05-05T22:22:03.331874Z","end":"2026-05-05T22:22:04.071541Z","steps":["trace[1412674665] 'range keys from in-memory index tree' (duration: 738.596368ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:04.071621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:03.331852Z","time spent":"739.762467ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-05-05T22:22:05.422488Z","caller":"traceutil/trace.go:171","msg":"trace[324335398] transaction","detail":"{read_only:false; response_revision:5941; number_of_response:1; }","duration":"449.208577ms","start":"2026-05-05T22:22:04.973251Z","end":"2026-05-05T22:22:05.42246Z","steps":["trace[324335398] 'process raft request' (duration: 449.002652ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.422659Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:04.973223Z","time spent":"449.34133ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:22:05.906542Z","caller":"traceutil/trace.go:171","msg":"trace[1485726856] transaction","detail":"{read_only:false; response_revision:5942; number_of_response:1; }","duration":"932.973624ms","start":"2026-05-05T22:22:04.973547Z","end":"2026-05-05T22:22:05.90652Z","steps":["trace[1485726856] 'process raft request' (duration: 931.297143ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.906666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:04.973525Z","time spent":"933.078877ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:22:05.906978Z","caller":"traceutil/trace.go:171","msg":"trace[302714388] linearizableReadLoop","detail":"{readStateIndex:6160; appliedIndex:6157; }","duration":"932.450151ms","start":"2026-05-05T22:22:04.974515Z","end":"2026-05-05T22:22:05.906966Z","steps":["trace[302714388] 'read index received' (duration: 447.71009ms)","trace[302714388] 'applied index is now lower than readState.Index' (duration: 484.738661ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:22:05.906982Z","caller":"traceutil/trace.go:171","msg":"trace[623142211] transaction","detail":"{read_only:false; response_revision:5944; number_of_response:1; }","duration":"258.540039ms","start":"2026-05-05T22:22:05.648418Z","end":"2026-05-05T22:22:05.906958Z","steps":["trace[623142211] 'process raft request' (duration: 258.457297ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.907152Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.645387ms","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-05-05T22:22:05.907183Z","caller":"traceutil/trace.go:171","msg":"trace[2009294409] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5944; }","duration":"932.720758ms","start":"2026-05-05T22:22:04.974455Z","end":"2026-05-05T22:22:05.907175Z","steps":["trace[2009294409] 'agreement among raft nodes before linearized reading' (duration: 932.603325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.907204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:04.974432Z","time spent":"932.766539ms","remote":"127.0.0.1:47238","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-05-05T22:22:05.907042Z","caller":"traceutil/trace.go:171","msg":"trace[1902657383] transaction","detail":"{read_only:false; response_revision:5943; number_of_response:1; }","duration":"932.738759ms","start":"2026-05-05T22:22:04.974296Z","end":"2026-05-05T22:22:05.907035Z","steps":["trace[1902657383] 'process raft request' (duration: 932.187635ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.907371Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"930.864453ms","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-05-05T22:22:05.907396Z","caller":"traceutil/trace.go:171","msg":"trace[1612430233] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5944; }","duration":"930.909134ms","start":"2026-05-05T22:22:04.976481Z","end":"2026-05-05T22:22:05.90739Z","steps":["trace[1612430233] 'agreement among raft nodes before linearized reading' (duration: 930.828342ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.907415Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:04.976471Z","time spent":"930.939444ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-05-05T22:22:05.90745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"565.477591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T22:22:05.907514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"931.378895ms","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-05-05T22:22:05.90757Z","caller":"traceutil/trace.go:171","msg":"trace[341162426] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5944; }","duration":"565.626654ms","start":"2026-05-05T22:22:05.341908Z","end":"2026-05-05T22:22:05.907534Z","steps":["trace[341162426] 'agreement among raft nodes before linearized reading' (duration: 565.493761ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.90766Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:05.341883Z","time spent":"565.767448ms","remote":"127.0.0.1:46960","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-05-05T22:22:05.90757Z","caller":"traceutil/trace.go:171","msg":"trace[1292361812] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5944; }","duration":"931.442267ms","start":"2026-05-05T22:22:04.976106Z","end":"2026-05-05T22:22:05.907548Z","steps":["trace[1292361812] 'agreement among raft nodes before linearized reading' (duration: 931.330474ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.907684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"928.239738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/keystone-api\" ","response":"range_response_count:1 size:673"} {"level":"warn","ts":"2026-05-05T22:22:05.907299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"932.520943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-05-05T22:22:05.907727Z","caller":"traceutil/trace.go:171","msg":"trace[1092885236] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5944; }","duration":"932.968495ms","start":"2026-05-05T22:22:04.974751Z","end":"2026-05-05T22:22:05.907719Z","steps":["trace[1092885236] 'agreement among raft nodes before linearized reading' (duration: 932.508933ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:05.907743Z","caller":"traceutil/trace.go:171","msg":"trace[356845665] range","detail":"{range_begin:/registry/services/endpoints/openstack/keystone-api; range_end:; response_count:1; response_revision:5944; }","duration":"928.287899ms","start":"2026-05-05T22:22:04.979415Z","end":"2026-05-05T22:22:05.907703Z","steps":["trace[356845665] 'agreement among raft nodes before linearized reading' (duration: 927.883589ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:05.907757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:04.97474Z","time spent":"933.009175ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T22:22:05.907775Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:04.979403Z","time spent":"928.364011ms","remote":"127.0.0.1:47148","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":696,"request content":"key:\"/registry/services/endpoints/openstack/keystone-api\" "} {"level":"warn","ts":"2026-05-05T22:22:05.907676Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:04.97429Z","time spent":"933.288232ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:22:05.908284Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:04.976098Z","time spent":"932.170535ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T22:22:07.118192Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5700323451271450196,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T22:22:07.344909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.263690231s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-05-05T22:22:07.345045Z","caller":"traceutil/trace.go:171","msg":"trace[1673268385] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5948; }","duration":"1.263887245s","start":"2026-05-05T22:22:06.081142Z","end":"2026-05-05T22:22:07.345029Z","steps":["trace[1673268385] 'range keys from in-memory index tree' (duration: 1.263559237s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:07.345102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:06.081124Z","time spent":"1.263967498s","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-05-05T22:22:07.345485Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.185098357s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:22:07.345567Z","caller":"traceutil/trace.go:171","msg":"trace[1373615560] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secretexports/; range_end:/registry/secretgen.carvel.dev/secretexports0; response_count:0; response_revision:5948; }","duration":"1.18521442s","start":"2026-05-05T22:22:06.160336Z","end":"2026-05-05T22:22:07.345551Z","steps":["trace[1373615560] 'count revisions from in-memory index tree' (duration: 1.184988564s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:07.34563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:06.160316Z","time spent":"1.185302833s","remote":"127.0.0.1:47056","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/secretgen.carvel.dev/secretexports/\" range_end:\"/registry/secretgen.carvel.dev/secretexports0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:22:07.34609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"976.69702ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:22:07.346195Z","caller":"traceutil/trace.go:171","msg":"trace[121941172] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5948; }","duration":"976.802563ms","start":"2026-05-05T22:22:06.369383Z","end":"2026-05-05T22:22:07.346186Z","steps":["trace[121941172] 'range keys from in-memory index tree' (duration: 976.68698ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:07.346351Z","caller":"traceutil/trace.go:171","msg":"trace[1843446603] linearizableReadLoop","detail":"{readStateIndex:6166; appliedIndex:6165; }","duration":"728.790151ms","start":"2026-05-05T22:22:06.617521Z","end":"2026-05-05T22:22:07.346311Z","steps":["trace[1843446603] 'read index received' (duration: 726.71313ms)","trace[1843446603] 'applied index is now lower than readState.Index' (duration: 2.076181ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:22:07.346457Z","caller":"traceutil/trace.go:171","msg":"trace[250519963] transaction","detail":"{read_only:false; response_revision:5949; number_of_response:1; }","duration":"853.457207ms","start":"2026-05-05T22:22:06.492989Z","end":"2026-05-05T22:22:07.346446Z","steps":["trace[250519963] 'process raft request' (duration: 851.295784ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:07.346557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:06.492955Z","time spent":"853.526709ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:22:07.346689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"729.17231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T22:22:07.346684Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"539.36734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T22:22:07.346723Z","caller":"traceutil/trace.go:171","msg":"trace[1100445039] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:5949; }","duration":"729.240322ms","start":"2026-05-05T22:22:06.617472Z","end":"2026-05-05T22:22:07.346712Z","steps":["trace[1100445039] 'agreement among raft nodes before linearized reading' (duration: 729.18082ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:07.346756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:06.617452Z","time spent":"729.294243ms","remote":"127.0.0.1:47410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":10,"response size":30,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"info","ts":"2026-05-05T22:22:07.346759Z","caller":"traceutil/trace.go:171","msg":"trace[492442871] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:5949; }","duration":"539.493583ms","start":"2026-05-05T22:22:06.807245Z","end":"2026-05-05T22:22:07.346739Z","steps":["trace[492442871] 'agreement among raft nodes before linearized reading' (duration: 539.323119ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:07.346806Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:06.807224Z","time spent":"539.572235ms","remote":"127.0.0.1:47426","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":40,"response size":30,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:22:07.347024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.818946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/\" range_end:\"/registry/minions0\" ","response":"range_response_count:1 size:12169"} {"level":"info","ts":"2026-05-05T22:22:07.347096Z","caller":"traceutil/trace.go:171","msg":"trace[1249381780] range","detail":"{range_begin:/registry/minions/; range_end:/registry/minions0; response_count:1; response_revision:5949; }","duration":"231.93513ms","start":"2026-05-05T22:22:07.115151Z","end":"2026-05-05T22:22:07.347086Z","steps":["trace[1249381780] 'agreement among raft nodes before linearized reading' (duration: 231.744975ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:07.963137Z","caller":"traceutil/trace.go:171","msg":"trace[1407836935] transaction","detail":"{read_only:false; response_revision:5951; number_of_response:1; }","duration":"534.429452ms","start":"2026-05-05T22:22:07.428688Z","end":"2026-05-05T22:22:07.963117Z","steps":["trace[1407836935] 'process raft request' (duration: 483.714086ms)","trace[1407836935] 'compare' (duration: 50.288466ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T22:22:07.963238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:07.428665Z","time spent":"534.518755ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:22:09.494581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.307086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-05T22:22:09.494671Z","caller":"traceutil/trace.go:171","msg":"trace[1180806568] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5962; }","duration":"350.4423ms","start":"2026-05-05T22:22:09.144208Z","end":"2026-05-05T22:22:09.49465Z","steps":["trace[1180806568] 'range keys from in-memory index tree' (duration: 350.05698ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:09.494727Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:09.144188Z","time spent":"350.522792ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-05-05T22:22:09.494745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.412888ms","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-05-05T22:22:09.49485Z","caller":"traceutil/trace.go:171","msg":"trace[1032194975] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:5962; }","duration":"791.555971ms","start":"2026-05-05T22:22:08.703265Z","end":"2026-05-05T22:22:09.494821Z","steps":["trace[1032194975] 'count revisions from in-memory index tree' (duration: 791.324696ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:09.494916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:08.703249Z","time spent":"791.650734ms","remote":"127.0.0.1:43122","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":35,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:22:09.495103Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"517.26783ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T22:22:09.495158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.476952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-domain-manage-n4s55\" ","response":"range_response_count:1 size:8150"} {"level":"info","ts":"2026-05-05T22:22:09.495264Z","caller":"traceutil/trace.go:171","msg":"trace[1933101301] range","detail":"{range_begin:/registry/pods/openstack/keystone-domain-manage-n4s55; range_end:; response_count:1; response_revision:5962; }","duration":"173.615175ms","start":"2026-05-05T22:22:09.321635Z","end":"2026-05-05T22:22:09.49525Z","steps":["trace[1933101301] 'range keys from in-memory index tree' (duration: 173.314349ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:09.49535Z","caller":"traceutil/trace.go:171","msg":"trace[1788542415] range","detail":"{range_begin:/registry/monitoring.coreos.com/servicemonitors/; range_end:/registry/monitoring.coreos.com/servicemonitors0; response_count:0; response_revision:5962; }","duration":"517.809553ms","start":"2026-05-05T22:22:08.977381Z","end":"2026-05-05T22:22:09.495191Z","steps":["trace[1788542415] 'count revisions from in-memory index tree' (duration: 517.169397ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:09.4954Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.297566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T22:22:09.49544Z","caller":"traceutil/trace.go:171","msg":"trace[1535665451] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5962; }","duration":"157.361027ms","start":"2026-05-05T22:22:09.338067Z","end":"2026-05-05T22:22:09.495428Z","steps":["trace[1535665451] 'range keys from in-memory index tree' (duration: 157.251335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:09.495543Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:08.977361Z","time spent":"518.055399ms","remote":"127.0.0.1:54314","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":21,"response size":30,"request content":"key:\"/registry/monitoring.coreos.com/servicemonitors/\" range_end:\"/registry/monitoring.coreos.com/servicemonitors0\" count_only:true "} {"level":"warn","ts":"2026-05-05T22:22:09.495734Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.252018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.83\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-05-05T22:22:09.495777Z","caller":"traceutil/trace.go:171","msg":"trace[1258358358] range","detail":"{range_begin:/registry/masterleases/199.19.213.83; range_end:; response_count:1; response_revision:5962; }","duration":"585.33694ms","start":"2026-05-05T22:22:08.91043Z","end":"2026-05-05T22:22:09.495767Z","steps":["trace[1258358358] 'range keys from in-memory index tree' (duration: 585.100574ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:09.495805Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:08.910409Z","time spent":"585.389022ms","remote":"127.0.0.1:46980","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/199.19.213.83\" "} {"level":"info","ts":"2026-05-05T22:22:09.496055Z","caller":"traceutil/trace.go:171","msg":"trace[2069628366] transaction","detail":"{read_only:false; response_revision:5963; number_of_response:1; }","duration":"144.302397ms","start":"2026-05-05T22:22:09.351742Z","end":"2026-05-05T22:22:09.496044Z","steps":["trace[2069628366] 'process raft request' (duration: 142.552374ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:09.496281Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.931155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-05-05T22:22:09.496288Z","caller":"traceutil/trace.go:171","msg":"trace[639414743] linearizableReadLoop","detail":"{readStateIndex:6181; appliedIndex:6180; }","duration":"138.649769ms","start":"2026-05-05T22:22:09.357342Z","end":"2026-05-05T22:22:09.495992Z","steps":["trace[639414743] 'read index received' (duration: 137.009888ms)","trace[639414743] 'applied index is now lower than readState.Index' (duration: 1.638711ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:22:09.496331Z","caller":"traceutil/trace.go:171","msg":"trace[2056036581] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5963; }","duration":"138.993666ms","start":"2026-05-05T22:22:09.357321Z","end":"2026-05-05T22:22:09.496315Z","steps":["trace[2056036581] 'agreement among raft nodes before linearized reading' (duration: 138.882974ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:09.888231Z","caller":"traceutil/trace.go:171","msg":"trace[2135429191] transaction","detail":"{read_only:false; response_revision:5964; number_of_response:1; }","duration":"387.094648ms","start":"2026-05-05T22:22:09.501114Z","end":"2026-05-05T22:22:09.888209Z","steps":["trace[2135429191] 'process raft request' (duration: 386.36129ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:09.888356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:09.5011Z","time spent":"387.18481ms","remote":"127.0.0.1:46980","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":118,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T22:22:09.889583Z","caller":"traceutil/trace.go:171","msg":"trace[193650533] linearizableReadLoop","detail":"{readStateIndex:6184; appliedIndex:6182; }","duration":"387.161629ms","start":"2026-05-05T22:22:09.502402Z","end":"2026-05-05T22:22:09.889564Z","steps":["trace[193650533] 'read index received' (duration: 385.084959ms)","trace[193650533] 'applied index is now lower than readState.Index' (duration: 2.07573ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T22:22:09.889929Z","caller":"traceutil/trace.go:171","msg":"trace[1117685784] transaction","detail":"{read_only:false; response_revision:5965; number_of_response:1; }","duration":"388.748519ms","start":"2026-05-05T22:22:09.501164Z","end":"2026-05-05T22:22:09.889913Z","steps":["trace[1117685784] 'process raft request' (duration: 388.047402ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T22:22:09.89014Z","caller":"traceutil/trace.go:171","msg":"trace[384527130] transaction","detail":"{read_only:false; response_revision:5966; number_of_response:1; }","duration":"383.371916ms","start":"2026-05-05T22:22:09.506759Z","end":"2026-05-05T22:22:09.890131Z","steps":["trace[384527130] 'process raft request' (duration: 382.736391ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:09.890189Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:09.506745Z","time spent":"383.420778ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":8072,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:22:09.89013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:09.501156Z","time spent":"388.920473ms","remote":"127.0.0.1:47238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T22:22:09.890007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.60254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12214"} {"level":"info","ts":"2026-05-05T22:22:09.890434Z","caller":"traceutil/trace.go:171","msg":"trace[1466120527] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5966; }","duration":"388.063292ms","start":"2026-05-05T22:22:09.502363Z","end":"2026-05-05T22:22:09.890426Z","steps":["trace[1466120527] 'agreement among raft nodes before linearized reading' (duration: 387.58313ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T22:22:09.890458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T22:22:09.502344Z","time spent":"388.107063ms","remote":"127.0.0.1:47166","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-05-05T22:26:05.993954Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5645} {"level":"info","ts":"2026-05-05T22:26:06.064876Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5645,"took":"69.66723ms","hash":2414627298,"current-db-size-bytes":54370304,"current-db-size":"54 MB","current-db-size-in-use-bytes":18374656,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-05T22:26:06.064931Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2414627298,"revision":5645,"compact-revision":2922}