{"level":"warn","ts":"2026-07-31T21:30:29.828568Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-31T21:30:29.828661Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-07-31T21:30:29.828799Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-31T21:30:29.828813Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-31T21:30:29.828832Z","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-07-31T21:30:29.829434Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-07-31T21:30:29.829512Z","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":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-07-31T21:30:29.833356Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.643707ms"} {"level":"info","ts":"2026-07-31T21:30:29.837848Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-07-31T21:30:29.837915Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-07-31T21:30:29.837933Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-07-31T21:30:29.83795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-31T21:30:29.837965Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-07-31T21:30:29.838001Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-07-31T21:30:29.842801Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-31T21:30:29.844198Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-31T21:30:29.845479Z","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-07-31T21:30:29.848191Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-31T21:30:29.848412Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-31T21:30:29.848485Z","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-07-31T21:30:29.848785Z","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-07-31T21:30:29.848803Z","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-07-31T21:30:29.849419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-07-31T21:30:29.850286Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-07-31T21:30:29.854873Z","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-07-31T21:30:29.855021Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-31T21:30:29.85505Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-07-31T21:30:29.855335Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-31T21:30:29.855379Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-31T21:30:29.939085Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-07-31T21:30:29.93922Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-07-31T21:30:29.939261Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-07-31T21:30:29.939282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-07-31T21:30:29.9393Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-31T21:30:29.939319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-07-31T21:30:29.939339Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-07-31T21:30:29.940489Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-07-31T21:30:29.940529Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-31T21:30:29.940553Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-31T21:30:29.940806Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-31T21:30:29.94089Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-31T21:30:29.941027Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T21:30:29.943837Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-07-31T21:30:29.944117Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T21:30:29.944195Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T21:30:29.944225Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-31T21:30:29.944289Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-31T21:30:30.229373Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.494216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-31T21:30:30.229486Z","caller":"traceutil/trace.go:171","msg":"trace[805981874] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"117.66062ms","start":"2026-07-31T21:30:30.111807Z","end":"2026-07-31T21:30:30.229467Z","steps":["trace[805981874] 'count revisions from in-memory index tree' (duration: 117.461196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:30:30.229692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.929848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-31T21:30:30.229733Z","caller":"traceutil/trace.go:171","msg":"trace[943059475] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"117.973739ms","start":"2026-07-31T21:30:30.111749Z","end":"2026-07-31T21:30:30.229722Z","steps":["trace[943059475] 'range keys from in-memory index tree' (duration: 117.804045ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:30:41.814217Z","caller":"traceutil/trace.go:171","msg":"trace[2022945241] transaction","detail":"{read_only:false; response_revision:355; number_of_response:1; }","duration":"141.244583ms","start":"2026-07-31T21:30:41.672949Z","end":"2026-07-31T21:30:41.814193Z","steps":["trace[2022945241] 'process raft request' (duration: 141.058598ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:30:42.416666Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.741711ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T21:30:42.416796Z","caller":"traceutil/trace.go:171","msg":"trace[1693917053] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"493.216067ms","start":"2026-07-31T21:30:41.923563Z","end":"2026-07-31T21:30:42.416779Z","steps":["trace[1693917053] 'read index received' (duration: 183.606472ms)","trace[1693917053] 'applied index is now lower than readState.Index' (duration: 309.607985ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:30:42.416962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.399832ms","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-07-31T21:30:42.417006Z","caller":"traceutil/trace.go:171","msg":"trace[998686958] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:356; }","duration":"493.460843ms","start":"2026-07-31T21:30:41.923532Z","end":"2026-07-31T21:30:42.416993Z","steps":["trace[998686958] 'agreement among raft nodes before linearized reading' (duration: 493.378761ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:30:42.41687Z","caller":"traceutil/trace.go:171","msg":"trace[1041156364] transaction","detail":"{read_only:false; response_revision:356; number_of_response:1; }","duration":"498.679552ms","start":"2026-07-31T21:30:41.91814Z","end":"2026-07-31T21:30:42.41682Z","steps":["trace[1041156364] 'process raft request' (duration: 189.103668ms)","trace[1041156364] 'compare' (duration: 308.577857ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:30:42.417058Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:30:41.92352Z","time spent":"493.526525ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T21:30:42.417203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:30:41.918104Z","time spent":"499.00069ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:30:42.417292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.374533ms","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-07-31T21:30:42.41751Z","caller":"traceutil/trace.go:171","msg":"trace[1197455663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:356; }","duration":"153.579149ms","start":"2026-07-31T21:30:42.26384Z","end":"2026-07-31T21:30:42.417419Z","steps":["trace[1197455663] 'agreement among raft nodes before linearized reading' (duration: 153.265161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:30:42.714001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.197195ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T21:30:42.714187Z","caller":"traceutil/trace.go:171","msg":"trace[1543505811] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"292.227734ms","start":"2026-07-31T21:30:42.421877Z","end":"2026-07-31T21:30:42.714105Z","steps":["trace[1543505811] 'process raft request' (duration: 165.828883ms)","trace[1543505811] 'compare' (duration: 126.057252ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:30:42.715565Z","caller":"traceutil/trace.go:171","msg":"trace[514851226] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"288.408613ms","start":"2026-07-31T21:30:42.427123Z","end":"2026-07-31T21:30:42.715531Z","steps":["trace[514851226] 'process raft request' (duration: 288.149837ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:31:06.445305Z","caller":"traceutil/trace.go:171","msg":"trace[1926970724] transaction","detail":"{read_only:false; response_revision:509; number_of_response:1; }","duration":"132.691927ms","start":"2026-07-31T21:31:06.312588Z","end":"2026-07-31T21:31:06.44528Z","steps":["trace[1926970724] 'process raft request' (duration: 80.148259ms)","trace[1926970724] 'compare' (duration: 52.442956ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:31:06.445807Z","caller":"traceutil/trace.go:171","msg":"trace[2091936828] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"128.068215ms","start":"2026-07-31T21:31:06.317729Z","end":"2026-07-31T21:31:06.445797Z","steps":["trace[2091936828] 'process raft request' (duration: 127.885731ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:31:06.445802Z","caller":"traceutil/trace.go:171","msg":"trace[1244757923] transaction","detail":"{read_only:false; response_revision:510; number_of_response:1; }","duration":"128.059075ms","start":"2026-07-31T21:31:06.317721Z","end":"2026-07-31T21:31:06.44578Z","steps":["trace[1244757923] 'process raft request' (duration: 127.779548ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:31:06.445803Z","caller":"traceutil/trace.go:171","msg":"trace[830609629] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"128.000324ms","start":"2026-07-31T21:31:06.317776Z","end":"2026-07-31T21:31:06.445776Z","steps":["trace[830609629] 'process raft request' (duration: 127.941702ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:31:06.652461Z","caller":"traceutil/trace.go:171","msg":"trace[1348617500] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"150.425237ms","start":"2026-07-31T21:31:06.502013Z","end":"2026-07-31T21:31:06.652438Z","steps":["trace[1348617500] 'process raft request' (duration: 146.630497ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:31:06.652821Z","caller":"traceutil/trace.go:171","msg":"trace[72993155] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"137.017062ms","start":"2026-07-31T21:31:06.515767Z","end":"2026-07-31T21:31:06.652784Z","steps":["trace[72993155] 'process raft request' (duration: 136.632902ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:31:06.652898Z","caller":"traceutil/trace.go:171","msg":"trace[596357851] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"137.030742ms","start":"2026-07-31T21:31:06.515837Z","end":"2026-07-31T21:31:06.652868Z","steps":["trace[596357851] 'process raft request' (duration: 136.724454ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:31:06.65359Z","caller":"traceutil/trace.go:171","msg":"trace[795985295] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"121.565004ms","start":"2026-07-31T21:31:06.532007Z","end":"2026-07-31T21:31:06.653572Z","steps":["trace[795985295] 'process raft request' (duration: 120.959648ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:31:12.456056Z","caller":"traceutil/trace.go:171","msg":"trace[919958951] transaction","detail":"{read_only:false; response_revision:588; number_of_response:1; }","duration":"145.517576ms","start":"2026-07-31T21:31:12.31051Z","end":"2026-07-31T21:31:12.456028Z","steps":["trace[919958951] 'process raft request' (duration: 145.294441ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:31:12.71941Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.382315ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:31:12.7195Z","caller":"traceutil/trace.go:171","msg":"trace[1341770806] range","detail":"{range_begin:/registry/jobs/envoy-gateway-system/envoy-gateway-gateway-helm-certgen; range_end:; response_count:0; response_revision:588; }","duration":"251.526158ms","start":"2026-07-31T21:31:12.46795Z","end":"2026-07-31T21:31:12.719476Z","steps":["trace[1341770806] 'range keys from in-memory index tree' (duration: 251.265802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:31:13.390189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.07123ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-31T21:31:13.39032Z","caller":"traceutil/trace.go:171","msg":"trace[29997372] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:591; }","duration":"295.305806ms","start":"2026-07-31T21:31:13.094984Z","end":"2026-07-31T21:31:13.39029Z","steps":["trace[29997372] 'range keys from in-memory index tree' (duration: 294.932887ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:31:13.566334Z","caller":"traceutil/trace.go:171","msg":"trace[2018103611] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"394.393247ms","start":"2026-07-31T21:31:13.171921Z","end":"2026-07-31T21:31:13.566314Z","steps":["trace[2018103611] 'read index received' (duration: 393.789181ms)","trace[2018103611] 'applied index is now lower than readState.Index' (duration: 603.176µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:31:13.566587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.653443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-dx2sz\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-31T21:31:13.566445Z","caller":"traceutil/trace.go:171","msg":"trace[1148675184] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"462.410514ms","start":"2026-07-31T21:31:13.103998Z","end":"2026-07-31T21:31:13.566408Z","steps":["trace[1148675184] 'process raft request' (duration: 461.87744ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:31:13.566622Z","caller":"traceutil/trace.go:171","msg":"trace[1518709835] range","detail":"{range_begin:/registry/pods/kube-system/cilium-dx2sz; range_end:; response_count:1; response_revision:592; }","duration":"394.737295ms","start":"2026-07-31T21:31:13.171875Z","end":"2026-07-31T21:31:13.566613Z","steps":["trace[1518709835] 'agreement among raft nodes before linearized reading' (duration: 394.5172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:31:13.566767Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:31:13.171858Z","time spent":"394.899899ms","remote":"127.0.0.1:59556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-dx2sz\" "} {"level":"warn","ts":"2026-07-31T21:31:13.566951Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:31:13.103965Z","time spent":"462.773953ms","remote":"127.0.0.1:59466","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:31:13.567633Z","caller":"traceutil/trace.go:171","msg":"trace[398598181] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"264.868482ms","start":"2026-07-31T21:31:13.302713Z","end":"2026-07-31T21:31:13.567581Z","steps":["trace[398598181] 'process raft request' (duration: 264.579804ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:31:29.537392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.162309ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-31T21:31:29.537528Z","caller":"traceutil/trace.go:171","msg":"trace[1629513891] linearizableReadLoop","detail":"{readStateIndex:781; appliedIndex:780; }","duration":"612.05717ms","start":"2026-07-31T21:31:28.925449Z","end":"2026-07-31T21:31:29.537506Z","steps":["trace[1629513891] 'read index received' (duration: 326.82315ms)","trace[1629513891] 'applied index is now lower than readState.Index' (duration: 285.23317ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:31:29.537626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"612.173403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:31:29.537702Z","caller":"traceutil/trace.go:171","msg":"trace[743778974] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:753; }","duration":"612.288606ms","start":"2026-07-31T21:31:28.925398Z","end":"2026-07-31T21:31:29.537687Z","steps":["trace[743778974] 'agreement among raft nodes before linearized reading' (duration: 612.174623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:31:29.537711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"572.075152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-31T21:31:29.537734Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:31:28.92538Z","time spent":"612.348767ms","remote":"127.0.0.1:59380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-31T21:31:29.537739Z","caller":"traceutil/trace.go:171","msg":"trace[648992939] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:753; }","duration":"572.110204ms","start":"2026-07-31T21:31:28.965617Z","end":"2026-07-31T21:31:29.537727Z","steps":["trace[648992939] 'agreement among raft nodes before linearized reading' (duration: 572.058403ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:31:29.537662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"575.711218ms","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-07-31T21:31:29.53777Z","caller":"traceutil/trace.go:171","msg":"trace[1606564551] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:753; }","duration":"575.864972ms","start":"2026-07-31T21:31:28.961895Z","end":"2026-07-31T21:31:29.53776Z","steps":["trace[1606564551] 'agreement among raft nodes before linearized reading' (duration: 575.686767ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:31:29.537763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:31:28.965608Z","time spent":"572.149365ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-31T21:31:29.537793Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:31:28.961872Z","time spent":"575.915613ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-31T21:31:29.537663Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.974903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jnzzx\" ","response":"range_response_count:1 size:3965"} {"level":"warn","ts":"2026-07-31T21:31:29.537816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.834045ms","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-07-31T21:31:29.537888Z","caller":"traceutil/trace.go:171","msg":"trace[1926389267] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:753; }","duration":"560.966078ms","start":"2026-07-31T21:31:28.976903Z","end":"2026-07-31T21:31:29.53787Z","steps":["trace[1926389267] 'agreement among raft nodes before linearized reading' (duration: 560.735413ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:31:29.537885Z","caller":"traceutil/trace.go:171","msg":"trace[583138099] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-gateway-helm-certgen-jnzzx; range_end:; response_count:1; response_revision:753; }","duration":"271.216299ms","start":"2026-07-31T21:31:29.266639Z","end":"2026-07-31T21:31:29.537856Z","steps":["trace[583138099] 'agreement among raft nodes before linearized reading' (duration: 270.956583ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:31:29.537931Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:31:28.976883Z","time spent":"561.039021ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-07-31T21:31:56.963488Z","caller":"traceutil/trace.go:171","msg":"trace[2094817673] transaction","detail":"{read_only:false; response_revision:1148; number_of_response:1; }","duration":"125.759702ms","start":"2026-07-31T21:31:56.837704Z","end":"2026-07-31T21:31:56.963463Z","steps":["trace[2094817673] 'process raft request' (duration: 125.613058ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:32:42.37267Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871125491145858,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-31T21:32:42.521144Z","caller":"traceutil/trace.go:171","msg":"trace[1952836399] linearizableReadLoop","detail":"{readStateIndex:1889; appliedIndex:1888; }","duration":"648.984775ms","start":"2026-07-31T21:32:41.872137Z","end":"2026-07-31T21:32:42.521122Z","steps":["trace[1952836399] 'read index received' (duration: 637.04628ms)","trace[1952836399] 'applied index is now lower than readState.Index' (duration: 11.937145ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:32:42.521173Z","caller":"traceutil/trace.go:171","msg":"trace[208962301] transaction","detail":"{read_only:false; response_revision:1816; number_of_response:1; }","duration":"650.624309ms","start":"2026-07-31T21:32:41.870523Z","end":"2026-07-31T21:32:42.521147Z","steps":["trace[208962301] 'process raft request' (duration: 638.631472ms)","trace[208962301] 'compare' (duration: 11.602946ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:32:42.521325Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:32:41.870498Z","time spent":"650.728921ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:32:42.521436Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"645.900223ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-31T21:32:42.521496Z","caller":"traceutil/trace.go:171","msg":"trace[2028553388] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1816; }","duration":"646.000326ms","start":"2026-07-31T21:32:41.875479Z","end":"2026-07-31T21:32:42.521479Z","steps":["trace[2028553388] 'agreement among raft nodes before linearized reading' (duration: 645.834962ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:32:42.521547Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:32:41.875463Z","time spent":"646.075978ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-31T21:32:42.521583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.155478ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-31T21:32:42.521453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"649.307823ms","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-07-31T21:32:42.521661Z","caller":"traceutil/trace.go:171","msg":"trace[1376664663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1816; }","duration":"649.54046ms","start":"2026-07-31T21:32:41.87211Z","end":"2026-07-31T21:32:42.521651Z","steps":["trace[1376664663] 'agreement among raft nodes before linearized reading' (duration: 649.129789ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:32:42.521661Z","caller":"traceutil/trace.go:171","msg":"trace[399563815] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1816; }","duration":"133.19519ms","start":"2026-07-31T21:32:42.388404Z","end":"2026-07-31T21:32:42.521599Z","steps":["trace[399563815] 'agreement among raft nodes before linearized reading' (duration: 133.072496ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:32:42.52192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:32:41.872095Z","time spent":"649.813997ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T21:32:42.521997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.026393ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c1476d2-7fff-4496-9cc6-74d13ca2f338\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-31T21:32:42.522083Z","caller":"traceutil/trace.go:171","msg":"trace[1872575921] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2c1476d2-7fff-4496-9cc6-74d13ca2f338; range_end:; response_count:1; response_revision:1816; }","duration":"418.151256ms","start":"2026-07-31T21:32:42.103911Z","end":"2026-07-31T21:32:42.522062Z","steps":["trace[1872575921] 'agreement among raft nodes before linearized reading' (duration: 417.90044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:32:42.522081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.421614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T21:32:42.522154Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:32:42.10389Z","time spent":"418.25335ms","remote":"127.0.0.1:59556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c1476d2-7fff-4496-9cc6-74d13ca2f338\" "} {"level":"info","ts":"2026-07-31T21:32:42.522166Z","caller":"traceutil/trace.go:171","msg":"trace[1069275035] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1816; }","duration":"608.558297ms","start":"2026-07-31T21:32:41.913589Z","end":"2026-07-31T21:32:42.522147Z","steps":["trace[1069275035] 'agreement among raft nodes before linearized reading' (duration: 608.055984ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:32:42.522215Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:32:41.913577Z","time spent":"608.623209ms","remote":"127.0.0.1:59380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T21:32:42.52235Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.080491ms","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-07-31T21:32:42.5225Z","caller":"traceutil/trace.go:171","msg":"trace[921071305] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1816; }","duration":"134.535144ms","start":"2026-07-31T21:32:42.387939Z","end":"2026-07-31T21:32:42.522474Z","steps":["trace[921071305] 'agreement among raft nodes before linearized reading' (duration: 133.469535ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:32:42.981509Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.653438ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T21:32:42.98161Z","caller":"traceutil/trace.go:171","msg":"trace[878949035] transaction","detail":"{read_only:false; response_revision:1821; number_of_response:1; }","duration":"380.622485ms","start":"2026-07-31T21:32:42.60097Z","end":"2026-07-31T21:32:42.981592Z","steps":["trace[878949035] 'process raft request' (duration: 64.805382ms)","trace[878949035] 'compare' (duration: 315.525085ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:32:42.981667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:32:42.600945Z","time spent":"380.695438ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:32:42.999251Z","caller":"traceutil/trace.go:171","msg":"trace[332561786] linearizableReadLoop","detail":"{readStateIndex:1895; appliedIndex:1893; }","duration":"370.908009ms","start":"2026-07-31T21:32:42.628313Z","end":"2026-07-31T21:32:42.999221Z","steps":["trace[332561786] 'read index received' (duration: 37.468881ms)","trace[332561786] 'applied index is now lower than readState.Index' (duration: 333.437798ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:32:42.999382Z","caller":"traceutil/trace.go:171","msg":"trace[965444944] transaction","detail":"{read_only:false; response_revision:1822; number_of_response:1; }","duration":"395.444997ms","start":"2026-07-31T21:32:42.603895Z","end":"2026-07-31T21:32:42.99934Z","steps":["trace[965444944] 'process raft request' (duration: 395.191191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:32:42.999519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.186826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c1476d2-7fff-4496-9cc6-74d13ca2f338\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-31T21:32:42.999593Z","caller":"traceutil/trace.go:171","msg":"trace[1144106122] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-2c1476d2-7fff-4496-9cc6-74d13ca2f338; range_end:; response_count:1; response_revision:1822; }","duration":"371.301729ms","start":"2026-07-31T21:32:42.628274Z","end":"2026-07-31T21:32:42.999576Z","steps":["trace[1144106122] 'agreement among raft nodes before linearized reading' (duration: 371.082443ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:32:42.999618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:32:42.603874Z","time spent":"395.595131ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:32:42.999678Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:32:42.628253Z","time spent":"371.413782ms","remote":"127.0.0.1:59556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3649,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-2c1476d2-7fff-4496-9cc6-74d13ca2f338\" "} {"level":"warn","ts":"2026-07-31T21:32:43.000028Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.680249ms","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-07-31T21:32:43.000086Z","caller":"traceutil/trace.go:171","msg":"trace[322684578] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1823; }","duration":"173.805071ms","start":"2026-07-31T21:32:42.826265Z","end":"2026-07-31T21:32:43.00007Z","steps":["trace[322684578] 'agreement among raft nodes before linearized reading' (duration: 173.629057ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:32:54.888548Z","caller":"traceutil/trace.go:171","msg":"trace[1699124648] transaction","detail":"{read_only:false; response_revision:1917; number_of_response:1; }","duration":"307.744877ms","start":"2026-07-31T21:32:54.580785Z","end":"2026-07-31T21:32:54.88853Z","steps":["trace[1699124648] 'process raft request' (duration: 307.486911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:32:54.888695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:32:54.580759Z","time spent":"307.85072ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:32:54.888732Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.366141ms","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-07-31T21:32:54.888786Z","caller":"traceutil/trace.go:171","msg":"trace[111314618] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1917; }","duration":"290.450954ms","start":"2026-07-31T21:32:54.598324Z","end":"2026-07-31T21:32:54.888775Z","steps":["trace[111314618] 'agreement among raft nodes before linearized reading' (duration: 290.270738ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:32:54.888456Z","caller":"traceutil/trace.go:171","msg":"trace[1940742072] linearizableReadLoop","detail":"{readStateIndex:1993; appliedIndex:1992; }","duration":"290.082603ms","start":"2026-07-31T21:32:54.598346Z","end":"2026-07-31T21:32:54.888429Z","steps":["trace[1940742072] 'read index received' (duration: 289.846277ms)","trace[1940742072] 'applied index is now lower than readState.Index' (duration: 235.026µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:32:54.890317Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.83443ms","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-07-31T21:32:54.890368Z","caller":"traceutil/trace.go:171","msg":"trace[408327952] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1917; }","duration":"196.914702ms","start":"2026-07-31T21:32:54.693442Z","end":"2026-07-31T21:32:54.890356Z","steps":["trace[408327952] 'agreement among raft nodes before linearized reading' (duration: 196.744278ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:32:54.890352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.733137ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-31T21:32:54.890442Z","caller":"traceutil/trace.go:171","msg":"trace[145547512] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1917; }","duration":"196.827509ms","start":"2026-07-31T21:32:54.693589Z","end":"2026-07-31T21:32:54.890417Z","steps":["trace[145547512] 'agreement among raft nodes before linearized reading' (duration: 196.609604ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:32:54.890792Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.383768ms","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-07-31T21:32:54.890836Z","caller":"traceutil/trace.go:171","msg":"trace[1086864860] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1917; }","duration":"204.459651ms","start":"2026-07-31T21:32:54.686365Z","end":"2026-07-31T21:32:54.890824Z","steps":["trace[1086864860] 'agreement among raft nodes before linearized reading' (duration: 203.732212ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:33:27.271231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.700674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T21:33:27.271344Z","caller":"traceutil/trace.go:171","msg":"trace[1534250648] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:2111; }","duration":"126.879588ms","start":"2026-07-31T21:33:27.144441Z","end":"2026-07-31T21:33:27.271321Z","steps":["trace[1534250648] 'count revisions from in-memory index tree' (duration: 126.575141ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:33:27.272463Z","caller":"traceutil/trace.go:171","msg":"trace[1912849534] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"115.024168ms","start":"2026-07-31T21:33:27.157403Z","end":"2026-07-31T21:33:27.272427Z","steps":["trace[1912849534] 'process raft request' (duration: 114.803591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:33:33.388304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.356599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T21:33:33.388425Z","caller":"traceutil/trace.go:171","msg":"trace[2121994957] linearizableReadLoop","detail":"{readStateIndex:2221; appliedIndex:2219; }","duration":"304.688229ms","start":"2026-07-31T21:33:33.083714Z","end":"2026-07-31T21:33:33.388403Z","steps":["trace[2121994957] 'read index received' (duration: 79.828681ms)","trace[2121994957] 'applied index is now lower than readState.Index' (duration: 224.857518ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:33:33.388547Z","caller":"traceutil/trace.go:171","msg":"trace[1446241467] transaction","detail":"{read_only:false; response_revision:2138; number_of_response:1; }","duration":"314.656191ms","start":"2026-07-31T21:33:33.073862Z","end":"2026-07-31T21:33:33.388518Z","steps":["trace[1446241467] 'process raft request' (duration: 158.952243ms)","trace[1446241467] 'compare' (duration: 155.219685ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:33:33.388707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:33:33.073845Z","time spent":"314.784134ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:33:33.388726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"304.999887ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-31T21:33:33.388798Z","caller":"traceutil/trace.go:171","msg":"trace[1065373688] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2138; }","duration":"305.11789ms","start":"2026-07-31T21:33:33.083662Z","end":"2026-07-31T21:33:33.38878Z","steps":["trace[1065373688] 'agreement among raft nodes before linearized reading' (duration: 304.877474ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:33:33.388851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:33:33.083646Z","time spent":"305.195732ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":513,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-31T21:33:33.65924Z","caller":"traceutil/trace.go:171","msg":"trace[1458896511] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"362.966661ms","start":"2026-07-31T21:33:33.296245Z","end":"2026-07-31T21:33:33.659212Z","steps":["trace[1458896511] 'process raft request' (duration: 362.779506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:33:33.659418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:33:33.296215Z","time spent":"363.113736ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:33:33.996678Z","caller":"traceutil/trace.go:171","msg":"trace[21517871] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"699.445096ms","start":"2026-07-31T21:33:33.297195Z","end":"2026-07-31T21:33:33.99664Z","steps":["trace[21517871] 'process raft request' (duration: 640.555766ms)","trace[21517871] 'compare' (duration: 58.381757ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:33:33.996959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:33:33.297169Z","time spent":"699.668643ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:33:33.997254Z","caller":"traceutil/trace.go:171","msg":"trace[887202544] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"699.891227ms","start":"2026-07-31T21:33:33.297297Z","end":"2026-07-31T21:33:33.997188Z","steps":["trace[887202544] 'process raft request' (duration: 699.209129ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:33:33.997483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:33:33.297263Z","time spent":"700.101044ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:33:33.998162Z","caller":"traceutil/trace.go:171","msg":"trace[913736821] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"632.968338ms","start":"2026-07-31T21:33:33.365165Z","end":"2026-07-31T21:33:33.998134Z","steps":["trace[913736821] 'process raft request' (duration: 631.753196ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:33:33.998232Z","caller":"traceutil/trace.go:171","msg":"trace[731270679] linearizableReadLoop","detail":"{readStateIndex:2225; appliedIndex:2221; }","duration":"609.685644ms","start":"2026-07-31T21:33:33.388518Z","end":"2026-07-31T21:33:33.998204Z","steps":["trace[731270679] 'read index received' (duration: 270.528598ms)","trace[731270679] 'applied index is now lower than readState.Index' (duration: 339.155796ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:33:33.998309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:33:33.365136Z","time spent":"633.09627ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:33:33.998396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"863.487023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T21:33:33.998429Z","caller":"traceutil/trace.go:171","msg":"trace[26643273] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:2144; }","duration":"863.553294ms","start":"2026-07-31T21:33:33.134867Z","end":"2026-07-31T21:33:33.99842Z","steps":["trace[26643273] 'agreement among raft nodes before linearized reading' (duration: 863.457722ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:33:33.998442Z","caller":"traceutil/trace.go:171","msg":"trace[323234706] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"605.954896ms","start":"2026-07-31T21:33:33.392477Z","end":"2026-07-31T21:33:33.998432Z","steps":["trace[323234706] 'process raft request' (duration: 605.520155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:33:33.998463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:33:33.134854Z","time spent":"863.598077ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":24,"response size":29,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"warn","ts":"2026-07-31T21:33:33.998486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:33:33.392408Z","time spent":"606.053249ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:33:33.998736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"895.963807ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-31T21:33:33.99878Z","caller":"traceutil/trace.go:171","msg":"trace[1843160773] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:2144; }","duration":"896.051109ms","start":"2026-07-31T21:33:33.102718Z","end":"2026-07-31T21:33:33.998769Z","steps":["trace[1843160773] 'agreement among raft nodes before linearized reading' (duration: 895.911795ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:33:33.998808Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:33:33.102698Z","time spent":"896.104502ms","remote":"127.0.0.1:59404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.229\" "} {"level":"warn","ts":"2026-07-31T21:33:33.998926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.556951ms","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-07-31T21:33:33.998955Z","caller":"traceutil/trace.go:171","msg":"trace[1141239268] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2144; }","duration":"910.603153ms","start":"2026-07-31T21:33:33.088345Z","end":"2026-07-31T21:33:33.998948Z","steps":["trace[1141239268] 'agreement among raft nodes before linearized reading' (duration: 910.46959ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:33:33.998974Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:33:33.088334Z","time spent":"910.634974ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T21:33:33.999003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.815268ms","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-07-31T21:33:33.999066Z","caller":"traceutil/trace.go:171","msg":"trace[1292304153] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2144; }","duration":"648.115986ms","start":"2026-07-31T21:33:33.350932Z","end":"2026-07-31T21:33:33.999048Z","steps":["trace[1292304153] 'agreement among raft nodes before linearized reading' (duration: 647.345566ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:33:33.999093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"910.780268ms","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-07-31T21:33:33.999115Z","caller":"traceutil/trace.go:171","msg":"trace[1110703547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2144; }","duration":"910.857949ms","start":"2026-07-31T21:33:33.088251Z","end":"2026-07-31T21:33:33.999109Z","steps":["trace[1110703547] 'agreement among raft nodes before linearized reading' (duration: 910.803338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:33:33.999117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:33:33.350909Z","time spent":"648.192477ms","remote":"127.0.0.1:40796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5434,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-07-31T21:33:33.999134Z","caller":"traceutil/trace.go:171","msg":"trace[929212774] transaction","detail":"{read_only:false; response_revision:2144; number_of_response:1; }","duration":"375.104371ms","start":"2026-07-31T21:33:33.623996Z","end":"2026-07-31T21:33:33.9991Z","steps":["trace[929212774] 'process raft request' (duration: 374.151596ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:33:33.999133Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:33:33.088234Z","time spent":"910.895141ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-31T21:33:33.999298Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:33:33.623966Z","time spent":"375.252095ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:34:27.849033Z","caller":"traceutil/trace.go:171","msg":"trace[725835658] transaction","detail":"{read_only:false; response_revision:2603; number_of_response:1; }","duration":"214.354174ms","start":"2026-07-31T21:34:27.634653Z","end":"2026-07-31T21:34:27.849007Z","steps":["trace[725835658] 'process raft request' (duration: 214.17875ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:34:27.862879Z","caller":"traceutil/trace.go:171","msg":"trace[1313148614] transaction","detail":"{read_only:false; response_revision:2604; number_of_response:1; }","duration":"209.444197ms","start":"2026-07-31T21:34:27.653408Z","end":"2026-07-31T21:34:27.862852Z","steps":["trace[1313148614] 'process raft request' (duration: 208.885092ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:35:02.215085Z","caller":"traceutil/trace.go:171","msg":"trace[831927141] linearizableReadLoop","detail":"{readStateIndex:2920; appliedIndex:2919; }","duration":"205.595027ms","start":"2026-07-31T21:35:02.009471Z","end":"2026-07-31T21:35:02.215066Z","steps":["trace[831927141] 'read index received' (duration: 205.420092ms)","trace[831927141] 'applied index is now lower than readState.Index' (duration: 174.355µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:35:02.215177Z","caller":"traceutil/trace.go:171","msg":"trace[1294897787] transaction","detail":"{read_only:false; response_revision:2810; number_of_response:1; }","duration":"214.512793ms","start":"2026-07-31T21:35:02.000625Z","end":"2026-07-31T21:35:02.215138Z","steps":["trace[1294897787] 'process raft request' (duration: 214.314208ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:35:02.215251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.768721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-31T21:35:02.215277Z","caller":"traceutil/trace.go:171","msg":"trace[984106480] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2810; }","duration":"205.812662ms","start":"2026-07-31T21:35:02.009456Z","end":"2026-07-31T21:35:02.215269Z","steps":["trace[984106480] 'agreement among raft nodes before linearized reading' (duration: 205.699439ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:35:02.524994Z","caller":"traceutil/trace.go:171","msg":"trace[1756442918] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"205.075742ms","start":"2026-07-31T21:35:02.319889Z","end":"2026-07-31T21:35:02.524964Z","steps":["trace[1756442918] 'process raft request' (duration: 199.741212ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:35:02.526019Z","caller":"traceutil/trace.go:171","msg":"trace[587806730] transaction","detail":"{read_only:false; response_revision:2813; number_of_response:1; }","duration":"184.795126ms","start":"2026-07-31T21:35:02.341193Z","end":"2026-07-31T21:35:02.525988Z","steps":["trace[587806730] 'process raft request' (duration: 184.662483ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:35:02.526435Z","caller":"traceutil/trace.go:171","msg":"trace[309900157] linearizableReadLoop","detail":"{readStateIndex:2924; appliedIndex:2921; }","duration":"112.275228ms","start":"2026-07-31T21:35:02.414065Z","end":"2026-07-31T21:35:02.52634Z","steps":["trace[309900157] 'read index received' (duration: 105.619643ms)","trace[309900157] 'applied index is now lower than readState.Index' (duration: 6.616924ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:35:02.526571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.501135ms","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-07-31T21:35:02.526605Z","caller":"traceutil/trace.go:171","msg":"trace[463395692] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2814; }","duration":"112.585667ms","start":"2026-07-31T21:35:02.414011Z","end":"2026-07-31T21:35:02.526597Z","steps":["trace[463395692] 'agreement among raft nodes before linearized reading' (duration: 112.464884ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:35:02.526474Z","caller":"traceutil/trace.go:171","msg":"trace[1969348914] transaction","detail":"{read_only:false; response_revision:2814; number_of_response:1; }","duration":"182.964838ms","start":"2026-07-31T21:35:02.343462Z","end":"2026-07-31T21:35:02.526427Z","steps":["trace[1969348914] 'process raft request' (duration: 182.485675ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:36:55.184319Z","caller":"traceutil/trace.go:171","msg":"trace[56430603] transaction","detail":"{read_only:false; response_revision:3537; number_of_response:1; }","duration":"121.727872ms","start":"2026-07-31T21:36:55.062555Z","end":"2026-07-31T21:36:55.184283Z","steps":["trace[56430603] 'process raft request' (duration: 118.072074ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:36:55.184304Z","caller":"traceutil/trace.go:171","msg":"trace[139086971] linearizableReadLoop","detail":"{readStateIndex:3677; appliedIndex:3676; }","duration":"120.615803ms","start":"2026-07-31T21:36:55.063662Z","end":"2026-07-31T21:36:55.184278Z","steps":["trace[139086971] 'read index received' (duration: 117.052367ms)","trace[139086971] 'applied index is now lower than readState.Index' (duration: 3.562336ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:36:55.18449Z","caller":"traceutil/trace.go:171","msg":"trace[166067087] transaction","detail":"{read_only:false; response_revision:3538; number_of_response:1; }","duration":"118.795174ms","start":"2026-07-31T21:36:55.065664Z","end":"2026-07-31T21:36:55.184459Z","steps":["trace[166067087] 'process raft request' (duration: 118.557757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:36:55.184624Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.944091ms","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-07-31T21:36:55.18473Z","caller":"traceutil/trace.go:171","msg":"trace[1780203065] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3538; }","duration":"121.097244ms","start":"2026-07-31T21:36:55.063607Z","end":"2026-07-31T21:36:55.184704Z","steps":["trace[1780203065] 'agreement among raft nodes before linearized reading' (duration: 120.760286ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:37:28.890949Z","caller":"traceutil/trace.go:171","msg":"trace[805772296] transaction","detail":"{read_only:false; response_revision:3787; number_of_response:1; }","duration":"165.869501ms","start":"2026-07-31T21:37:28.725057Z","end":"2026-07-31T21:37:28.890926Z","steps":["trace[805772296] 'process raft request' (duration: 165.717997ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:38:46.627516Z","caller":"traceutil/trace.go:171","msg":"trace[1403312876] transaction","detail":"{read_only:false; response_revision:4670; number_of_response:1; }","duration":"119.328707ms","start":"2026-07-31T21:38:46.508161Z","end":"2026-07-31T21:38:46.62749Z","steps":["trace[1403312876] 'process raft request' (duration: 119.257274ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:38:46.627508Z","caller":"traceutil/trace.go:171","msg":"trace[42618758] linearizableReadLoop","detail":"{readStateIndex:4849; appliedIndex:4848; }","duration":"132.895016ms","start":"2026-07-31T21:38:46.494574Z","end":"2026-07-31T21:38:46.627469Z","steps":["trace[42618758] 'read index received' (duration: 118.769161ms)","trace[42618758] 'applied index is now lower than readState.Index' (duration: 14.121935ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:38:46.627602Z","caller":"traceutil/trace.go:171","msg":"trace[1067252848] transaction","detail":"{read_only:false; response_revision:4669; number_of_response:1; }","duration":"120.046486ms","start":"2026-07-31T21:38:46.507513Z","end":"2026-07-31T21:38:46.62756Z","steps":["trace[1067252848] 'process raft request' (duration: 119.828449ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:38:46.627683Z","caller":"traceutil/trace.go:171","msg":"trace[886333434] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"153.885704ms","start":"2026-07-31T21:38:46.47376Z","end":"2026-07-31T21:38:46.627646Z","steps":["trace[886333434] 'process raft request' (duration: 139.405209ms)","trace[886333434] 'compare' (duration: 14.014072ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:38:46.627883Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.272405ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-07-31T21:38:46.627956Z","caller":"traceutil/trace.go:171","msg":"trace[1267992539] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4670; }","duration":"133.43726ms","start":"2026-07-31T21:38:46.494502Z","end":"2026-07-31T21:38:46.627939Z","steps":["trace[1267992539] 'agreement among raft nodes before linearized reading' (duration: 133.163973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:38:46.627988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.592407ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/general\" ","response":"range_response_count:1 size:1124"} {"level":"info","ts":"2026-07-31T21:38:46.628087Z","caller":"traceutil/trace.go:171","msg":"trace[229019521] range","detail":"{range_begin:/registry/storageclasses/general; range_end:; response_count:1; response_revision:4670; }","duration":"132.764842ms","start":"2026-07-31T21:38:46.495303Z","end":"2026-07-31T21:38:46.628068Z","steps":["trace[229019521] 'agreement among raft nodes before linearized reading' (duration: 132.449414ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:38:52.084759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.291474ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-31T21:38:52.084836Z","caller":"traceutil/trace.go:171","msg":"trace[1516738857] linearizableReadLoop","detail":"{readStateIndex:5017; appliedIndex:5016; }","duration":"314.520086ms","start":"2026-07-31T21:38:51.770304Z","end":"2026-07-31T21:38:52.084824Z","steps":["trace[1516738857] 'read index received' (duration: 36.089859ms)","trace[1516738857] 'applied index is now lower than readState.Index' (duration: 278.428867ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:38:52.084869Z","caller":"traceutil/trace.go:171","msg":"trace[1857371644] transaction","detail":"{read_only:false; response_revision:4830; number_of_response:1; }","duration":"318.10023ms","start":"2026-07-31T21:38:51.766763Z","end":"2026-07-31T21:38:52.084863Z","steps":["trace[1857371644] 'process raft request' (duration: 39.654592ms)","trace[1857371644] 'compare' (duration: 278.032647ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:38:52.084895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:38:51.766735Z","time spent":"318.146652ms","remote":"127.0.0.1:59442","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":816,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-31T21:38:52.085114Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.808954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:11454"} {"level":"info","ts":"2026-07-31T21:38:52.085136Z","caller":"traceutil/trace.go:171","msg":"trace[1185952618] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4830; }","duration":"314.871465ms","start":"2026-07-31T21:38:51.770258Z","end":"2026-07-31T21:38:52.08513Z","steps":["trace[1185952618] 'agreement among raft nodes before linearized reading' (duration: 314.742042ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:38:52.085152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:38:51.770239Z","time spent":"314.908006ms","remote":"127.0.0.1:59556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":77,"response count":1,"response size":11476,"request content":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"warn","ts":"2026-07-31T21:38:52.222205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.204624ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:38:52.222313Z","caller":"traceutil/trace.go:171","msg":"trace[946110447] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4831; }","duration":"309.346118ms","start":"2026-07-31T21:38:51.912942Z","end":"2026-07-31T21:38:52.222288Z","steps":["trace[946110447] 'agreement among raft nodes before linearized reading' (duration: 309.113313ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:38:52.222319Z","caller":"traceutil/trace.go:171","msg":"trace[1008297108] transaction","detail":"{read_only:false; response_revision:4831; number_of_response:1; }","duration":"145.903041ms","start":"2026-07-31T21:38:52.076383Z","end":"2026-07-31T21:38:52.222286Z","steps":["trace[1008297108] 'process raft request' (duration: 137.951191ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:38:52.22237Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:38:51.912931Z","time spent":"309.42642ms","remote":"127.0.0.1:59380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T21:38:52.222437Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"353.672404ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-07-31T21:38:52.222497Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"378.741719ms","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-07-31T21:38:52.222583Z","caller":"traceutil/trace.go:171","msg":"trace[1679152054] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4831; }","duration":"378.853862ms","start":"2026-07-31T21:38:51.843698Z","end":"2026-07-31T21:38:52.222552Z","steps":["trace[1679152054] 'agreement among raft nodes before linearized reading' (duration: 378.638877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:38:52.222639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:38:51.843683Z","time spent":"378.944195ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-31T21:38:52.222626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.692733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-31T21:38:52.222737Z","caller":"traceutil/trace.go:171","msg":"trace[1559534608] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4831; }","duration":"372.061033ms","start":"2026-07-31T21:38:51.850654Z","end":"2026-07-31T21:38:52.222715Z","steps":["trace[1559534608] 'agreement among raft nodes before linearized reading' (duration: 371.535219ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:38:52.22252Z","caller":"traceutil/trace.go:171","msg":"trace[2062500277] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4831; }","duration":"353.787417ms","start":"2026-07-31T21:38:51.868713Z","end":"2026-07-31T21:38:52.2225Z","steps":["trace[2062500277] 'agreement among raft nodes before linearized reading' (duration: 353.51335ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:38:52.22277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.974906ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-07-31T21:38:52.222794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:38:51.850644Z","time spent":"372.134375ms","remote":"127.0.0.1:59532","response type":"/etcdserverpb.KV/Range","request count":0,"request size":147,"response count":1,"response size":1957,"request content":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" "} {"level":"info","ts":"2026-07-31T21:38:52.222813Z","caller":"traceutil/trace.go:171","msg":"trace[1617416953] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4831; }","duration":"402.050667ms","start":"2026-07-31T21:38:51.820747Z","end":"2026-07-31T21:38:52.222798Z","steps":["trace[1617416953] 'agreement among raft nodes before linearized reading' (duration: 401.913264ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:38:52.222814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:38:51.868701Z","time spent":"354.094855ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T21:38:52.222836Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:38:51.820731Z","time spent":"402.1002ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-31T21:38:52.222068Z","caller":"traceutil/trace.go:171","msg":"trace[1681211810] linearizableReadLoop","detail":"{readStateIndex:5018; appliedIndex:5017; }","duration":"137.1903ms","start":"2026-07-31T21:38:52.084849Z","end":"2026-07-31T21:38:52.22204Z","steps":["trace[1681211810] 'read index received' (duration: 129.446455ms)","trace[1681211810] 'applied index is now lower than readState.Index' (duration: 7.742865ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:38:52.23021Z","caller":"traceutil/trace.go:171","msg":"trace[1341256820] transaction","detail":"{read_only:false; response_revision:4832; number_of_response:1; }","duration":"134.865058ms","start":"2026-07-31T21:38:52.095332Z","end":"2026-07-31T21:38:52.230197Z","steps":["trace[1341256820] 'process raft request' (duration: 134.704804ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:38:52.230405Z","caller":"traceutil/trace.go:171","msg":"trace[203777808] transaction","detail":"{read_only:false; response_revision:4833; number_of_response:1; }","duration":"107.513443ms","start":"2026-07-31T21:38:52.122881Z","end":"2026-07-31T21:38:52.230395Z","steps":["trace[203777808] 'process raft request' (duration: 107.269646ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:39:09.389639Z","caller":"traceutil/trace.go:171","msg":"trace[965359967] transaction","detail":"{read_only:false; response_revision:5242; number_of_response:1; }","duration":"460.893609ms","start":"2026-07-31T21:39:08.928716Z","end":"2026-07-31T21:39:09.38961Z","steps":["trace[965359967] 'process raft request' (duration: 460.716574ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:39:09.389851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:39:08.928704Z","time spent":"461.033483ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:39:09.389731Z","caller":"traceutil/trace.go:171","msg":"trace[795360597] linearizableReadLoop","detail":"{readStateIndex:5442; appliedIndex:5442; }","duration":"458.588528ms","start":"2026-07-31T21:39:08.931106Z","end":"2026-07-31T21:39:09.389694Z","steps":["trace[795360597] 'read index received' (duration: 458.567917ms)","trace[795360597] 'applied index is now lower than readState.Index' (duration: 13.791µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:39:09.390251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.112331ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:8 size:71023"} {"level":"info","ts":"2026-07-31T21:39:09.390298Z","caller":"traceutil/trace.go:171","msg":"trace[1919777423] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:8; response_revision:5242; }","duration":"459.200123ms","start":"2026-07-31T21:39:08.931087Z","end":"2026-07-31T21:39:09.390287Z","steps":["trace[1919777423] 'agreement among raft nodes before linearized reading' (duration: 458.872904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:39:09.39034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:39:08.931077Z","time spent":"459.253806ms","remote":"127.0.0.1:59556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":8,"response size":71045,"request content":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" "} {"level":"info","ts":"2026-07-31T21:39:09.480933Z","caller":"traceutil/trace.go:171","msg":"trace[403970296] transaction","detail":"{read_only:false; response_revision:5243; number_of_response:1; }","duration":"232.330495ms","start":"2026-07-31T21:39:09.248576Z","end":"2026-07-31T21:39:09.480907Z","steps":["trace[403970296] 'process raft request' (duration: 227.718362ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:39:09.481104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.267734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-kgn2g.18c77da687a02374\" ","response":"range_response_count:1 size:906"} {"level":"info","ts":"2026-07-31T21:39:09.481199Z","caller":"traceutil/trace.go:171","msg":"trace[1683391646] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-kgn2g.18c77da687a02374; range_end:; response_count:1; response_revision:5246; }","duration":"416.44039ms","start":"2026-07-31T21:39:09.064739Z","end":"2026-07-31T21:39:09.481179Z","steps":["trace[1683391646] 'agreement among raft nodes before linearized reading' (duration: 416.103301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:39:09.481225Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"310.308163ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T21:39:09.481323Z","caller":"traceutil/trace.go:171","msg":"trace[582310591] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:5246; }","duration":"310.452087ms","start":"2026-07-31T21:39:09.170846Z","end":"2026-07-31T21:39:09.481299Z","steps":["trace[582310591] 'agreement among raft nodes before linearized reading' (duration: 310.274522ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:39:09.481251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:39:09.064707Z","time spent":"416.534262ms","remote":"127.0.0.1:59442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":99,"response count":1,"response size":928,"request content":"key:\"/registry/events/monitoring/kube-prometheus-stack-prometheus-node-exporter-kgn2g.18c77da687a02374\" "} {"level":"warn","ts":"2026-07-31T21:39:09.481427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:39:09.170822Z","time spent":"310.584401ms","remote":"127.0.0.1:59556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":43,"response size":29,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-07-31T21:39:16.603105Z","caller":"traceutil/trace.go:171","msg":"trace[479155926] transaction","detail":"{read_only:false; response_revision:5424; number_of_response:1; }","duration":"143.98687ms","start":"2026-07-31T21:39:16.459092Z","end":"2026-07-31T21:39:16.603079Z","steps":["trace[479155926] 'process raft request' (duration: 143.796305ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:39:16.603076Z","caller":"traceutil/trace.go:171","msg":"trace[1596965706] linearizableReadLoop","detail":"{readStateIndex:5627; appliedIndex:5626; }","duration":"143.399165ms","start":"2026-07-31T21:39:16.459651Z","end":"2026-07-31T21:39:16.60305Z","steps":["trace[1596965706] 'read index received' (duration: 143.316702ms)","trace[1596965706] 'applied index is now lower than readState.Index' (duration: 81.263µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:39:16.603423Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.752093ms","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-07-31T21:39:16.603609Z","caller":"traceutil/trace.go:171","msg":"trace[1143652415] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5424; }","duration":"143.997631ms","start":"2026-07-31T21:39:16.459597Z","end":"2026-07-31T21:39:16.603594Z","steps":["trace[1143652415] 'agreement among raft nodes before linearized reading' (duration: 143.540449ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:39:16.872596Z","caller":"traceutil/trace.go:171","msg":"trace[365386853] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"411.834166ms","start":"2026-07-31T21:39:16.460723Z","end":"2026-07-31T21:39:16.872557Z","steps":["trace[365386853] 'process raft request' (duration: 349.659646ms)","trace[365386853] 'compare' (duration: 61.550594ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:39:16.872787Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:39:16.460708Z","time spent":"411.983981ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:39:16.891303Z","caller":"traceutil/trace.go:171","msg":"trace[585935262] linearizableReadLoop","detail":"{readStateIndex:5629; appliedIndex:5627; }","duration":"131.831648ms","start":"2026-07-31T21:39:16.759447Z","end":"2026-07-31T21:39:16.891278Z","steps":["trace[585935262] 'read index received' (duration: 50.952662ms)","trace[585935262] 'applied index is now lower than readState.Index' (duration: 80.877796ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:39:16.891545Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.085045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:39:16.891657Z","caller":"traceutil/trace.go:171","msg":"trace[411633573] transaction","detail":"{read_only:false; response_revision:5426; number_of_response:1; }","duration":"284.55589ms","start":"2026-07-31T21:39:16.607087Z","end":"2026-07-31T21:39:16.891643Z","steps":["trace[411633573] 'process raft request' (duration: 284.057577ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:39:16.891733Z","caller":"traceutil/trace.go:171","msg":"trace[1485382279] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:5426; }","duration":"132.27209ms","start":"2026-07-31T21:39:16.75939Z","end":"2026-07-31T21:39:16.891662Z","steps":["trace[1485382279] 'agreement among raft nodes before linearized reading' (duration: 132.073985ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:39:16.891806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.677444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-95f57386-55c5-4d85-87ef-debff1536654\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-31T21:39:16.891886Z","caller":"traceutil/trace.go:171","msg":"trace[1635515773] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-95f57386-55c5-4d85-87ef-debff1536654; range_end:; response_count:1; response_revision:5426; }","duration":"102.778667ms","start":"2026-07-31T21:39:16.789088Z","end":"2026-07-31T21:39:16.891867Z","steps":["trace[1635515773] 'agreement among raft nodes before linearized reading' (duration: 102.562101ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:39:17.122655Z","caller":"traceutil/trace.go:171","msg":"trace[5499360] linearizableReadLoop","detail":"{readStateIndex:5630; appliedIndex:5629; }","duration":"209.814017ms","start":"2026-07-31T21:39:16.912808Z","end":"2026-07-31T21:39:17.122622Z","steps":["trace[5499360] 'read index received' (duration: 200.668405ms)","trace[5499360] 'applied index is now lower than readState.Index' (duration: 9.144182ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:39:17.122706Z","caller":"traceutil/trace.go:171","msg":"trace[515644806] transaction","detail":"{read_only:false; response_revision:5427; number_of_response:1; }","duration":"221.742024ms","start":"2026-07-31T21:39:16.900937Z","end":"2026-07-31T21:39:17.122679Z","steps":["trace[515644806] 'process raft request' (duration: 212.505629ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:39:17.122839Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"210.019603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:39:17.122947Z","caller":"traceutil/trace.go:171","msg":"trace[1704338558] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5427; }","duration":"210.199728ms","start":"2026-07-31T21:39:16.912721Z","end":"2026-07-31T21:39:17.122921Z","steps":["trace[1704338558] 'agreement among raft nodes before linearized reading' (duration: 210.006482ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:40:08.313763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.434429ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-31T21:40:30.087441Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2965} {"level":"info","ts":"2026-07-31T21:40:30.163282Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2965,"took":"74.145926ms","hash":2024246140,"current-db-size-bytes":57696256,"current-db-size":"58 MB","current-db-size-in-use-bytes":34172928,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-31T21:40:30.163351Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2024246140,"revision":2965,"compact-revision":-1} {"level":"info","ts":"2026-07-31T21:45:30.094027Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6076} {"level":"info","ts":"2026-07-31T21:45:30.188179Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6076,"took":"92.199839ms","hash":295857349,"current-db-size-bytes":57696256,"current-db-size":"58 MB","current-db-size-in-use-bytes":23863296,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-07-31T21:45:30.188249Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":295857349,"revision":6076,"compact-revision":2965} {"level":"warn","ts":"2026-07-31T21:45:31.869484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"595.351176ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:45:31.869574Z","caller":"traceutil/trace.go:171","msg":"trace[1548895552] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:8276; }","duration":"595.49997ms","start":"2026-07-31T21:45:31.274054Z","end":"2026-07-31T21:45:31.869554Z","steps":["trace[1548895552] 'count revisions from in-memory index tree' (duration: 595.230682ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:45:31.869618Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:45:31.274039Z","time spent":"595.567631ms","remote":"127.0.0.1:37308","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-07-31T21:45:31.869709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.790836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-31T21:45:31.869773Z","caller":"traceutil/trace.go:171","msg":"trace[1489491889] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8276; }","duration":"546.883258ms","start":"2026-07-31T21:45:31.322874Z","end":"2026-07-31T21:45:31.869757Z","steps":["trace[1489491889] 'range keys from in-memory index tree' (duration: 546.598421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:45:31.869818Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:45:31.322859Z","time spent":"546.949709ms","remote":"127.0.0.1:59622","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-07-31T21:45:31.869813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.082193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6v8kg\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-07-31T21:45:31.869904Z","caller":"traceutil/trace.go:171","msg":"trace[1349634569] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6v8kg; range_end:; response_count:1; response_revision:8276; }","duration":"342.208216ms","start":"2026-07-31T21:45:31.527677Z","end":"2026-07-31T21:45:31.869885Z","steps":["trace[1349634569] 'range keys from in-memory index tree' (duration: 341.932059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:45:31.869943Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:45:31.527662Z","time spent":"342.271968ms","remote":"127.0.0.1:59556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6552,"request content":"key:\"/registry/pods/openstack/keepalived-6v8kg\" "} {"level":"warn","ts":"2026-07-31T21:45:31.869993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"542.729298ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-31T21:45:31.870055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"432.166565ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"info","ts":"2026-07-31T21:45:31.870101Z","caller":"traceutil/trace.go:171","msg":"trace[1478353504] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:8276; }","duration":"432.240057ms","start":"2026-07-31T21:45:31.437848Z","end":"2026-07-31T21:45:31.870088Z","steps":["trace[1478353504] 'range keys from in-memory index tree' (duration: 432.025882ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:45:31.870166Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:45:31.437832Z","time spent":"432.32397ms","remote":"127.0.0.1:59440","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":140,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-07-31T21:45:31.869775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.909837ms","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-07-31T21:45:31.870284Z","caller":"traceutil/trace.go:171","msg":"trace[62044016] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:8276; }","duration":"104.437702ms","start":"2026-07-31T21:45:31.765833Z","end":"2026-07-31T21:45:31.870271Z","steps":["trace[62044016] 'range keys from in-memory index tree' (duration: 103.893057ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:45:31.870061Z","caller":"traceutil/trace.go:171","msg":"trace[952548388] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8276; }","duration":"542.853911ms","start":"2026-07-31T21:45:31.327191Z","end":"2026-07-31T21:45:31.870045Z","steps":["trace[952548388] 'range keys from in-memory index tree' (duration: 542.545884ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:45:31.870406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:45:31.327168Z","time spent":"543.225662ms","remote":"127.0.0.1:59622","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-07-31T21:45:31.870407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"422.903292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:123753"} {"level":"info","ts":"2026-07-31T21:45:31.870465Z","caller":"traceutil/trace.go:171","msg":"trace[2038564768] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:8276; }","duration":"422.990744ms","start":"2026-07-31T21:45:31.447457Z","end":"2026-07-31T21:45:31.870448Z","steps":["trace[2038564768] 'range keys from in-memory index tree' (duration: 422.704527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:45:31.870505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:45:31.44744Z","time spent":"423.054176ms","remote":"127.0.0.1:59438","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":123775,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-07-31T21:45:32.033827Z","caller":"traceutil/trace.go:171","msg":"trace[968210868] transaction","detail":"{read_only:false; response_revision:8277; number_of_response:1; }","duration":"158.839305ms","start":"2026-07-31T21:45:31.874961Z","end":"2026-07-31T21:45:32.0338Z","steps":["trace[968210868] 'process raft request' (duration: 158.684981ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:45:32.034505Z","caller":"traceutil/trace.go:171","msg":"trace[428440644] linearizableReadLoop","detail":"{readStateIndex:8570; appliedIndex:8568; }","duration":"156.194585ms","start":"2026-07-31T21:45:31.878288Z","end":"2026-07-31T21:45:32.034483Z","steps":["trace[428440644] 'read index received' (duration: 155.447995ms)","trace[428440644] 'applied index is now lower than readState.Index' (duration: 745.53µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:45:32.034888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.582457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:118"} {"level":"warn","ts":"2026-07-31T21:45:32.034943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.169041ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:45:32.034959Z","caller":"traceutil/trace.go:171","msg":"trace[693099353] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:8278; }","duration":"156.71539ms","start":"2026-07-31T21:45:31.878226Z","end":"2026-07-31T21:45:32.034942Z","steps":["trace[693099353] 'agreement among raft nodes before linearized reading' (duration: 156.471183ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:45:32.034988Z","caller":"traceutil/trace.go:171","msg":"trace[1175124548] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8278; }","duration":"124.256024ms","start":"2026-07-31T21:45:31.910719Z","end":"2026-07-31T21:45:32.034975Z","steps":["trace[1175124548] 'agreement among raft nodes before linearized reading' (duration: 124.177122ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:45:32.034886Z","caller":"traceutil/trace.go:171","msg":"trace[1121049998] transaction","detail":"{read_only:false; response_revision:8278; number_of_response:1; }","duration":"159.036821ms","start":"2026-07-31T21:45:31.875825Z","end":"2026-07-31T21:45:32.034862Z","steps":["trace[1121049998] 'process raft request' (duration: 158.551167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:45:32.035124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.787304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:123753"} {"level":"info","ts":"2026-07-31T21:45:32.035223Z","caller":"traceutil/trace.go:171","msg":"trace[276680409] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:8278; }","duration":"155.879236ms","start":"2026-07-31T21:45:31.879299Z","end":"2026-07-31T21:45:32.035178Z","steps":["trace[276680409] 'agreement among raft nodes before linearized reading' (duration: 155.583109ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:45:42.91746Z","caller":"traceutil/trace.go:171","msg":"trace[920436289] linearizableReadLoop","detail":"{readStateIndex:8625; appliedIndex:8624; }","duration":"331.893495ms","start":"2026-07-31T21:45:42.585543Z","end":"2026-07-31T21:45:42.917436Z","steps":["trace[920436289] 'read index received' (duration: 331.747891ms)","trace[920436289] 'applied index is now lower than readState.Index' (duration: 144.294µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:45:42.917557Z","caller":"traceutil/trace.go:171","msg":"trace[1248238171] transaction","detail":"{read_only:false; response_revision:8331; number_of_response:1; }","duration":"332.716836ms","start":"2026-07-31T21:45:42.584811Z","end":"2026-07-31T21:45:42.917528Z","steps":["trace[1248238171] 'process raft request' (duration: 332.47044ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:45:42.917696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:45:42.584795Z","time spent":"332.826808ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:45:42.917799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.230814ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-31T21:45:42.917901Z","caller":"traceutil/trace.go:171","msg":"trace[1058089391] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8331; }","duration":"332.362507ms","start":"2026-07-31T21:45:42.585508Z","end":"2026-07-31T21:45:42.91787Z","steps":["trace[1058089391] 'agreement among raft nodes before linearized reading' (duration: 332.057958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:45:42.917941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:45:42.585493Z","time spent":"332.439629ms","remote":"127.0.0.1:59556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12238,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-07-31T21:45:43.062517Z","caller":"traceutil/trace.go:171","msg":"trace[689127664] transaction","detail":"{read_only:false; response_revision:8332; number_of_response:1; }","duration":"223.273121ms","start":"2026-07-31T21:45:42.839232Z","end":"2026-07-31T21:45:43.062505Z","steps":["trace[689127664] 'process raft request' (duration: 223.187679ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:45:43.062813Z","caller":"traceutil/trace.go:171","msg":"trace[334374597] linearizableReadLoop","detail":"{readStateIndex:8626; appliedIndex:8626; }","duration":"145.283608ms","start":"2026-07-31T21:45:42.917523Z","end":"2026-07-31T21:45:43.062807Z","steps":["trace[334374597] 'read index received' (duration: 145.280618ms)","trace[334374597] 'applied index is now lower than readState.Index' (duration: 2.56µs)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:45:43.062871Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.807617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:45:43.062899Z","caller":"traceutil/trace.go:171","msg":"trace[670662895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8332; }","duration":"149.876238ms","start":"2026-07-31T21:45:42.913015Z","end":"2026-07-31T21:45:43.062892Z","steps":["trace[670662895] 'agreement among raft nodes before linearized reading' (duration: 149.826017ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:45:43.063036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.378298ms","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-07-31T21:45:43.063055Z","caller":"traceutil/trace.go:171","msg":"trace[577726290] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8332; }","duration":"258.441559ms","start":"2026-07-31T21:45:42.804609Z","end":"2026-07-31T21:45:43.06305Z","steps":["trace[577726290] 'agreement among raft nodes before linearized reading' (duration: 258.369117ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:45:43.063135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.524911ms","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-07-31T21:45:43.063152Z","caller":"traceutil/trace.go:171","msg":"trace[1766537904] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8332; }","duration":"258.578272ms","start":"2026-07-31T21:45:42.80457Z","end":"2026-07-31T21:45:43.063148Z","steps":["trace[1766537904] 'agreement among raft nodes before linearized reading' (duration: 258.535891ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:45:43.099785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.766601ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-31T21:45:43.099844Z","caller":"traceutil/trace.go:171","msg":"trace[610124504] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:8336; }","duration":"178.869883ms","start":"2026-07-31T21:45:42.92096Z","end":"2026-07-31T21:45:43.09983Z","steps":["trace[610124504] 'agreement among raft nodes before linearized reading' (duration: 178.72684ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:45:45.553778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.332646ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-31T21:45:45.553888Z","caller":"traceutil/trace.go:171","msg":"trace[212102084] transaction","detail":"{read_only:false; response_revision:8350; number_of_response:1; }","duration":"394.098664ms","start":"2026-07-31T21:45:45.159769Z","end":"2026-07-31T21:45:45.553868Z","steps":["trace[212102084] 'process raft request' (duration: 102.601424ms)","trace[212102084] 'compare' (duration: 291.093468ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:45:45.554264Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:45:45.159753Z","time spent":"394.165775ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:45:45.555205Z","caller":"traceutil/trace.go:171","msg":"trace[56293237] transaction","detail":"{read_only:false; response_revision:8351; number_of_response:1; }","duration":"395.237464ms","start":"2026-07-31T21:45:45.159881Z","end":"2026-07-31T21:45:45.555118Z","steps":["trace[56293237] 'process raft request' (duration: 395.121301ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:45:45.555375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:45:45.159847Z","time spent":"395.447029ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:46:07.909975Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000225f.wal"} {"level":"info","ts":"2026-07-31T21:46:12.574919Z","caller":"traceutil/trace.go:171","msg":"trace[1766922072] linearizableReadLoop","detail":"{readStateIndex:8847; appliedIndex:8846; }","duration":"273.170734ms","start":"2026-07-31T21:46:12.301708Z","end":"2026-07-31T21:46:12.574879Z","steps":["trace[1766922072] 'read index received' (duration: 272.731843ms)","trace[1766922072] 'applied index is now lower than readState.Index' (duration: 436.901µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:46:12.575152Z","caller":"traceutil/trace.go:171","msg":"trace[2003135581] transaction","detail":"{read_only:false; response_revision:8545; number_of_response:1; }","duration":"300.133126ms","start":"2026-07-31T21:46:12.274967Z","end":"2026-07-31T21:46:12.5751Z","steps":["trace[2003135581] 'process raft request' (duration: 299.580521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:46:12.575288Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.558785ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-31T21:46:12.575373Z","caller":"traceutil/trace.go:171","msg":"trace[335166495] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8545; }","duration":"273.686928ms","start":"2026-07-31T21:46:12.301662Z","end":"2026-07-31T21:46:12.575349Z","steps":["trace[335166495] 'agreement among raft nodes before linearized reading' (duration: 273.446032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:46:12.575444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:46:12.274934Z","time spent":"300.407742ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:46:12.689388Z","caller":"traceutil/trace.go:171","msg":"trace[102399703] transaction","detail":"{read_only:false; response_revision:8546; number_of_response:1; }","duration":"108.782196ms","start":"2026-07-31T21:46:12.580576Z","end":"2026-07-31T21:46:12.689358Z","steps":["trace[102399703] 'process raft request' (duration: 102.1371ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:46:16.91826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"214.665023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-31T21:46:16.918336Z","caller":"traceutil/trace.go:171","msg":"trace[1846887780] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8568; }","duration":"214.776446ms","start":"2026-07-31T21:46:16.703544Z","end":"2026-07-31T21:46:16.91832Z","steps":["trace[1846887780] 'range keys from in-memory index tree' (duration: 214.53656ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:46:17.136579Z","caller":"traceutil/trace.go:171","msg":"trace[997344066] transaction","detail":"{read_only:false; response_revision:8569; number_of_response:1; }","duration":"214.409036ms","start":"2026-07-31T21:46:16.922135Z","end":"2026-07-31T21:46:17.136544Z","steps":["trace[997344066] 'process raft request' (duration: 214.096779ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:46:26.390347Z","caller":"traceutil/trace.go:171","msg":"trace[1077360903] transaction","detail":"{read_only:false; response_revision:8667; number_of_response:1; }","duration":"120.313319ms","start":"2026-07-31T21:46:26.269997Z","end":"2026-07-31T21:46:26.390311Z","steps":["trace[1077360903] 'process raft request' (duration: 99.981344ms)","trace[1077360903] 'compare' (duration: 20.202072ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:46:27.196697Z","caller":"traceutil/trace.go:171","msg":"trace[2048349421] transaction","detail":"{read_only:false; response_revision:8669; number_of_response:1; }","duration":"106.519505ms","start":"2026-07-31T21:46:27.090105Z","end":"2026-07-31T21:46:27.196624Z","steps":["trace[2048349421] 'process raft request' (duration: 106.242417ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:46:27.441135Z","caller":"traceutil/trace.go:171","msg":"trace[2051947685] transaction","detail":"{read_only:false; response_revision:8670; number_of_response:1; }","duration":"240.68444ms","start":"2026-07-31T21:46:27.200433Z","end":"2026-07-31T21:46:27.441117Z","steps":["trace[2051947685] 'process raft request' (duration: 239.348744ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:46:27.608975Z","caller":"traceutil/trace.go:171","msg":"trace[2136215210] transaction","detail":"{read_only:false; response_revision:8671; number_of_response:1; }","duration":"408.038537ms","start":"2026-07-31T21:46:27.200911Z","end":"2026-07-31T21:46:27.608949Z","steps":["trace[2136215210] 'process raft request' (duration: 379.953048ms)","trace[2136215210] 'compare' (duration: 27.818372ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:46:27.609027Z","caller":"traceutil/trace.go:171","msg":"trace[2010880749] transaction","detail":"{read_only:false; response_revision:8672; number_of_response:1; }","duration":"309.396989ms","start":"2026-07-31T21:46:27.299612Z","end":"2026-07-31T21:46:27.609009Z","steps":["trace[2010880749] 'process raft request' (duration: 309.204733ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:46:27.609057Z","caller":"traceutil/trace.go:171","msg":"trace[1251445921] linearizableReadLoop","detail":"{readStateIndex:8980; appliedIndex:8978; }","duration":"404.932527ms","start":"2026-07-31T21:46:27.204101Z","end":"2026-07-31T21:46:27.609033Z","steps":["trace[1251445921] 'read index received' (duration: 235.715649ms)","trace[1251445921] 'applied index is now lower than readState.Index' (duration: 169.215938ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:46:27.609097Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:46:27.200893Z","time spent":"408.14008ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:46:27.609129Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:46:27.299585Z","time spent":"309.497602ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:46:27.609173Z","caller":"traceutil/trace.go:171","msg":"trace[1436364570] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"304.220874ms","start":"2026-07-31T21:46:27.304943Z","end":"2026-07-31T21:46:27.609164Z","steps":["trace[1436364570] 'process raft request' (duration: 303.983828ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:46:27.609105Z","caller":"traceutil/trace.go:171","msg":"trace[1227118067] transaction","detail":"{read_only:false; response_revision:8674; number_of_response:1; }","duration":"300.241228ms","start":"2026-07-31T21:46:27.308829Z","end":"2026-07-31T21:46:27.60907Z","steps":["trace[1227118067] 'process raft request' (duration: 300.140056ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:46:27.609292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:46:27.308813Z","time spent":"300.435663ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:46:27.609324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:46:27.304924Z","time spent":"304.310826ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:46:27.609334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.209203ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-config-override\" ","response":"range_response_count:1 size:626"} {"level":"info","ts":"2026-07-31T21:46:27.60918Z","caller":"traceutil/trace.go:171","msg":"trace[749901094] transaction","detail":"{read_only:false; response_revision:8675; number_of_response:1; }","duration":"299.514319ms","start":"2026-07-31T21:46:27.309655Z","end":"2026-07-31T21:46:27.609169Z","steps":["trace[749901094] 'process raft request' (duration: 299.344575ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:46:27.609369Z","caller":"traceutil/trace.go:171","msg":"trace[2065834358] range","detail":"{range_begin:/registry/configmaps/openstack/rook-config-override; range_end:; response_count:1; response_revision:8675; }","duration":"405.274355ms","start":"2026-07-31T21:46:27.204085Z","end":"2026-07-31T21:46:27.60936Z","steps":["trace[2065834358] 'agreement among raft nodes before linearized reading' (duration: 405.116251ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:46:27.609403Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:46:27.204078Z","time spent":"405.316596ms","remote":"127.0.0.1:59474","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":648,"request content":"key:\"/registry/configmaps/openstack/rook-config-override\" "} {"level":"warn","ts":"2026-07-31T21:46:27.609519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.937246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T21:46:27.609553Z","caller":"traceutil/trace.go:171","msg":"trace[2077546380] range","detail":"{range_begin:/registry/roles/; range_end:/registry/roles0; response_count:0; response_revision:8675; }","duration":"328.011608ms","start":"2026-07-31T21:46:27.281534Z","end":"2026-07-31T21:46:27.609546Z","steps":["trace[2077546380] 'agreement among raft nodes before linearized reading' (duration: 327.916106ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:46:27.609573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:46:27.281515Z","time spent":"328.053111ms","remote":"127.0.0.1:59676","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":59,"response size":29,"request content":"key:\"/registry/roles/\" range_end:\"/registry/roles0\" count_only:true "} {"level":"warn","ts":"2026-07-31T21:47:59.241742Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871125491165192,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T21:47:59.742254Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871125491165192,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T21:48:03.987251Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871125491165192,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T21:48:03.987367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.037429345s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-31T21:48:03.987398Z","caller":"traceutil/trace.go:171","msg":"trace[1404379477] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"5.037487937s","start":"2026-07-31T21:47:58.949897Z","end":"2026-07-31T21:48:03.987384Z","steps":["trace[1404379477] 'agreement among raft nodes before linearized reading' (duration: 5.037442586s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:03.987428Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:47:58.949889Z","time spent":"5.037533039s","remote":"127.0.0.1:59380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T21:48:04.084188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999368853s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-07-31T21:48:04.084256Z","caller":"traceutil/trace.go:171","msg":"trace[856328625] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"4.999480036s","start":"2026-07-31T21:47:59.084761Z","end":"2026-07-31T21:48:04.084241Z","steps":["trace[856328625] 'agreement among raft nodes before linearized reading' (duration: 4.999388513s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.08429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:47:59.084747Z","time spent":"4.999536328s","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-31T21:48:04.487753Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":3880871125491165192,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-07-31T21:48:04.53437Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"6.304029996s","expected-duration":"1s"} {"level":"warn","ts":"2026-07-31T21:48:04.54907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.334780656s","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-07-31T21:48:04.549136Z","caller":"traceutil/trace.go:171","msg":"trace[1573114030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9541; }","duration":"6.334886449s","start":"2026-07-31T21:47:58.214236Z","end":"2026-07-31T21:48:04.549122Z","steps":["trace[1573114030] 'range keys from in-memory index tree' (duration: 6.334633702s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.54916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:47:58.214219Z","time spent":"6.33493606s","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T21:48:04.549389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"522.692206ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T21:48:04.549484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.32127485s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-31T21:48:04.549566Z","caller":"traceutil/trace.go:171","msg":"trace[5954485] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:9541; }","duration":"6.321393613s","start":"2026-07-31T21:47:58.228163Z","end":"2026-07-31T21:48:04.549556Z","steps":["trace[5954485] 'range keys from in-memory index tree' (duration: 6.321135486s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.549585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:47:58.228143Z","time spent":"6.321436074s","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-31T21:48:04.549577Z","caller":"traceutil/trace.go:171","msg":"trace[218144842] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:9541; }","duration":"522.825989ms","start":"2026-07-31T21:48:04.026666Z","end":"2026-07-31T21:48:04.549492Z","steps":["trace[218144842] 'range keys from in-memory index tree' (duration: 522.677595ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.55279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:47:58.74189Z","time spent":"5.810891747s","remote":"127.0.0.1:60092","response type":"/etcdserverpb.Maintenance/Status","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-07-31T21:48:04.568235Z","caller":"traceutil/trace.go:171","msg":"trace[1447055574] transaction","detail":"{read_only:false; response_revision:9542; number_of_response:1; }","duration":"538.165414ms","start":"2026-07-31T21:48:04.030042Z","end":"2026-07-31T21:48:04.568208Z","steps":["trace[1447055574] 'process raft request' (duration: 537.393164ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.568386Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.030027Z","time spent":"538.281128ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:48:04.56901Z","caller":"traceutil/trace.go:171","msg":"trace[10971631] transaction","detail":"{read_only:false; response_revision:9543; number_of_response:1; }","duration":"538.193265ms","start":"2026-07-31T21:48:04.030756Z","end":"2026-07-31T21:48:04.568949Z","steps":["trace[10971631] 'process raft request' (duration: 537.400644ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:48:04.568974Z","caller":"traceutil/trace.go:171","msg":"trace[1089094571] linearizableReadLoop","detail":"{readStateIndex:9880; appliedIndex:9873; }","duration":"5.827410913s","start":"2026-07-31T21:47:58.741546Z","end":"2026-07-31T21:48:04.568957Z","steps":["trace[1089094571] 'read index received' (duration: 5.793039988s)","trace[1089094571] 'applied index is now lower than readState.Index' (duration: 34.370035ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:48:04.569178Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.030103Z","time spent":"538.986795ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:48:04.569244Z","caller":"traceutil/trace.go:171","msg":"trace[708843296] transaction","detail":"{read_only:false; response_revision:9545; number_of_response:1; }","duration":"532.66841ms","start":"2026-07-31T21:48:04.036369Z","end":"2026-07-31T21:48:04.569037Z","steps":["trace[708843296] 'process raft request' (duration: 532.511136ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:48:04.569352Z","caller":"traceutil/trace.go:171","msg":"trace[1345365637] transaction","detail":"{read_only:false; response_revision:9546; number_of_response:1; }","duration":"375.43339ms","start":"2026-07-31T21:48:04.193908Z","end":"2026-07-31T21:48:04.569342Z","steps":["trace[1345365637] 'process raft request' (duration: 374.997998ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:48:04.569407Z","caller":"traceutil/trace.go:171","msg":"trace[749522587] transaction","detail":"{read_only:false; response_revision:9544; number_of_response:1; }","duration":"533.090731ms","start":"2026-07-31T21:48:04.036307Z","end":"2026-07-31T21:48:04.569397Z","steps":["trace[749522587] 'process raft request' (duration: 532.503425ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:48:04.569415Z","caller":"traceutil/trace.go:171","msg":"trace[1197647219] transaction","detail":"{read_only:false; response_revision:9547; number_of_response:1; }","duration":"113.717016ms","start":"2026-07-31T21:48:04.455662Z","end":"2026-07-31T21:48:04.569379Z","steps":["trace[1197647219] 'process raft request' (duration: 113.265623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.569507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.827955397s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:48:04.569589Z","caller":"traceutil/trace.go:171","msg":"trace[1027092970] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:9547; }","duration":"5.828059489s","start":"2026-07-31T21:47:58.74152Z","end":"2026-07-31T21:48:04.56958Z","steps":["trace[1027092970] 'agreement among raft nodes before linearized reading' (duration: 5.827955317s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.569599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.193881Z","time spent":"375.4971ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:48:04.569614Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:47:58.741506Z","time spent":"5.828100591s","remote":"127.0.0.1:51150","response type":"/etcdserverpb.KV/Range","request count":0,"request size":104,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true "} {"level":"warn","ts":"2026-07-31T21:48:04.569453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.036296Z","time spent":"533.133022ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:48:04.5699Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.036365Z","time spent":"532.949218ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:48:04.575993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.690021ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T21:48:04.576063Z","caller":"traceutil/trace.go:171","msg":"trace[2072758022] range","detail":"{range_begin:/registry/secretgen.carvel.dev/secrettemplates/; range_end:/registry/secretgen.carvel.dev/secrettemplates0; response_count:0; response_revision:9549; }","duration":"548.790065ms","start":"2026-07-31T21:48:04.027256Z","end":"2026-07-31T21:48:04.576046Z","steps":["trace[2072758022] 'agreement among raft nodes before linearized reading' (duration: 548.674572ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.576072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.435094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:48:04.576174Z","caller":"traceutil/trace.go:171","msg":"trace[1955343576] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:9549; }","duration":"155.572507ms","start":"2026-07-31T21:48:04.420585Z","end":"2026-07-31T21:48:04.576158Z","steps":["trace[1955343576] 'agreement among raft nodes before linearized reading' (duration: 155.463045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.5762Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.365559ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T21:48:04.576224Z","caller":"traceutil/trace.go:171","msg":"trace[1347056221] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:9549; }","duration":"549.428931ms","start":"2026-07-31T21:48:04.026787Z","end":"2026-07-31T21:48:04.576216Z","steps":["trace[1347056221] 'agreement among raft nodes before linearized reading' (duration: 549.322877ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.576245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.026772Z","time spent":"549.465672ms","remote":"127.0.0.1:59434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":120,"response count":92,"response size":29,"request content":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true "} {"level":"warn","ts":"2026-07-31T21:48:04.576362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.827447ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T21:48:04.57639Z","caller":"traceutil/trace.go:171","msg":"trace[1055922186] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:9549; }","duration":"250.893509ms","start":"2026-07-31T21:48:04.325486Z","end":"2026-07-31T21:48:04.576379Z","steps":["trace[1055922186] 'agreement among raft nodes before linearized reading' (duration: 250.843298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.576427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.632261033s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:19331"} {"level":"warn","ts":"2026-07-31T21:48:04.576467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.79262ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:48:04.576469Z","caller":"traceutil/trace.go:171","msg":"trace[1324278430] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:9549; }","duration":"5.632318004s","start":"2026-07-31T21:47:58.944138Z","end":"2026-07-31T21:48:04.576456Z","steps":["trace[1324278430] 'agreement among raft nodes before linearized reading' (duration: 5.63216675s)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:48:04.576493Z","caller":"traceutil/trace.go:171","msg":"trace[58277526] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:9549; }","duration":"549.839582ms","start":"2026-07-31T21:48:04.026645Z","end":"2026-07-31T21:48:04.576485Z","steps":["trace[58277526] 'agreement among raft nodes before linearized reading' (duration: 549.798661ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.576503Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"478.972655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T21:48:04.576505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:47:58.944131Z","time spent":"5.632365555s","remote":"127.0.0.1:59552","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":19353,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-31T21:48:04.576523Z","caller":"traceutil/trace.go:171","msg":"trace[1166058798] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9549; }","duration":"479.018676ms","start":"2026-07-31T21:48:04.097499Z","end":"2026-07-31T21:48:04.576517Z","steps":["trace[1166058798] 'agreement among raft nodes before linearized reading' (duration: 478.981745ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.576531Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.02663Z","time spent":"549.887312ms","remote":"127.0.0.1:37336","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-31T21:48:04.576602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"536.439118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-77f5695ff4-z7xgf.18c77e01e8b7534f\" ","response":"range_response_count:1 size:1135"} {"level":"info","ts":"2026-07-31T21:48:04.576623Z","caller":"traceutil/trace.go:171","msg":"trace[2031496709] range","detail":"{range_begin:/registry/events/openstack/barbican-api-77f5695ff4-z7xgf.18c77e01e8b7534f; range_end:; response_count:1; response_revision:9549; }","duration":"536.50653ms","start":"2026-07-31T21:48:04.04011Z","end":"2026-07-31T21:48:04.576617Z","steps":["trace[2031496709] 'agreement among raft nodes before linearized reading' (duration: 536.440678ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.576639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.04009Z","time spent":"536.544431ms","remote":"127.0.0.1:59442","response type":"/etcdserverpb.KV/Range","request count":0,"request size":75,"response count":1,"response size":1157,"request content":"key:\"/registry/events/openstack/barbican-api-77f5695ff4-z7xgf.18c77e01e8b7534f\" "} {"level":"warn","ts":"2026-07-31T21:48:04.576669Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.092589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:48:04.576697Z","caller":"traceutil/trace.go:171","msg":"trace[1007352224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:9549; }","duration":"550.1478ms","start":"2026-07-31T21:48:04.026541Z","end":"2026-07-31T21:48:04.576689Z","steps":["trace[1007352224] 'agreement among raft nodes before linearized reading' (duration: 550.101218ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.57673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.026518Z","time spent":"550.196541ms","remote":"127.0.0.1:59390","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T21:48:04.576773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"540.63732ms","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-07-31T21:48:04.576793Z","caller":"traceutil/trace.go:171","msg":"trace[2103307680] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:9549; }","duration":"540.69015ms","start":"2026-07-31T21:48:04.036097Z","end":"2026-07-31T21:48:04.576787Z","steps":["trace[2103307680] 'agreement among raft nodes before linearized reading' (duration: 540.630869ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.576809Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.036077Z","time spent":"540.728662ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-31T21:48:04.576841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.056707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-31T21:48:04.576869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.543744ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:48:04.576874Z","caller":"traceutil/trace.go:171","msg":"trace[855723273] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:9549; }","duration":"550.111308ms","start":"2026-07-31T21:48:04.026755Z","end":"2026-07-31T21:48:04.576866Z","steps":["trace[855723273] 'agreement among raft nodes before linearized reading' (duration: 549.889733ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:48:04.576888Z","caller":"traceutil/trace.go:171","msg":"trace[782212346] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:9549; }","duration":"546.582846ms","start":"2026-07-31T21:48:04.030299Z","end":"2026-07-31T21:48:04.576882Z","steps":["trace[782212346] 'agreement among raft nodes before linearized reading' (duration: 546.551345ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.576902Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.02675Z","time spent":"550.14426ms","remote":"127.0.0.1:60156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"warn","ts":"2026-07-31T21:48:04.576933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.631690849s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6v8kg\" ","response":"range_response_count:1 size:6530"} {"level":"info","ts":"2026-07-31T21:48:04.576963Z","caller":"traceutil/trace.go:171","msg":"trace[999433093] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6v8kg; range_end:; response_count:1; response_revision:9549; }","duration":"5.631731729s","start":"2026-07-31T21:47:58.945222Z","end":"2026-07-31T21:48:04.576954Z","steps":["trace[999433093] 'agreement among raft nodes before linearized reading' (duration: 5.631631576s)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.576986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:47:58.945209Z","time spent":"5.63177086s","remote":"127.0.0.1:59556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6552,"request content":"key:\"/registry/pods/openstack/keepalived-6v8kg\" "} {"level":"warn","ts":"2026-07-31T21:48:04.577027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.282434ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T21:48:04.577054Z","caller":"traceutil/trace.go:171","msg":"trace[1699653436] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9549; }","duration":"550.318975ms","start":"2026-07-31T21:48:04.026729Z","end":"2026-07-31T21:48:04.577048Z","steps":["trace[1699653436] 'agreement among raft nodes before linearized reading' (duration: 550.274123ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.577068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"546.789032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-31T21:48:04.577075Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.026725Z","time spent":"550.343115ms","remote":"127.0.0.1:40736","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-31T21:48:04.577093Z","caller":"traceutil/trace.go:171","msg":"trace[1673789057] range","detail":"{range_begin:/registry/endpointslices/; range_end:/registry/endpointslices0; response_count:0; response_revision:9549; }","duration":"546.846683ms","start":"2026-07-31T21:48:04.03024Z","end":"2026-07-31T21:48:04.577087Z","steps":["trace[1673789057] 'agreement among raft nodes before linearized reading' (duration: 546.678028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.577152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.030224Z","time spent":"546.884324ms","remote":"127.0.0.1:59626","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":61,"response size":29,"request content":"key:\"/registry/endpointslices/\" range_end:\"/registry/endpointslices0\" count_only:true "} {"level":"warn","ts":"2026-07-31T21:48:04.576541Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.097484Z","time spent":"479.052627ms","remote":"127.0.0.1:59380","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-31T21:48:04.576095Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.02725Z","time spent":"548.835416ms","remote":"127.0.0.1:34002","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":2,"response size":29,"request content":"key:\"/registry/secretgen.carvel.dev/secrettemplates/\" range_end:\"/registry/secretgen.carvel.dev/secrettemplates0\" count_only:true "} {"level":"warn","ts":"2026-07-31T21:48:04.577257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.843181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T21:48:04.577315Z","caller":"traceutil/trace.go:171","msg":"trace[123715276] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:9549; }","duration":"549.918933ms","start":"2026-07-31T21:48:04.027383Z","end":"2026-07-31T21:48:04.577302Z","steps":["trace[123715276] 'agreement among raft nodes before linearized reading' (duration: 549.833911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.577344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.02738Z","time spent":"549.954215ms","remote":"127.0.0.1:37400","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":1,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true "} {"level":"warn","ts":"2026-07-31T21:48:04.575998Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.63457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:48:04.577412Z","caller":"traceutil/trace.go:171","msg":"trace[1129463085] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:9549; }","duration":"550.066537ms","start":"2026-07-31T21:48:04.027325Z","end":"2026-07-31T21:48:04.577391Z","steps":["trace[1129463085] 'agreement among raft nodes before linearized reading' (duration: 548.592828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.57745Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.027322Z","time spent":"550.115748ms","remote":"127.0.0.1:51114","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":27,"request content":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true "} {"level":"warn","ts":"2026-07-31T21:48:04.577585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.446249ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-31T21:48:04.577608Z","caller":"traceutil/trace.go:171","msg":"trace[670808762] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:9549; }","duration":"547.550132ms","start":"2026-07-31T21:48:04.03005Z","end":"2026-07-31T21:48:04.577601Z","steps":["trace[670808762] 'agreement among raft nodes before linearized reading' (duration: 547.434998ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.577608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"550.135079ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-31T21:48:04.57763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.030047Z","time spent":"547.576213ms","remote":"127.0.0.1:40796","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":29,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-07-31T21:48:04.577681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.701986ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-31T21:48:04.577696Z","caller":"traceutil/trace.go:171","msg":"trace[1091254809] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:9549; }","duration":"547.732567ms","start":"2026-07-31T21:48:04.029959Z","end":"2026-07-31T21:48:04.577691Z","steps":["trace[1091254809] 'agreement among raft nodes before linearized reading' (duration: 547.683465ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:48:04.57763Z","caller":"traceutil/trace.go:171","msg":"trace[379588651] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9549; }","duration":"550.15807ms","start":"2026-07-31T21:48:04.027466Z","end":"2026-07-31T21:48:04.577624Z","steps":["trace[379588651] 'agreement among raft nodes before linearized reading' (duration: 550.050976ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.577751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.746327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:9 size:88514"} {"level":"warn","ts":"2026-07-31T21:48:04.576906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.030284Z","time spent":"546.616817ms","remote":"127.0.0.1:40654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"info","ts":"2026-07-31T21:48:04.577779Z","caller":"traceutil/trace.go:171","msg":"trace[1175977558] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:9; response_revision:9549; }","duration":"547.784839ms","start":"2026-07-31T21:48:04.029986Z","end":"2026-07-31T21:48:04.577771Z","steps":["trace[1175977558] 'agreement among raft nodes before linearized reading' (duration: 547.604634ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:48:04.577799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.029983Z","time spent":"547.810159ms","remote":"127.0.0.1:59754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":9,"response size":88536,"request content":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" "} {"level":"warn","ts":"2026-07-31T21:48:04.577751Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.027455Z","time spent":"550.285464ms","remote":"127.0.0.1:59622","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-07-31T21:48:04.577714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:48:04.02995Z","time spent":"547.760188ms","remote":"127.0.0.1:59404","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.204.45.229\" "} {"level":"info","ts":"2026-07-31T21:48:23.449681Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-31T21:48:23.45495Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-31T21:48:23.455047Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-07-31T21:50:30.099595Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8268} {"level":"info","ts":"2026-07-31T21:50:30.177196Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8268,"took":"75.18697ms","hash":3856772314,"current-db-size-bytes":57696256,"current-db-size":"58 MB","current-db-size-in-use-bytes":25137152,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-07-31T21:50:30.177268Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3856772314,"revision":8268,"compact-revision":6076} {"level":"info","ts":"2026-07-31T21:53:20.068018Z","caller":"traceutil/trace.go:171","msg":"trace[997344718] transaction","detail":"{read_only:false; response_revision:11813; number_of_response:1; }","duration":"132.033505ms","start":"2026-07-31T21:53:19.935951Z","end":"2026-07-31T21:53:20.067985Z","steps":["trace[997344718] 'process raft request' (duration: 131.85632ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:53:20.635454Z","caller":"traceutil/trace.go:171","msg":"trace[1879970965] transaction","detail":"{read_only:false; response_revision:11814; number_of_response:1; }","duration":"437.583388ms","start":"2026-07-31T21:53:20.197849Z","end":"2026-07-31T21:53:20.635433Z","steps":["trace[1879970965] 'process raft request' (duration: 437.325421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:53:20.635578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:53:20.197832Z","time spent":"437.68455ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T21:53:20.653644Z","caller":"traceutil/trace.go:171","msg":"trace[528424876] transaction","detail":"{read_only:false; response_revision:11815; number_of_response:1; }","duration":"455.690485ms","start":"2026-07-31T21:53:20.197926Z","end":"2026-07-31T21:53:20.653617Z","steps":["trace[528424876] 'process raft request' (duration: 455.477328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:53:20.653791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:53:20.197872Z","time spent":"455.840268ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T21:53:20.653979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.040117ms","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-07-31T21:53:20.654043Z","caller":"traceutil/trace.go:171","msg":"trace[381074444] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11816; }","duration":"455.115209ms","start":"2026-07-31T21:53:20.198914Z","end":"2026-07-31T21:53:20.654029Z","steps":["trace[381074444] 'agreement among raft nodes before linearized reading' (duration: 454.942325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:53:20.65408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:53:20.198898Z","time spent":"455.173401ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-07-31T21:53:20.653822Z","caller":"traceutil/trace.go:171","msg":"trace[1909199772] linearizableReadLoop","detail":"{readStateIndex:12221; appliedIndex:12219; }","duration":"454.657127ms","start":"2026-07-31T21:53:20.198941Z","end":"2026-07-31T21:53:20.653598Z","steps":["trace[1909199772] 'read index received' (duration: 436.307984ms)","trace[1909199772] 'applied index is now lower than readState.Index' (duration: 18.348113ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T21:53:20.654782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.739419ms","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-07-31T21:53:20.654872Z","caller":"traceutil/trace.go:171","msg":"trace[1823690481] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11816; }","duration":"454.836441ms","start":"2026-07-31T21:53:20.199988Z","end":"2026-07-31T21:53:20.654824Z","steps":["trace[1823690481] 'agreement among raft nodes before linearized reading' (duration: 454.625626ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:53:20.654956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T21:53:20.199977Z","time spent":"454.937965ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-31T21:53:20.761821Z","caller":"traceutil/trace.go:171","msg":"trace[2044670523] transaction","detail":"{read_only:false; response_revision:11817; number_of_response:1; }","duration":"103.479914ms","start":"2026-07-31T21:53:20.658321Z","end":"2026-07-31T21:53:20.7618Z","steps":["trace[2044670523] 'process raft request' (duration: 17.210393ms)","trace[2044670523] 'compare' (duration: 86.141297ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:53:20.761989Z","caller":"traceutil/trace.go:171","msg":"trace[735558692] transaction","detail":"{read_only:false; response_revision:11818; number_of_response:1; }","duration":"102.59993ms","start":"2026-07-31T21:53:20.65936Z","end":"2026-07-31T21:53:20.76196Z","steps":["trace[735558692] 'process raft request' (duration: 102.414045ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:53:21.959668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.867226ms","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-07-31T21:53:21.96012Z","caller":"traceutil/trace.go:171","msg":"trace[1679071815] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:11822; }","duration":"193.334029ms","start":"2026-07-31T21:53:21.766765Z","end":"2026-07-31T21:53:21.960099Z","steps":["trace[1679071815] 'range keys from in-memory index tree' (duration: 192.847316ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:53:21.968555Z","caller":"traceutil/trace.go:171","msg":"trace[1275908793] linearizableReadLoop","detail":"{readStateIndex:12229; appliedIndex:12228; }","duration":"258.976666ms","start":"2026-07-31T21:53:21.709551Z","end":"2026-07-31T21:53:21.968527Z","steps":["trace[1275908793] 'read index received' (duration: 258.823403ms)","trace[1275908793] 'applied index is now lower than readState.Index' (duration: 152.173µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T21:53:21.968679Z","caller":"traceutil/trace.go:171","msg":"trace[1140676692] transaction","detail":"{read_only:false; response_revision:11823; number_of_response:1; }","duration":"282.161746ms","start":"2026-07-31T21:53:21.686484Z","end":"2026-07-31T21:53:21.968646Z","steps":["trace[1140676692] 'process raft request' (duration: 281.864239ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:53:21.968876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.308904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1988"} {"level":"info","ts":"2026-07-31T21:53:21.968934Z","caller":"traceutil/trace.go:171","msg":"trace[1129601755] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:11823; }","duration":"259.409757ms","start":"2026-07-31T21:53:21.709507Z","end":"2026-07-31T21:53:21.968917Z","steps":["trace[1129601755] 'agreement among raft nodes before linearized reading' (duration: 259.143261ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:53:21.987213Z","caller":"traceutil/trace.go:171","msg":"trace[644989989] transaction","detail":"{read_only:false; response_revision:11824; number_of_response:1; }","duration":"228.174186ms","start":"2026-07-31T21:53:21.759011Z","end":"2026-07-31T21:53:21.987185Z","steps":["trace[644989989] 'process raft request' (duration: 228.014991ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:53:21.987434Z","caller":"traceutil/trace.go:171","msg":"trace[2049781391] transaction","detail":"{read_only:false; response_revision:11825; number_of_response:1; }","duration":"219.536139ms","start":"2026-07-31T21:53:21.767881Z","end":"2026-07-31T21:53:21.987417Z","steps":["trace[2049781391] 'process raft request' (duration: 219.264851ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:53:21.98744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.341941ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:53:21.987498Z","caller":"traceutil/trace.go:171","msg":"trace[1325777757] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11825; }","duration":"111.450353ms","start":"2026-07-31T21:53:21.876031Z","end":"2026-07-31T21:53:21.987481Z","steps":["trace[1325777757] 'agreement among raft nodes before linearized reading' (duration: 111.339271ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:53:21.987579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.342425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T21:53:21.987569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.1782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5077"} {"level":"warn","ts":"2026-07-31T21:53:21.987596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.447534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:53:21.987642Z","caller":"traceutil/trace.go:171","msg":"trace[860252242] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11825; }","duration":"110.428307ms","start":"2026-07-31T21:53:21.877194Z","end":"2026-07-31T21:53:21.987622Z","steps":["trace[860252242] 'agreement among raft nodes before linearized reading' (duration: 110.332025ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:53:21.987653Z","caller":"traceutil/trace.go:171","msg":"trace[1038485225] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11825; }","duration":"110.277762ms","start":"2026-07-31T21:53:21.877356Z","end":"2026-07-31T21:53:21.987634Z","steps":["trace[1038485225] 'agreement among raft nodes before linearized reading' (duration: 110.030096ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:53:21.987683Z","caller":"traceutil/trace.go:171","msg":"trace[1109097613] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11825; }","duration":"111.579117ms","start":"2026-07-31T21:53:21.876083Z","end":"2026-07-31T21:53:21.987662Z","steps":["trace[1109097613] 'agreement among raft nodes before linearized reading' (duration: 111.423033ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T21:53:21.988251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.253428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T21:53:21.988301Z","caller":"traceutil/trace.go:171","msg":"trace[889129066] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11825; }","duration":"111.345601ms","start":"2026-07-31T21:53:21.876944Z","end":"2026-07-31T21:53:21.98829Z","steps":["trace[889129066] 'agreement among raft nodes before linearized reading' (duration: 110.5502ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T21:55:30.10773Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10471} {"level":"info","ts":"2026-07-31T21:55:30.182848Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10471,"took":"72.385815ms","hash":3990391981,"current-db-size-bytes":57696256,"current-db-size":"58 MB","current-db-size-in-use-bytes":25739264,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-07-31T21:55:30.182926Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3990391981,"revision":10471,"compact-revision":8268} {"level":"info","ts":"2026-07-31T21:59:25.664531Z","caller":"traceutil/trace.go:171","msg":"trace[2035635455] transaction","detail":"{read_only:false; response_revision:14286; number_of_response:1; }","duration":"158.997657ms","start":"2026-07-31T21:59:25.505508Z","end":"2026-07-31T21:59:25.664506Z","steps":["trace[2035635455] 'process raft request' (duration: 158.839373ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:00:01.598705Z","caller":"traceutil/trace.go:171","msg":"trace[1089893083] linearizableReadLoop","detail":"{readStateIndex:14989; appliedIndex:14988; }","duration":"216.954673ms","start":"2026-07-31T22:00:01.381727Z","end":"2026-07-31T22:00:01.598681Z","steps":["trace[1089893083] 'read index received' (duration: 216.797879ms)","trace[1089893083] 'applied index is now lower than readState.Index' (duration: 155.934µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:00:01.598787Z","caller":"traceutil/trace.go:171","msg":"trace[1792068761] transaction","detail":"{read_only:false; response_revision:14489; number_of_response:1; }","duration":"258.741193ms","start":"2026-07-31T22:00:01.340017Z","end":"2026-07-31T22:00:01.598759Z","steps":["trace[1792068761] 'process raft request' (duration: 258.510657ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:00:01.598887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.092436ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xlistenersets/\" range_end:\"/registry/gateway.networking.x-k8s.io/xlistenersets0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T22:00:01.598975Z","caller":"traceutil/trace.go:171","msg":"trace[724026332] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xlistenersets/; range_end:/registry/gateway.networking.x-k8s.io/xlistenersets0; response_count:0; response_revision:14489; }","duration":"217.282571ms","start":"2026-07-31T22:00:01.381674Z","end":"2026-07-31T22:00:01.598957Z","steps":["trace[724026332] 'agreement among raft nodes before linearized reading' (duration: 217.108556ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:00:01.994294Z","caller":"traceutil/trace.go:171","msg":"trace[660265598] transaction","detail":"{read_only:false; response_revision:14490; number_of_response:1; }","duration":"599.552017ms","start":"2026-07-31T22:00:01.394709Z","end":"2026-07-31T22:00:01.994261Z","steps":["trace[660265598] 'process raft request' (duration: 589.580874ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:00:01.994401Z","caller":"traceutil/trace.go:171","msg":"trace[603840542] transaction","detail":"{read_only:false; response_revision:14491; number_of_response:1; }","duration":"591.588287ms","start":"2026-07-31T22:00:01.402766Z","end":"2026-07-31T22:00:01.994354Z","steps":["trace[603840542] 'process raft request' (duration: 591.385892ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:00:01.994454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:00:01.394696Z","time spent":"599.677559ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T22:00:01.994565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:00:01.402757Z","time spent":"591.71878ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:00:01.99477Z","caller":"traceutil/trace.go:171","msg":"trace[55040056] linearizableReadLoop","detail":"{readStateIndex:14991; appliedIndex:14989; }","duration":"178.187312ms","start":"2026-07-31T22:00:01.816205Z","end":"2026-07-31T22:00:01.994392Z","steps":["trace[55040056] 'read index received' (duration: 168.23361ms)","trace[55040056] 'applied index is now lower than readState.Index' (duration: 9.951572ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:00:01.994909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.698895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-31T22:00:01.99497Z","caller":"traceutil/trace.go:171","msg":"trace[1780817096] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:14492; }","duration":"178.789598ms","start":"2026-07-31T22:00:01.81616Z","end":"2026-07-31T22:00:01.99495Z","steps":["trace[1780817096] 'agreement among raft nodes before linearized reading' (duration: 178.617893ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:00:01.994002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.018274ms","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-07-31T22:00:01.995073Z","caller":"traceutil/trace.go:171","msg":"trace[1084149022] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:14489; }","duration":"229.103092ms","start":"2026-07-31T22:00:01.765951Z","end":"2026-07-31T22:00:01.995054Z","steps":["trace[1084149022] 'range keys from in-memory index tree' (duration: 227.997334ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:00:30.11371Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12690} {"level":"info","ts":"2026-07-31T22:00:30.187888Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12690,"took":"71.528673ms","hash":4007467612,"current-db-size-bytes":57696256,"current-db-size":"58 MB","current-db-size-in-use-bytes":27693056,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-07-31T22:00:30.187948Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4007467612,"revision":12690,"compact-revision":10471} {"level":"warn","ts":"2026-07-31T22:00:32.128214Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.883556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T22:00:32.128324Z","caller":"traceutil/trace.go:171","msg":"trace[921463019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14837; }","duration":"216.024659ms","start":"2026-07-31T22:00:31.912276Z","end":"2026-07-31T22:00:32.1283Z","steps":["trace[921463019] 'range keys from in-memory index tree' (duration: 215.822923ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:00:32.129209Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.209866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a2479ab0-cc29-4963-b783-d45416aa17bd\" ","response":"range_response_count:1 size:3637"} {"level":"info","ts":"2026-07-31T22:00:32.129327Z","caller":"traceutil/trace.go:171","msg":"trace[1332793967] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a2479ab0-cc29-4963-b783-d45416aa17bd; range_end:; response_count:1; response_revision:14837; }","duration":"177.375821ms","start":"2026-07-31T22:00:31.951933Z","end":"2026-07-31T22:00:32.129309Z","steps":["trace[1332793967] 'range keys from in-memory index tree' (duration: 176.818216ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:00:32.69838Z","caller":"traceutil/trace.go:171","msg":"trace[1473337862] linearizableReadLoop","detail":"{readStateIndex:15358; appliedIndex:15357; }","duration":"401.08802ms","start":"2026-07-31T22:00:32.297261Z","end":"2026-07-31T22:00:32.698349Z","steps":["trace[1473337862] 'read index received' (duration: 400.788633ms)","trace[1473337862] 'applied index is now lower than readState.Index' (duration: 298.497µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:00:32.698471Z","caller":"traceutil/trace.go:171","msg":"trace[1468502311] transaction","detail":"{read_only:false; response_revision:14842; number_of_response:1; }","duration":"482.923456ms","start":"2026-07-31T22:00:32.215517Z","end":"2026-07-31T22:00:32.698441Z","steps":["trace[1468502311] 'process raft request' (duration: 482.666439ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:00:32.698634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.187978ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-07-31T22:00:32.698664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"401.379009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-6v8kg\" ","response":"range_response_count:1 size:6530"} {"level":"warn","ts":"2026-07-31T22:00:32.69863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:00:32.215489Z","time spent":"483.06506ms","remote":"127.0.0.1:59556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:00:32.698697Z","caller":"traceutil/trace.go:171","msg":"trace[2008310671] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:14842; }","duration":"215.294211ms","start":"2026-07-31T22:00:32.483389Z","end":"2026-07-31T22:00:32.698684Z","steps":["trace[2008310671] 'agreement among raft nodes before linearized reading' (duration: 215.184667ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:00:32.699149Z","caller":"traceutil/trace.go:171","msg":"trace[732698238] range","detail":"{range_begin:/registry/pods/openstack/keepalived-6v8kg; range_end:; response_count:1; response_revision:14842; }","duration":"401.503242ms","start":"2026-07-31T22:00:32.297203Z","end":"2026-07-31T22:00:32.698706Z","steps":["trace[732698238] 'agreement among raft nodes before linearized reading' (duration: 401.301216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:00:32.69922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:00:32.297178Z","time spent":"402.026055ms","remote":"127.0.0.1:59556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6552,"request content":"key:\"/registry/pods/openstack/keepalived-6v8kg\" "} {"level":"info","ts":"2026-07-31T22:00:33.153147Z","caller":"traceutil/trace.go:171","msg":"trace[1568384750] linearizableReadLoop","detail":"{readStateIndex:15359; appliedIndex:15358; }","duration":"285.823997ms","start":"2026-07-31T22:00:32.867286Z","end":"2026-07-31T22:00:33.15311Z","steps":["trace[1568384750] 'read index received' (duration: 242.987689ms)","trace[1568384750] 'applied index is now lower than readState.Index' (duration: 42.835088ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:00:33.153243Z","caller":"traceutil/trace.go:171","msg":"trace[1885853439] transaction","detail":"{read_only:false; response_revision:14843; number_of_response:1; }","duration":"305.002511ms","start":"2026-07-31T22:00:32.848167Z","end":"2026-07-31T22:00:33.153169Z","steps":["trace[1885853439] 'process raft request' (duration: 262.191094ms)","trace[1885853439] 'compare' (duration: 42.487798ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:00:33.153309Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"286.010931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-07-31T22:00:33.153357Z","caller":"traceutil/trace.go:171","msg":"trace[271612577] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14843; }","duration":"286.100534ms","start":"2026-07-31T22:00:32.867246Z","end":"2026-07-31T22:00:33.153347Z","steps":["trace[271612577] 'agreement among raft nodes before linearized reading' (duration: 285.97083ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:00:33.15342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:00:32.848144Z","time spent":"305.175165ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T22:00:33.153468Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.034739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-nova\" ","response":"range_response_count:1 size:3296"} {"level":"warn","ts":"2026-07-31T22:00:33.153429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.185106ms","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-07-31T22:00:33.153541Z","caller":"traceutil/trace.go:171","msg":"trace[605861621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:14843; }","duration":"283.331659ms","start":"2026-07-31T22:00:32.870203Z","end":"2026-07-31T22:00:33.153535Z","steps":["trace[605861621] 'agreement among raft nodes before linearized reading' (duration: 283.084173ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:00:33.153543Z","caller":"traceutil/trace.go:171","msg":"trace[1790555105] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-nova; range_end:; response_count:1; response_revision:14843; }","duration":"145.158002ms","start":"2026-07-31T22:00:33.00837Z","end":"2026-07-31T22:00:33.153528Z","steps":["trace[1790555105] 'agreement among raft nodes before linearized reading' (duration: 144.928366ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:00:33.153564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.859837ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-fsp4m\" ","response":"range_response_count:1 size:20643"} {"level":"info","ts":"2026-07-31T22:00:33.153613Z","caller":"traceutil/trace.go:171","msg":"trace[2029717053] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-fsp4m; range_end:; response_count:1; response_revision:14843; }","duration":"173.929219ms","start":"2026-07-31T22:00:32.979664Z","end":"2026-07-31T22:00:33.153593Z","steps":["trace[2029717053] 'agreement among raft nodes before linearized reading' (duration: 173.813726ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:00:33.153614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.624077ms","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-07-31T22:00:33.153673Z","caller":"traceutil/trace.go:171","msg":"trace[915022069] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14842; }","duration":"327.724588ms","start":"2026-07-31T22:00:32.825931Z","end":"2026-07-31T22:00:33.153656Z","steps":["trace[915022069] 'range keys from in-memory index tree' (duration: 326.868175ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:00:33.153711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:00:32.825915Z","time spent":"327.785621ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-31T22:00:33.153744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.448083ms","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-07-31T22:00:33.153762Z","caller":"traceutil/trace.go:171","msg":"trace[495246093] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:14843; }","duration":"283.488314ms","start":"2026-07-31T22:00:32.870269Z","end":"2026-07-31T22:00:33.153757Z","steps":["trace[495246093] 'agreement among raft nodes before linearized reading' (duration: 283.439363ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:01:57.955429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.993276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-07-31T22:01:57.955506Z","caller":"traceutil/trace.go:171","msg":"trace[408284782] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15650; }","duration":"156.111441ms","start":"2026-07-31T22:01:57.799376Z","end":"2026-07-31T22:01:57.955487Z","steps":["trace[408284782] 'range keys from in-memory index tree' (duration: 155.871893ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:01:58.733946Z","caller":"traceutil/trace.go:171","msg":"trace[1955865713] transaction","detail":"{read_only:false; response_revision:15653; number_of_response:1; }","duration":"282.94617ms","start":"2026-07-31T22:01:58.450976Z","end":"2026-07-31T22:01:58.733922Z","steps":["trace[1955865713] 'process raft request' (duration: 282.784636ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:01:58.745562Z","caller":"traceutil/trace.go:171","msg":"trace[1177360656] transaction","detail":"{read_only:false; response_revision:15654; number_of_response:1; }","duration":"294.499295ms","start":"2026-07-31T22:01:58.451045Z","end":"2026-07-31T22:01:58.745544Z","steps":["trace[1177360656] 'process raft request' (duration: 294.387732ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:01:58.745742Z","caller":"traceutil/trace.go:171","msg":"trace[1169865130] linearizableReadLoop","detail":"{readStateIndex:16193; appliedIndex:16191; }","duration":"279.767406ms","start":"2026-07-31T22:01:58.465948Z","end":"2026-07-31T22:01:58.745715Z","steps":["trace[1169865130] 'read index received' (duration: 267.904453ms)","trace[1169865130] 'applied index is now lower than readState.Index' (duration: 11.861423ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:01:58.745771Z","caller":"traceutil/trace.go:171","msg":"trace[1450438399] transaction","detail":"{read_only:false; response_revision:15655; number_of_response:1; }","duration":"278.179735ms","start":"2026-07-31T22:01:58.467561Z","end":"2026-07-31T22:01:58.745741Z","steps":["trace[1450438399] 'process raft request' (duration: 277.956839ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:01:58.745879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.91352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T22:01:58.745932Z","caller":"traceutil/trace.go:171","msg":"trace[2089161422] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:15655; }","duration":"280.008403ms","start":"2026-07-31T22:01:58.465911Z","end":"2026-07-31T22:01:58.745919Z","steps":["trace[2089161422] 'agreement among raft nodes before linearized reading' (duration: 279.917571ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:01:58.745934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.581216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T22:01:58.745992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.652614ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-31T22:01:58.746009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.875449ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-07-31T22:01:58.746022Z","caller":"traceutil/trace.go:171","msg":"trace[1729296444] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15655; }","duration":"188.703389ms","start":"2026-07-31T22:01:58.557298Z","end":"2026-07-31T22:01:58.746002Z","steps":["trace[1729296444] 'agreement among raft nodes before linearized reading' (duration: 188.573516ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:01:58.746036Z","caller":"traceutil/trace.go:171","msg":"trace[1704702837] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15655; }","duration":"208.736517ms","start":"2026-07-31T22:01:58.537288Z","end":"2026-07-31T22:01:58.746025Z","steps":["trace[1704702837] 'agreement among raft nodes before linearized reading' (duration: 208.647904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:01:58.746034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.279769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:667"} {"level":"info","ts":"2026-07-31T22:01:58.746043Z","caller":"traceutil/trace.go:171","msg":"trace[1813308001] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15655; }","duration":"181.936911ms","start":"2026-07-31T22:01:58.564097Z","end":"2026-07-31T22:01:58.746034Z","steps":["trace[1813308001] 'agreement among raft nodes before linearized reading' (duration: 181.804968ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:01:58.746052Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.705361ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-31T22:01:58.746081Z","caller":"traceutil/trace.go:171","msg":"trace[1935079363] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15655; }","duration":"182.358682ms","start":"2026-07-31T22:01:58.563708Z","end":"2026-07-31T22:01:58.746067Z","steps":["trace[1935079363] 'agreement among raft nodes before linearized reading' (duration: 182.233889ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:01:58.745968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.604977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-07-31T22:01:58.746096Z","caller":"traceutil/trace.go:171","msg":"trace[618909660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:15655; }","duration":"204.762962ms","start":"2026-07-31T22:01:58.541319Z","end":"2026-07-31T22:01:58.746082Z","steps":["trace[618909660] 'agreement among raft nodes before linearized reading' (duration: 204.67817ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:01:58.746186Z","caller":"traceutil/trace.go:171","msg":"trace[2084807982] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15655; }","duration":"207.864243ms","start":"2026-07-31T22:01:58.538313Z","end":"2026-07-31T22:01:58.746178Z","steps":["trace[2084807982] 'agreement among raft nodes before linearized reading' (duration: 207.569456ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:01:58.746191Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.980793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T22:01:58.746313Z","caller":"traceutil/trace.go:171","msg":"trace[1933027492] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15655; }","duration":"209.164248ms","start":"2026-07-31T22:01:58.537117Z","end":"2026-07-31T22:01:58.746281Z","steps":["trace[1933027492] 'agreement among raft nodes before linearized reading' (duration: 208.924292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:01:58.746441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.282071ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-31T22:01:58.746473Z","caller":"traceutil/trace.go:171","msg":"trace[577097686] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15655; }","duration":"209.312702ms","start":"2026-07-31T22:01:58.53715Z","end":"2026-07-31T22:01:58.746463Z","steps":["trace[577097686] 'agreement among raft nodes before linearized reading' (duration: 208.699136ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:05:30.119713Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14769} {"level":"info","ts":"2026-07-31T22:05:30.211605Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14769,"took":"89.087396ms","hash":1792664193,"current-db-size-bytes":57696256,"current-db-size":"58 MB","current-db-size-in-use-bytes":30605312,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-31T22:05:30.211682Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1792664193,"revision":14769,"compact-revision":12690} {"level":"info","ts":"2026-07-31T22:08:13.553443Z","caller":"traceutil/trace.go:171","msg":"trace[1126310089] transaction","detail":"{read_only:false; response_revision:18409; number_of_response:1; }","duration":"248.59109ms","start":"2026-07-31T22:08:13.304828Z","end":"2026-07-31T22:08:13.553419Z","steps":["trace[1126310089] 'process raft request' (duration: 248.21082ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:08:13.574934Z","caller":"traceutil/trace.go:171","msg":"trace[62756296] transaction","detail":"{read_only:false; response_revision:18410; number_of_response:1; }","duration":"105.860779ms","start":"2026-07-31T22:08:13.46905Z","end":"2026-07-31T22:08:13.57491Z","steps":["trace[62756296] 'process raft request' (duration: 105.638323ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:08:13.575004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.257078ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-07-31T22:08:13.57514Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.508408ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-07-31T22:08:13.575204Z","caller":"traceutil/trace.go:171","msg":"trace[1047004822] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:18410; }","duration":"100.611021ms","start":"2026-07-31T22:08:13.474579Z","end":"2026-07-31T22:08:13.57519Z","steps":["trace[1047004822] 'agreement among raft nodes before linearized reading' (duration: 100.400695ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:08:13.575271Z","caller":"traceutil/trace.go:171","msg":"trace[276780803] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18410; }","duration":"106.462425ms","start":"2026-07-31T22:08:13.468689Z","end":"2026-07-31T22:08:13.575152Z","steps":["trace[276780803] 'agreement among raft nodes before linearized reading' (duration: 106.154916ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:08:13.686504Z","caller":"traceutil/trace.go:171","msg":"trace[960132077] transaction","detail":"{read_only:false; response_revision:18412; number_of_response:1; }","duration":"107.081891ms","start":"2026-07-31T22:08:13.579397Z","end":"2026-07-31T22:08:13.686479Z","steps":["trace[960132077] 'process raft request' (duration: 106.844734ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:08:13.686801Z","caller":"traceutil/trace.go:171","msg":"trace[863871198] transaction","detail":"{read_only:false; response_revision:18413; number_of_response:1; }","duration":"106.870086ms","start":"2026-07-31T22:08:13.579918Z","end":"2026-07-31T22:08:13.686788Z","steps":["trace[863871198] 'process raft request' (duration: 106.522107ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:08:13.686789Z","caller":"traceutil/trace.go:171","msg":"trace[1903756782] transaction","detail":"{read_only:false; response_revision:18414; number_of_response:1; }","duration":"102.562872ms","start":"2026-07-31T22:08:13.584196Z","end":"2026-07-31T22:08:13.686759Z","steps":["trace[1903756782] 'process raft request' (duration: 102.430179ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:08:23.76053Z","caller":"traceutil/trace.go:171","msg":"trace[424866060] transaction","detail":"{read_only:false; response_revision:18608; number_of_response:1; }","duration":"106.793684ms","start":"2026-07-31T22:08:23.653717Z","end":"2026-07-31T22:08:23.76051Z","steps":["trace[424866060] 'process raft request' (duration: 105.440228ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:09:44.465066Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-31T22:09:44.474865Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-31T22:09:44.475019Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-31T22:10:30.124504Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17337} {"level":"info","ts":"2026-07-31T22:10:30.215394Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17337,"took":"87.874286ms","hash":866942921,"current-db-size-bytes":57696256,"current-db-size":"58 MB","current-db-size-in-use-bytes":34377728,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-07-31T22:10:30.215467Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":866942921,"revision":17337,"compact-revision":14769} {"level":"info","ts":"2026-07-31T22:14:11.84461Z","caller":"traceutil/trace.go:171","msg":"trace[109706180] transaction","detail":"{read_only:false; response_revision:21154; number_of_response:1; }","duration":"373.253783ms","start":"2026-07-31T22:14:11.471329Z","end":"2026-07-31T22:14:11.844582Z","steps":["trace[109706180] 'process raft request' (duration: 373.093909ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:14:11.844817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:14:11.471313Z","time spent":"373.381196ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T22:14:12.323611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"354.103249ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:14:12.323729Z","caller":"traceutil/trace.go:171","msg":"trace[1266641877] linearizableReadLoop","detail":"{readStateIndex:21874; appliedIndex:21872; }","duration":"791.190934ms","start":"2026-07-31T22:14:11.53252Z","end":"2026-07-31T22:14:12.323711Z","steps":["trace[1266641877] 'read index received' (duration: 311.960749ms)","trace[1266641877] 'applied index is now lower than readState.Index' (duration: 479.225455ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:14:12.323843Z","caller":"traceutil/trace.go:171","msg":"trace[1213480305] transaction","detail":"{read_only:false; response_revision:21155; number_of_response:1; }","duration":"851.937554ms","start":"2026-07-31T22:14:11.471859Z","end":"2026-07-31T22:14:12.323797Z","steps":["trace[1213480305] 'process raft request' (duration: 497.517677ms)","trace[1213480305] 'compare' (duration: 353.862393ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:14:12.323953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.425271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-31T22:14:12.323996Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:14:11.471847Z","time spent":"852.069278ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:14:12.324003Z","caller":"traceutil/trace.go:171","msg":"trace[664339490] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:21155; }","duration":"791.476013ms","start":"2026-07-31T22:14:11.532513Z","end":"2026-07-31T22:14:12.323989Z","steps":["trace[664339490] 'agreement among raft nodes before linearized reading' (duration: 791.301757ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:14:12.324065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:14:11.532493Z","time spent":"791.554994ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-31T22:14:12.324056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"791.002769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"warn","ts":"2026-07-31T22:14:12.324167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"574.843014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:14:12.32422Z","caller":"traceutil/trace.go:171","msg":"trace[1900436608] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:21155; }","duration":"574.936256ms","start":"2026-07-31T22:14:11.749269Z","end":"2026-07-31T22:14:12.324206Z","steps":["trace[1900436608] 'agreement among raft nodes before linearized reading' (duration: 574.846084ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:14:12.324247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:14:11.749249Z","time spent":"574.990938ms","remote":"127.0.0.1:37368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true "} {"level":"info","ts":"2026-07-31T22:14:12.324293Z","caller":"traceutil/trace.go:171","msg":"trace[630030929] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:21155; }","duration":"791.187354ms","start":"2026-07-31T22:14:11.532981Z","end":"2026-07-31T22:14:12.324168Z","steps":["trace[630030929] 'agreement among raft nodes before linearized reading' (duration: 790.862735ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:14:12.324405Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:14:11.532973Z","time spent":"791.399069ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-31T22:14:12.590401Z","caller":"traceutil/trace.go:171","msg":"trace[1847046305] transaction","detail":"{read_only:false; response_revision:21158; number_of_response:1; }","duration":"260.077011ms","start":"2026-07-31T22:14:12.3303Z","end":"2026-07-31T22:14:12.590377Z","steps":["trace[1847046305] 'process raft request' (duration: 260.02537ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:14:12.590415Z","caller":"traceutil/trace.go:171","msg":"trace[462780575] transaction","detail":"{read_only:false; response_revision:21157; number_of_response:1; }","duration":"261.268014ms","start":"2026-07-31T22:14:12.329112Z","end":"2026-07-31T22:14:12.59038Z","steps":["trace[462780575] 'process raft request' (duration: 229.722163ms)","trace[462780575] 'compare' (duration: 31.347116ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:14:12.999409Z","caller":"traceutil/trace.go:171","msg":"trace[1120979171] transaction","detail":"{read_only:false; response_revision:21159; number_of_response:1; }","duration":"183.334479ms","start":"2026-07-31T22:14:12.816042Z","end":"2026-07-31T22:14:12.999376Z","steps":["trace[1120979171] 'process raft request' (duration: 183.062823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:14:13.448636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.528161ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-07-31T22:14:13.448722Z","caller":"traceutil/trace.go:171","msg":"trace[1058543046] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:21159; }","duration":"321.681544ms","start":"2026-07-31T22:14:13.12702Z","end":"2026-07-31T22:14:13.448702Z","steps":["trace[1058543046] 'count revisions from in-memory index tree' (duration: 321.382287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:14:13.448768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:14:13.126996Z","time spent":"321.760867ms","remote":"127.0.0.1:40752","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-07-31T22:14:13.752351Z","caller":"traceutil/trace.go:171","msg":"trace[841519901] transaction","detail":"{read_only:false; response_revision:21160; number_of_response:1; }","duration":"317.455964ms","start":"2026-07-31T22:14:13.434877Z","end":"2026-07-31T22:14:13.752333Z","steps":["trace[841519901] 'process raft request' (duration: 317.328261ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:14:13.752464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:14:13.434852Z","time spent":"317.552357ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:14:13.903036Z","caller":"traceutil/trace.go:171","msg":"trace[187125010] transaction","detail":"{read_only:false; response_revision:21162; number_of_response:1; }","duration":"449.770019ms","start":"2026-07-31T22:14:13.453225Z","end":"2026-07-31T22:14:13.902995Z","steps":["trace[187125010] 'process raft request' (duration: 449.636876ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:14:13.903035Z","caller":"traceutil/trace.go:171","msg":"trace[883983308] transaction","detail":"{read_only:false; response_revision:21161; number_of_response:1; }","duration":"450.578082ms","start":"2026-07-31T22:14:13.452403Z","end":"2026-07-31T22:14:13.902982Z","steps":["trace[883983308] 'process raft request' (duration: 438.191985ms)","trace[883983308] 'compare' (duration: 12.13551ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:14:13.903191Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:14:13.453213Z","time spent":"449.895102ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T22:14:13.903344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:14:13.452379Z","time spent":"450.750815ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-31T22:14:14.376522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.209069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-07-31T22:14:14.376897Z","caller":"traceutil/trace.go:171","msg":"trace[293058418] transaction","detail":"{read_only:false; response_revision:21164; number_of_response:1; }","duration":"469.655573ms","start":"2026-07-31T22:14:13.907202Z","end":"2026-07-31T22:14:14.376857Z","steps":["trace[293058418] 'process raft request' (duration: 343.962371ms)","trace[293058418] 'compare' (duration: 124.884321ms)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:14:14.376647Z","caller":"traceutil/trace.go:171","msg":"trace[1419054424] linearizableReadLoop","detail":"{readStateIndex:21883; appliedIndex:21882; }","duration":"393.824006ms","start":"2026-07-31T22:14:13.982797Z","end":"2026-07-31T22:14:14.376621Z","steps":["trace[1419054424] 'read index received' (duration: 268.308029ms)","trace[1419054424] 'applied index is now lower than readState.Index' (duration: 125.514327ms)"],"step_count":2} {"level":"warn","ts":"2026-07-31T22:14:14.377036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"394.237066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-07-31T22:14:14.377065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:14:13.907158Z","time spent":"469.818997ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:14:14.377084Z","caller":"traceutil/trace.go:171","msg":"trace[1526005843] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstores/; range_end:/registry/ceph.rook.io/cephobjectstores0; response_count:0; response_revision:21164; }","duration":"394.324189ms","start":"2026-07-31T22:14:13.982744Z","end":"2026-07-31T22:14:14.377068Z","steps":["trace[1526005843] 'agreement among raft nodes before linearized reading' (duration: 394.215136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:14:14.377132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:14:13.982723Z","time spent":"394.398461ms","remote":"127.0.0.1:60156","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":1,"response size":30,"request content":"key:\"/registry/ceph.rook.io/cephobjectstores/\" range_end:\"/registry/ceph.rook.io/cephobjectstores0\" count_only:true "} {"level":"warn","ts":"2026-07-31T22:14:14.377251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.28426ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-07-31T22:14:14.377308Z","caller":"traceutil/trace.go:171","msg":"trace[1689300422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:21164; }","duration":"276.364361ms","start":"2026-07-31T22:14:14.100928Z","end":"2026-07-31T22:14:14.377292Z","steps":["trace[1689300422] 'agreement among raft nodes before linearized reading' (duration: 276.148325ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:14:14.377518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.34853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.229\" ","response":"range_response_count:1 size:140"} {"level":"info","ts":"2026-07-31T22:14:14.37755Z","caller":"traceutil/trace.go:171","msg":"trace[453861621] range","detail":"{range_begin:/registry/masterleases/199.204.45.229; range_end:; response_count:1; response_revision:21164; }","duration":"268.398901ms","start":"2026-07-31T22:14:14.109142Z","end":"2026-07-31T22:14:14.377541Z","steps":["trace[453861621] 'agreement among raft nodes before linearized reading' (duration: 268.285239ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:15:30.13124Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19651} {"level":"info","ts":"2026-07-31T22:15:30.238524Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19651,"took":"104.884004ms","hash":2599046546,"current-db-size-bytes":57696256,"current-db-size":"58 MB","current-db-size-in-use-bytes":29614080,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-31T22:15:30.238574Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2599046546,"revision":19651,"compact-revision":17337} {"level":"info","ts":"2026-07-31T22:18:14.695173Z","caller":"traceutil/trace.go:171","msg":"trace[1665053525] transaction","detail":"{read_only:false; response_revision:23669; number_of_response:1; }","duration":"242.187026ms","start":"2026-07-31T22:18:14.45296Z","end":"2026-07-31T22:18:14.695147Z","steps":["trace[1665053525] 'process raft request' (duration: 240.129991ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:19:34.073301Z","caller":"traceutil/trace.go:171","msg":"trace[491171466] transaction","detail":"{read_only:false; response_revision:24525; number_of_response:1; }","duration":"141.579713ms","start":"2026-07-31T22:19:33.931685Z","end":"2026-07-31T22:19:34.073265Z","steps":["trace[491171466] 'process raft request' (duration: 141.162243ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:20:30.139132Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21885} {"level":"info","ts":"2026-07-31T22:20:30.247Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21885,"took":"104.528398ms","hash":1520881544,"current-db-size-bytes":57696256,"current-db-size":"58 MB","current-db-size-in-use-bytes":43806720,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-07-31T22:20:30.247069Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1520881544,"revision":21885,"compact-revision":19651} {"level":"info","ts":"2026-07-31T22:23:21.990795Z","caller":"traceutil/trace.go:171","msg":"trace[1748318760] transaction","detail":"{read_only:false; response_revision:26262; number_of_response:1; }","duration":"117.53433ms","start":"2026-07-31T22:23:21.873232Z","end":"2026-07-31T22:23:21.990766Z","steps":["trace[1748318760] 'process raft request' (duration: 113.424661ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:23:21.991204Z","caller":"traceutil/trace.go:171","msg":"trace[1902009283] transaction","detail":"{read_only:false; response_revision:26263; number_of_response:1; }","duration":"115.901206ms","start":"2026-07-31T22:23:21.875275Z","end":"2026-07-31T22:23:21.991176Z","steps":["trace[1902009283] 'process raft request' (duration: 115.450095ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:23:21.991229Z","caller":"traceutil/trace.go:171","msg":"trace[784017520] transaction","detail":"{read_only:false; response_revision:26264; number_of_response:1; }","duration":"113.271276ms","start":"2026-07-31T22:23:21.877912Z","end":"2026-07-31T22:23:21.991183Z","steps":["trace[784017520] 'process raft request' (duration: 113.01082ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:23:36.973927Z","caller":"traceutil/trace.go:171","msg":"trace[395438185] linearizableReadLoop","detail":"{readStateIndex:27232; appliedIndex:27231; }","duration":"350.01159ms","start":"2026-07-31T22:23:36.623887Z","end":"2026-07-31T22:23:36.973898Z","steps":["trace[395438185] 'read index received' (duration: 349.665561ms)","trace[395438185] 'applied index is now lower than readState.Index' (duration: 344.909µs)"],"step_count":2} {"level":"info","ts":"2026-07-31T22:23:36.974075Z","caller":"traceutil/trace.go:171","msg":"trace[1654884291] transaction","detail":"{read_only:false; response_revision:26367; number_of_response:1; }","duration":"483.386936ms","start":"2026-07-31T22:23:36.490654Z","end":"2026-07-31T22:23:36.974041Z","steps":["trace[1654884291] 'process raft request' (duration: 482.872972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:23:36.974587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.673347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"warn","ts":"2026-07-31T22:23:36.974985Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:23:36.490642Z","time spent":"484.251719ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:23:36.975063Z","caller":"traceutil/trace.go:171","msg":"trace[1287334169] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:26367; }","duration":"351.205551ms","start":"2026-07-31T22:23:36.623832Z","end":"2026-07-31T22:23:36.975038Z","steps":["trace[1287334169] 'agreement among raft nodes before linearized reading' (duration: 350.457911ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:23:36.975262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:23:36.623813Z","time spent":"351.422287ms","remote":"127.0.0.1:59556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":169,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"info","ts":"2026-07-31T22:23:36.985834Z","caller":"traceutil/trace.go:171","msg":"trace[354407202] transaction","detail":"{read_only:false; response_revision:26368; number_of_response:1; }","duration":"348.724795ms","start":"2026-07-31T22:23:36.637089Z","end":"2026-07-31T22:23:36.985814Z","steps":["trace[354407202] 'process raft request' (duration: 348.555521ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:23:36.985865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.569964ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12217"} {"level":"warn","ts":"2026-07-31T22:23:36.985893Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"211.439605ms","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:6"} {"level":"info","ts":"2026-07-31T22:23:36.985904Z","caller":"traceutil/trace.go:171","msg":"trace[259890113] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26368; }","duration":"295.612306ms","start":"2026-07-31T22:23:36.690281Z","end":"2026-07-31T22:23:36.985893Z","steps":["trace[259890113] 'agreement among raft nodes before linearized reading' (duration: 295.476202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-31T22:23:36.985919Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-31T22:23:36.637066Z","time spent":"348.815969ms","remote":"127.0.0.1:59622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-31T22:23:36.985935Z","caller":"traceutil/trace.go:171","msg":"trace[64030294] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:26368; }","duration":"211.515689ms","start":"2026-07-31T22:23:36.774405Z","end":"2026-07-31T22:23:36.98592Z","steps":["trace[64030294] 'agreement among raft nodes before linearized reading' (duration: 211.442766ms)"],"step_count":1} {"level":"info","ts":"2026-07-31T22:25:30.145067Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":24995} {"level":"info","ts":"2026-07-31T22:25:30.285494Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":24995,"took":"136.941312ms","hash":2300941167,"current-db-size-bytes":57696256,"current-db-size":"58 MB","current-db-size-in-use-bytes":30720000,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-07-31T22:25:30.285569Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2300941167,"revision":24995,"compact-revision":21885} {"level":"info","ts":"2026-07-31T22:26:30.435404Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006faa.wal"}