{"level":"warn","ts":"2026-04-02T08:47:34.621117Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T08:47:34.621232Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.62: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://162.253.55.62:2380","--initial-cluster=instance=https://162.253.55.62:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.62:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.62:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-04-02T08:47:34.621306Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-02T08:47:34.621319Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-02T08:47:34.621338Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-02T08:47:34.622104Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"]} {"level":"info","ts":"2026-04-02T08:47:34.622181Z","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://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.62:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-04-02T08:47:34.625793Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.425038ms"} {"level":"info","ts":"2026-04-02T08:47:34.631956Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"791a7e25b533cd26","cluster-id":"be484d2748da72cd"} {"level":"info","ts":"2026-04-02T08:47:34.632077Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=()"} {"level":"info","ts":"2026-04-02T08:47:34.632103Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 0"} {"level":"info","ts":"2026-04-02T08:47:34.632125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 791a7e25b533cd26 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-02T08:47:34.632136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became follower at term 1"} {"level":"info","ts":"2026-04-02T08:47:34.632187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"warn","ts":"2026-04-02T08:47:34.637202Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-02T08:47:34.638792Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-02T08:47:34.640022Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-04-02T08:47:34.64208Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"791a7e25b533cd26","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-02T08:47:34.642358Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"791a7e25b533cd26","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-02T08:47:34.642447Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T08:47:34.642635Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T08:47:34.642664Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-04-02T08:47:34.64407Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 switched to configuration voters=(8726425928402717990)"} {"level":"info","ts":"2026-04-02T08:47:34.644661Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","added-peer-id":"791a7e25b533cd26","added-peer-peer-urls":["https://162.253.55.62:2380"]} {"level":"info","ts":"2026-04-02T08:47:34.647229Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-04-02T08:47:34.647328Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-02T08:47:34.647359Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.62:2380"} {"level":"info","ts":"2026-04-02T08:47:34.647554Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"791a7e25b533cd26","initial-advertise-peer-urls":["https://162.253.55.62:2380"],"listen-peer-urls":["https://162.253.55.62:2380"],"advertise-client-urls":["https://162.253.55.62:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.62:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-02T08:47:34.647598Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-02T08:47:35.232708Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 is starting a new election at term 1"} {"level":"info","ts":"2026-04-02T08:47:35.233413Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became pre-candidate at term 1"} {"level":"info","ts":"2026-04-02T08:47:35.233449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgPreVoteResp from 791a7e25b533cd26 at term 1"} {"level":"info","ts":"2026-04-02T08:47:35.233472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became candidate at term 2"} {"level":"info","ts":"2026-04-02T08:47:35.233485Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 received MsgVoteResp from 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-02T08:47:35.23351Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"791a7e25b533cd26 became leader at term 2"} {"level":"info","ts":"2026-04-02T08:47:35.233526Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 791a7e25b533cd26 elected leader 791a7e25b533cd26 at term 2"} {"level":"info","ts":"2026-04-02T08:47:35.234526Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"791a7e25b533cd26","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.62:2379]}","request-path":"/0/members/791a7e25b533cd26/attributes","cluster-id":"be484d2748da72cd","publish-timeout":"7s"} {"level":"info","ts":"2026-04-02T08:47:35.23485Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:47:35.235742Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T08:47:35.236075Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-02T08:47:35.236237Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-02T08:47:35.236251Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-02T08:47:35.236331Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"be484d2748da72cd","local-member-id":"791a7e25b533cd26","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:47:35.236397Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:47:35.236422Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-02T08:47:35.237825Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.62:2379"} {"level":"info","ts":"2026-04-02T08:47:35.238886Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-02T08:47:49.028696Z","caller":"traceutil/trace.go:171","msg":"trace[1931657298] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"145.902201ms","start":"2026-04-02T08:47:48.882769Z","end":"2026-04-02T08:47:49.028671Z","steps":["trace[1931657298] 'process raft request' (duration: 145.750677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:49.315193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.128411ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:47:49.31536Z","caller":"traceutil/trace.go:171","msg":"trace[1159701002] linearizableReadLoop","detail":"{readStateIndex:375; appliedIndex:374; }","duration":"242.352211ms","start":"2026-04-02T08:47:49.072984Z","end":"2026-04-02T08:47:49.315337Z","steps":["trace[1159701002] 'read index received' (duration: 59.17142ms)","trace[1159701002] 'applied index is now lower than readState.Index' (duration: 183.178701ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:47:49.3154Z","caller":"traceutil/trace.go:171","msg":"trace[1161155986] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"257.605619ms","start":"2026-04-02T08:47:49.057767Z","end":"2026-04-02T08:47:49.315373Z","steps":["trace[1161155986] 'process raft request' (duration: 74.369576ms)","trace[1161155986] 'compare' (duration: 181.928236ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:47:49.315449Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.463764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:47:49.315486Z","caller":"traceutil/trace.go:171","msg":"trace[918779455] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:361; }","duration":"242.502065ms","start":"2026-04-02T08:47:49.07297Z","end":"2026-04-02T08:47:49.315472Z","steps":["trace[918779455] 'agreement among raft nodes before linearized reading' (duration: 242.432103ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:47:49.570975Z","caller":"traceutil/trace.go:171","msg":"trace[521466782] linearizableReadLoop","detail":"{readStateIndex:376; appliedIndex:375; }","duration":"159.487551ms","start":"2026-04-02T08:47:49.411454Z","end":"2026-04-02T08:47:49.570941Z","steps":["trace[521466782] 'read index received' (duration: 158.575245ms)","trace[521466782] 'applied index is now lower than readState.Index' (duration: 911.216µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:47:49.57108Z","caller":"traceutil/trace.go:171","msg":"trace[596674318] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"177.770755ms","start":"2026-04-02T08:47:49.393258Z","end":"2026-04-02T08:47:49.571029Z","steps":["trace[596674318] 'process raft request' (duration: 176.795538ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:47:49.571132Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.661486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T08:47:49.57138Z","caller":"traceutil/trace.go:171","msg":"trace[1010674086] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:362; }","duration":"159.926954ms","start":"2026-04-02T08:47:49.411432Z","end":"2026-04-02T08:47:49.571359Z","steps":["trace[1010674086] 'agreement among raft nodes before linearized reading' (duration: 159.634335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:14.937011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.696362ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:48:14.937179Z","caller":"traceutil/trace.go:171","msg":"trace[1455164357] transaction","detail":"{read_only:false; response_revision:560; number_of_response:1; }","duration":"295.433454ms","start":"2026-04-02T08:48:14.641721Z","end":"2026-04-02T08:48:14.937155Z","steps":["trace[1455164357] 'process raft request' (duration: 82.497526ms)","trace[1455164357] 'compare' (duration: 212.408673ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:48:14.941713Z","caller":"traceutil/trace.go:171","msg":"trace[1225034328] transaction","detail":"{read_only:false; response_revision:561; number_of_response:1; }","duration":"147.717908ms","start":"2026-04-02T08:48:14.793973Z","end":"2026-04-02T08:48:14.941691Z","steps":["trace[1225034328] 'process raft request' (duration: 147.349037ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:19.720301Z","caller":"traceutil/trace.go:171","msg":"trace[52612800] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"228.437643ms","start":"2026-04-02T08:48:19.491817Z","end":"2026-04-02T08:48:19.720254Z","steps":["trace[52612800] 'process raft request' (duration: 227.886717ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:48:19.819518Z","caller":"traceutil/trace.go:171","msg":"trace[2032126855] transaction","detail":"{read_only:false; response_revision:593; number_of_response:1; }","duration":"287.357093ms","start":"2026-04-02T08:48:19.532124Z","end":"2026-04-02T08:48:19.819481Z","steps":["trace[2032126855] 'process raft request' (duration: 279.147208ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:20.321753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.278027ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:48:20.322035Z","caller":"traceutil/trace.go:171","msg":"trace[730561969] linearizableReadLoop","detail":"{readStateIndex:618; appliedIndex:616; }","duration":"247.421657ms","start":"2026-04-02T08:48:20.074589Z","end":"2026-04-02T08:48:20.322011Z","steps":["trace[730561969] 'read index received' (duration: 90.687571ms)","trace[730561969] 'applied index is now lower than readState.Index' (duration: 156.733026ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:48:20.322069Z","caller":"traceutil/trace.go:171","msg":"trace[1124587519] transaction","detail":"{read_only:false; response_revision:594; number_of_response:1; }","duration":"497.798869ms","start":"2026-04-02T08:48:19.824212Z","end":"2026-04-02T08:48:20.322011Z","steps":["trace[1124587519] 'process raft request' (duration: 208.12523ms)","trace[1124587519] 'compare' (duration: 289.093992ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:48:20.322156Z","caller":"traceutil/trace.go:171","msg":"trace[911455444] transaction","detail":"{read_only:false; response_revision:595; number_of_response:1; }","duration":"414.16845ms","start":"2026-04-02T08:48:19.907974Z","end":"2026-04-02T08:48:20.322142Z","steps":["trace[911455444] 'process raft request' (duration: 413.949193ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:20.322251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:19.907943Z","time spent":"414.275213ms","remote":"127.0.0.1:50908","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-04-02T08:48:20.322317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:48:19.824181Z","time spent":"498.010744ms","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:48:20.322353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.566936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-04-02T08:48:20.32241Z","caller":"traceutil/trace.go:171","msg":"trace[1449097598] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:595; }","duration":"236.658259ms","start":"2026-04-02T08:48:20.085735Z","end":"2026-04-02T08:48:20.322393Z","steps":["trace[1449097598] 'agreement among raft nodes before linearized reading' (duration: 236.440621ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:20.322619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.003994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:48:20.322726Z","caller":"traceutil/trace.go:171","msg":"trace[1543509524] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:595; }","duration":"248.128607ms","start":"2026-04-02T08:48:20.07457Z","end":"2026-04-02T08:48:20.322698Z","steps":["trace[1543509524] 'agreement among raft nodes before linearized reading' (duration: 247.925772ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:48:34.582866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.90502ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:48:34.583061Z","caller":"traceutil/trace.go:171","msg":"trace[361246462] transaction","detail":"{read_only:false; response_revision:756; number_of_response:1; }","duration":"144.767023ms","start":"2026-04-02T08:48:34.438242Z","end":"2026-04-02T08:48:34.583009Z","steps":["trace[361246462] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/leases/kube-system/plndr-cp-lock; req_size:416; } (duration: 134.752446ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:49:53.888465Z","caller":"traceutil/trace.go:171","msg":"trace[1523793981] transaction","detail":"{read_only:false; response_revision:1841; number_of_response:1; }","duration":"170.292897ms","start":"2026-04-02T08:49:53.718119Z","end":"2026-04-02T08:49:53.888411Z","steps":["trace[1523793981] 'process raft request' (duration: 169.318519ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:50:32.262653Z","caller":"traceutil/trace.go:171","msg":"trace[1534517064] transaction","detail":"{read_only:false; response_revision:2101; number_of_response:1; }","duration":"109.193592ms","start":"2026-04-02T08:50:32.153414Z","end":"2026-04-02T08:50:32.262608Z","steps":["trace[1534517064] 'process raft request' (duration: 108.998146ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:50:32.392765Z","caller":"traceutil/trace.go:171","msg":"trace[4199441] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"124.803783ms","start":"2026-04-02T08:50:32.267923Z","end":"2026-04-02T08:50:32.392727Z","steps":["trace[4199441] 'process raft request' (duration: 70.506257ms)","trace[4199441] 'compare' (duration: 54.135692ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:50:43.969843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"240.959023ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:50:43.96992Z","caller":"traceutil/trace.go:171","msg":"trace[1684266280] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:2162; }","duration":"241.059725ms","start":"2026-04-02T08:50:43.728844Z","end":"2026-04-02T08:50:43.969904Z","steps":["trace[1684266280] 'count revisions from in-memory index tree' (duration: 240.86267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:50:44.10353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.253568ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-02T08:50:44.45004Z","caller":"traceutil/trace.go:171","msg":"trace[1141705723] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"263.701556ms","start":"2026-04-02T08:50:44.186215Z","end":"2026-04-02T08:50:44.449916Z","steps":["trace[1141705723] 'process raft request' (duration: 190.669138ms)","trace[1141705723] 'compare' (duration: 72.884084ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:50:44.458778Z","caller":"traceutil/trace.go:171","msg":"trace[1367129141] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"199.601121ms","start":"2026-04-02T08:50:44.259137Z","end":"2026-04-02T08:50:44.458738Z","steps":["trace[1367129141] 'process raft request' (duration: 199.415096ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:54:42.78952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.861975ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:54:42.789611Z","caller":"traceutil/trace.go:171","msg":"trace[113493056] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:3830; }","duration":"225.994108ms","start":"2026-04-02T08:54:42.563597Z","end":"2026-04-02T08:54:42.789591Z","steps":["trace[113493056] 'range keys from in-memory index tree' (duration: 225.700709ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:55:49.627647Z","caller":"traceutil/trace.go:171","msg":"trace[2105869664] transaction","detail":"{read_only:false; response_revision:4282; number_of_response:1; }","duration":"115.079914ms","start":"2026-04-02T08:55:49.512532Z","end":"2026-04-02T08:55:49.627612Z","steps":["trace[2105869664] 'process raft request' (duration: 71.488292ms)","trace[2105869664] 'compare' (duration: 43.409256ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:55:49.627996Z","caller":"traceutil/trace.go:171","msg":"trace[1082261767] transaction","detail":"{read_only:false; response_revision:4283; number_of_response:1; }","duration":"113.424846ms","start":"2026-04-02T08:55:49.514538Z","end":"2026-04-02T08:55:49.627963Z","steps":["trace[1082261767] 'process raft request' (duration: 113.013194ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:09.429284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.683915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c114c96f-ccca-4d29-b5ec-043a69295086\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-04-02T08:56:09.429395Z","caller":"traceutil/trace.go:171","msg":"trace[603606627] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c114c96f-ccca-4d29-b5ec-043a69295086; range_end:; response_count:1; response_revision:5023; }","duration":"221.83647ms","start":"2026-04-02T08:56:09.207538Z","end":"2026-04-02T08:56:09.429374Z","steps":["trace[603606627] 'range keys from in-memory index tree' (duration: 221.525521ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:11.165445Z","caller":"traceutil/trace.go:171","msg":"trace[454735190] linearizableReadLoop","detail":"{readStateIndex:5257; appliedIndex:5256; }","duration":"111.851872ms","start":"2026-04-02T08:56:11.053564Z","end":"2026-04-02T08:56:11.165416Z","steps":["trace[454735190] 'read index received' (duration: 111.725798ms)","trace[454735190] 'applied index is now lower than readState.Index' (duration: 125.064µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:56:11.165503Z","caller":"traceutil/trace.go:171","msg":"trace[142312224] transaction","detail":"{read_only:false; response_revision:5064; number_of_response:1; }","duration":"124.540255ms","start":"2026-04-02T08:56:11.040936Z","end":"2026-04-02T08:56:11.165477Z","steps":["trace[142312224] 'process raft request' (duration: 124.268828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:11.165657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.065538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-04-02T08:56:11.165702Z","caller":"traceutil/trace.go:171","msg":"trace[283764578] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5064; }","duration":"112.144199ms","start":"2026-04-02T08:56:11.053544Z","end":"2026-04-02T08:56:11.165688Z","steps":["trace[283764578] 'agreement among raft nodes before linearized reading' (duration: 111.981315ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:11.263252Z","caller":"traceutil/trace.go:171","msg":"trace[1929134957] transaction","detail":"{read_only:false; response_revision:5065; number_of_response:1; }","duration":"197.929142ms","start":"2026-04-02T08:56:11.065292Z","end":"2026-04-02T08:56:11.263221Z","steps":["trace[1929134957] 'process raft request' (duration: 197.646074ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:11.273098Z","caller":"traceutil/trace.go:171","msg":"trace[2094074616] transaction","detail":"{read_only:false; response_revision:5066; number_of_response:1; }","duration":"102.888454ms","start":"2026-04-02T08:56:11.170181Z","end":"2026-04-02T08:56:11.273069Z","steps":["trace[2094074616] 'process raft request' (duration: 102.73818ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:27.793107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.816672ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7613"} {"level":"info","ts":"2026-04-02T08:56:27.793296Z","caller":"traceutil/trace.go:171","msg":"trace[1898764443] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5386; }","duration":"111.047669ms","start":"2026-04-02T08:56:27.682201Z","end":"2026-04-02T08:56:27.793249Z","steps":["trace[1898764443] 'range keys from in-memory index tree' (duration: 110.565955ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:28.299643Z","caller":"traceutil/trace.go:171","msg":"trace[1627524572] transaction","detail":"{read_only:false; response_revision:5393; number_of_response:1; }","duration":"126.163523ms","start":"2026-04-02T08:56:28.173458Z","end":"2026-04-02T08:56:28.299622Z","steps":["trace[1627524572] 'process raft request' (duration: 125.985898ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:28.7215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.336779ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T08:56:28.721804Z","caller":"traceutil/trace.go:171","msg":"trace[988676051] transaction","detail":"{read_only:false; response_revision:5397; number_of_response:1; }","duration":"414.908983ms","start":"2026-04-02T08:56:28.306881Z","end":"2026-04-02T08:56:28.72179Z","steps":["trace[988676051] 'process raft request' (duration: 414.755699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:28.721858Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:28.306853Z","time spent":"414.981415ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1676,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:56:28.722037Z","caller":"traceutil/trace.go:171","msg":"trace[1491666222] transaction","detail":"{read_only:false; response_revision:5395; number_of_response:1; }","duration":"416.315103ms","start":"2026-04-02T08:56:28.305676Z","end":"2026-04-02T08:56:28.721991Z","steps":["trace[1491666222] 'process raft request' (duration: 283.417107ms)","trace[1491666222] 'compare' (duration: 132.241177ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:56:28.72206Z","caller":"traceutil/trace.go:171","msg":"trace[945210991] transaction","detail":"{read_only:false; response_revision:5400; number_of_response:1; }","duration":"413.094901ms","start":"2026-04-02T08:56:28.308926Z","end":"2026-04-02T08:56:28.722021Z","steps":["trace[945210991] 'process raft request' (duration: 412.796132ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:28.722136Z","caller":"traceutil/trace.go:171","msg":"trace[2147091405] transaction","detail":"{read_only:false; response_revision:5398; number_of_response:1; }","duration":"415.230032ms","start":"2026-04-02T08:56:28.306892Z","end":"2026-04-02T08:56:28.722122Z","steps":["trace[2147091405] 'process raft request' (duration: 414.778789ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:28.722031Z","caller":"traceutil/trace.go:171","msg":"trace[1682001880] transaction","detail":"{read_only:false; response_revision:5399; number_of_response:1; }","duration":"414.892852ms","start":"2026-04-02T08:56:28.307042Z","end":"2026-04-02T08:56:28.721935Z","steps":["trace[1682001880] 'process raft request' (duration: 414.654895ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:28.72226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:28.305655Z","time spent":"416.491418ms","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:56:28.722249Z","caller":"traceutil/trace.go:171","msg":"trace[742189808] transaction","detail":"{read_only:false; response_revision:5396; number_of_response:1; }","duration":"416.157199ms","start":"2026-04-02T08:56:28.306059Z","end":"2026-04-02T08:56:28.722216Z","steps":["trace[742189808] 'process raft request' (duration: 415.52417ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:28.72228Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:28.308914Z","time spent":"413.238335ms","remote":"127.0.0.1:51008","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3884,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:56:28.722322Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:28.307027Z","time spent":"415.267834ms","remote":"127.0.0.1:50734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1482,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:56:28.72232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:28.306873Z","time spent":"415.313455ms","remote":"127.0.0.1:50824","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1775,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:56:28.72241Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:28.306044Z","time spent":"416.277622ms","remote":"127.0.0.1:50734","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1136,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:56:28.723508Z","caller":"traceutil/trace.go:171","msg":"trace[687986578] transaction","detail":"{read_only:false; response_revision:5401; number_of_response:1; }","duration":"414.530522ms","start":"2026-04-02T08:56:28.308967Z","end":"2026-04-02T08:56:28.723497Z","steps":["trace[687986578] 'process raft request' (duration: 412.785432ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:28.723563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:28.308956Z","time spent":"414.582464ms","remote":"127.0.0.1:50868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1413,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:56:28.723689Z","caller":"traceutil/trace.go:171","msg":"trace[662905247] transaction","detail":"{read_only:false; response_revision:5402; number_of_response:1; }","duration":"414.252564ms","start":"2026-04-02T08:56:28.309406Z","end":"2026-04-02T08:56:28.723659Z","steps":["trace[662905247] 'process raft request' (duration: 413.883564ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:28.723832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:28.309399Z","time spent":"414.358327ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3750,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:56:28.762225Z","caller":"traceutil/trace.go:171","msg":"trace[1858722332] linearizableReadLoop","detail":"{readStateIndex:5602; appliedIndex:5593; }","duration":"348.419333ms","start":"2026-04-02T08:56:28.41378Z","end":"2026-04-02T08:56:28.7622Z","steps":["trace[1858722332] 'read index received' (duration: 175.267122ms)","trace[1858722332] 'applied index is now lower than readState.Index' (duration: 173.151131ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:56:28.762346Z","caller":"traceutil/trace.go:171","msg":"trace[1759743678] transaction","detail":"{read_only:false; response_revision:5403; number_of_response:1; }","duration":"382.864792ms","start":"2026-04-02T08:56:28.379472Z","end":"2026-04-02T08:56:28.762336Z","steps":["trace[1759743678] 'process raft request' (duration: 382.597035ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:28.762474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:28.379447Z","time spent":"382.923894ms","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T08:56:28.762849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.624886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18a27e67b3f15d18\" ","response":"range_response_count:1 size:1012"} {"level":"warn","ts":"2026-04-02T08:56:28.762968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.195306ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.62\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-04-02T08:56:28.762998Z","caller":"traceutil/trace.go:171","msg":"trace[229934782] range","detail":"{range_begin:/registry/masterleases/162.253.55.62; range_end:; response_count:1; response_revision:5403; }","duration":"349.264828ms","start":"2026-04-02T08:56:28.413725Z","end":"2026-04-02T08:56:28.76299Z","steps":["trace[229934782] 'agreement among raft nodes before linearized reading' (duration: 349.148595ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:28.763026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:28.413704Z","time spent":"349.315639ms","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":156,"request content":"key:\"/registry/masterleases/162.253.55.62\" "} {"level":"warn","ts":"2026-04-02T08:56:28.763283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.760502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-02T08:56:28.762934Z","caller":"traceutil/trace.go:171","msg":"trace[524938203] range","detail":"{range_begin:/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18a27e67b3f15d18; range_end:; response_count:1; response_revision:5403; }","duration":"327.74795ms","start":"2026-04-02T08:56:28.435166Z","end":"2026-04-02T08:56:28.762914Z","steps":["trace[524938203] 'agreement among raft nodes before linearized reading' (duration: 327.555134ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:28.763395Z","caller":"traceutil/trace.go:171","msg":"trace[1127384744] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5403; }","duration":"194.927956ms","start":"2026-04-02T08:56:28.568442Z","end":"2026-04-02T08:56:28.76337Z","steps":["trace[1127384744] 'agreement among raft nodes before linearized reading' (duration: 194.480343ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:28.763884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:28.435149Z","time spent":"328.240254ms","remote":"127.0.0.1:50660","response type":"/etcdserverpb.KV/Range","request count":0,"request size":84,"response count":1,"response size":1035,"request content":"key:\"/registry/events/openstack/persistence-rabbitmq-keystone-server-0.18a27e67b3f15d18\" "} {"level":"warn","ts":"2026-04-02T08:56:30.592138Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.931291ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/local-path-storage/helper-pod-create-pvc-b7f5bd5f-c634-46c4-9ab5-57994e42efc3\" ","response":"range_response_count:1 size:2243"} {"level":"info","ts":"2026-04-02T08:56:30.592278Z","caller":"traceutil/trace.go:171","msg":"trace[1377062445] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/local-path-storage/helper-pod-create-pvc-b7f5bd5f-c634-46c4-9ab5-57994e42efc3; range_end:; response_count:1; response_revision:5424; }","duration":"114.139208ms","start":"2026-04-02T08:56:30.478109Z","end":"2026-04-02T08:56:30.592249Z","steps":["trace[1377062445] 'range keys from in-memory index tree' (duration: 113.642273ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:30.874511Z","caller":"traceutil/trace.go:171","msg":"trace[815224917] transaction","detail":"{read_only:false; response_revision:5425; number_of_response:1; }","duration":"318.189946ms","start":"2026-04-02T08:56:30.556276Z","end":"2026-04-02T08:56:30.874466Z","steps":["trace[815224917] 'process raft request' (duration: 317.97831ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:30.874803Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:30.556258Z","time spent":"318.374341ms","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T08:56:31.063855Z","caller":"traceutil/trace.go:171","msg":"trace[1803416200] transaction","detail":"{read_only:false; number_of_response:1; response_revision:5426; }","duration":"468.901483ms","start":"2026-04-02T08:56:30.594868Z","end":"2026-04-02T08:56:31.063769Z","steps":["trace[1803416200] 'process raft request' (duration: 463.823498ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:31.063972Z","caller":"traceutil/trace.go:171","msg":"trace[761620637] transaction","detail":"{read_only:false; response_revision:5427; number_of_response:1; }","duration":"295.310468ms","start":"2026-04-02T08:56:30.768627Z","end":"2026-04-02T08:56:31.063938Z","steps":["trace[761620637] 'process raft request' (duration: 294.946288ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:31.064064Z","caller":"traceutil/trace.go:171","msg":"trace[683137334] linearizableReadLoop","detail":"{readStateIndex:5626; appliedIndex:5625; }","duration":"418.075813ms","start":"2026-04-02T08:56:30.64566Z","end":"2026-04-02T08:56:31.063736Z","steps":["trace[683137334] 'read index received' (duration: 228.918922ms)","trace[683137334] 'applied index is now lower than readState.Index' (duration: 189.155121ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:56:31.06408Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:30.59484Z","time spent":"469.096618ms","remote":"127.0.0.1:60854","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":117,"response count":0,"response size":41,"request content":"compare: success: > failure: >"} {"level":"warn","ts":"2026-04-02T08:56:31.064179Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.509167ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T08:56:31.064238Z","caller":"traceutil/trace.go:171","msg":"trace[1640232216] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:5427; }","duration":"418.61953ms","start":"2026-04-02T08:56:30.645603Z","end":"2026-04-02T08:56:31.064223Z","steps":["trace[1640232216] 'agreement among raft nodes before linearized reading' (duration: 418.414073ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:31.06427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:30.645581Z","time spent":"418.679512ms","remote":"127.0.0.1:51016","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":21,"response size":30,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-04-02T08:56:31.064292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.62639ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T08:56:31.064354Z","caller":"traceutil/trace.go:171","msg":"trace[821804835] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:5427; }","duration":"255.771754ms","start":"2026-04-02T08:56:30.808569Z","end":"2026-04-02T08:56:31.064341Z","steps":["trace[821804835] 'agreement among raft nodes before linearized reading' (duration: 255.64677ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:31.064342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.745866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/topicpermissions/\" range_end:\"/registry/rabbitmq.com/topicpermissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T08:56:31.064392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.651774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ct9bf\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-02T08:56:31.064408Z","caller":"traceutil/trace.go:171","msg":"trace[993167069] range","detail":"{range_begin:/registry/rabbitmq.com/topicpermissions/; range_end:/registry/rabbitmq.com/topicpermissions0; response_count:0; response_revision:5427; }","duration":"121.870119ms","start":"2026-04-02T08:56:30.942518Z","end":"2026-04-02T08:56:31.064388Z","steps":["trace[993167069] 'agreement among raft nodes before linearized reading' (duration: 121.749506ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:31.064458Z","caller":"traceutil/trace.go:171","msg":"trace[641770696] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ct9bf; range_end:; response_count:1; response_revision:5427; }","duration":"245.773667ms","start":"2026-04-02T08:56:30.818668Z","end":"2026-04-02T08:56:31.064442Z","steps":["trace[641770696] 'agreement among raft nodes before linearized reading' (duration: 245.54283ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:31.064962Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.460048ms","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":"warn","ts":"2026-04-02T08:56:31.064981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.983823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-04-02T08:56:31.065027Z","caller":"traceutil/trace.go:171","msg":"trace[1201007131] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5427; }","duration":"252.550401ms","start":"2026-04-02T08:56:30.812462Z","end":"2026-04-02T08:56:31.065012Z","steps":["trace[1201007131] 'agreement among raft nodes before linearized reading' (duration: 252.252473ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:31.065037Z","caller":"traceutil/trace.go:171","msg":"trace[1211474923] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5427; }","duration":"339.043374ms","start":"2026-04-02T08:56:30.725979Z","end":"2026-04-02T08:56:31.065022Z","steps":["trace[1211474923] 'agreement among raft nodes before linearized reading' (duration: 338.209361ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T08:56:31.065077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T08:56:30.725823Z","time spent":"339.24381ms","remote":"127.0.0.1:50818","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":"info","ts":"2026-04-02T08:56:36.270451Z","caller":"traceutil/trace.go:171","msg":"trace[1447670295] transaction","detail":"{read_only:false; response_revision:5519; number_of_response:1; }","duration":"154.576548ms","start":"2026-04-02T08:56:36.115848Z","end":"2026-04-02T08:56:36.270424Z","steps":["trace[1447670295] 'process raft request' (duration: 154.332931ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:36.437877Z","caller":"traceutil/trace.go:171","msg":"trace[842797220] transaction","detail":"{read_only:false; response_revision:5521; number_of_response:1; }","duration":"108.439493ms","start":"2026-04-02T08:56:36.329394Z","end":"2026-04-02T08:56:36.437834Z","steps":["trace[842797220] 'process raft request' (duration: 96.894022ms)","trace[842797220] 'compare' (duration: 10.994956ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T08:56:41.720293Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.007825ms","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-04-02T08:56:41.720397Z","caller":"traceutil/trace.go:171","msg":"trace[279034675] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5576; }","duration":"237.168659ms","start":"2026-04-02T08:56:41.483208Z","end":"2026-04-02T08:56:41.720376Z","steps":["trace[279034675] 'range keys from in-memory index tree' (duration: 236.741986ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:41.853856Z","caller":"traceutil/trace.go:171","msg":"trace[1233611594] transaction","detail":"{read_only:false; response_revision:5577; number_of_response:1; }","duration":"128.683315ms","start":"2026-04-02T08:56:41.725146Z","end":"2026-04-02T08:56:41.85383Z","steps":["trace[1233611594] 'process raft request' (duration: 128.51097ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:56:41.971625Z","caller":"traceutil/trace.go:171","msg":"trace[1960410461] transaction","detail":"{read_only:false; response_revision:5578; number_of_response:1; }","duration":"116.475184ms","start":"2026-04-02T08:56:41.85512Z","end":"2026-04-02T08:56:41.971595Z","steps":["trace[1960410461] 'process raft request' (duration: 68.288981ms)","trace[1960410461] 'compare' (duration: 48.002548ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T08:56:42.395237Z","caller":"traceutil/trace.go:171","msg":"trace[1961834895] transaction","detail":"{read_only:false; response_revision:5584; number_of_response:1; }","duration":"101.908835ms","start":"2026-04-02T08:56:42.293217Z","end":"2026-04-02T08:56:42.395126Z","steps":["trace[1961834895] 'process raft request' (duration: 101.498283ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:20.201833Z","caller":"traceutil/trace.go:171","msg":"trace[2125581240] transaction","detail":"{read_only:false; response_revision:5911; number_of_response:1; }","duration":"114.834227ms","start":"2026-04-02T08:57:20.086977Z","end":"2026-04-02T08:57:20.201811Z","steps":["trace[2125581240] 'process raft request' (duration: 114.709714ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:35.28274Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2964} {"level":"info","ts":"2026-04-02T08:57:35.496845Z","caller":"traceutil/trace.go:171","msg":"trace[489576801] transaction","detail":"{read_only:false; response_revision:6026; number_of_response:1; }","duration":"100.374872ms","start":"2026-04-02T08:57:35.396435Z","end":"2026-04-02T08:57:35.49681Z","steps":["trace[489576801] 'process raft request' (duration: 94.595786ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T08:57:35.537302Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2964,"took":"252.89082ms","hash":2289291320,"current-db-size-bytes":58425344,"current-db-size":"58 MB","current-db-size-in-use-bytes":33431552,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-02T08:57:35.537405Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2289291320,"revision":2964,"compact-revision":-1} {"level":"warn","ts":"2026-04-02T09:00:08.412398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.440552ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-02T09:00:08.412535Z","caller":"traceutil/trace.go:171","msg":"trace[851397111] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7042; }","duration":"119.657199ms","start":"2026-04-02T09:00:08.29285Z","end":"2026-04-02T09:00:08.412507Z","steps":["trace[851397111] 'range keys from in-memory index tree' (duration: 119.284688ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:02:01.509681Z","caller":"traceutil/trace.go:171","msg":"trace[477729886] linearizableReadLoop","detail":"{readStateIndex:8136; appliedIndex:8135; }","duration":"465.200662ms","start":"2026-04-02T09:02:01.044454Z","end":"2026-04-02T09:02:01.509654Z","steps":["trace[477729886] 'read index received' (duration: 465.13871ms)","trace[477729886] 'applied index is now lower than readState.Index' (duration: 60.612µs)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:02:01.509803Z","caller":"traceutil/trace.go:171","msg":"trace[1126356648] transaction","detail":"{read_only:false; response_revision:7857; number_of_response:1; }","duration":"479.735625ms","start":"2026-04-02T09:02:01.029992Z","end":"2026-04-02T09:02:01.509728Z","steps":["trace[1126356648] 'process raft request' (duration: 479.497198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:01.51011Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"465.600284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-04-02T09:02:01.510243Z","caller":"traceutil/trace.go:171","msg":"trace[978728690] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7857; }","duration":"465.82297ms","start":"2026-04-02T09:02:01.044393Z","end":"2026-04-02T09:02:01.510216Z","steps":["trace[978728690] 'agreement among raft nodes before linearized reading' (duration: 465.390118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:01.510303Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:01.044371Z","time spent":"465.920463ms","remote":"127.0.0.1:50818","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-04-02T09:02:01.510329Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:01.029922Z","time spent":"480.079765ms","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:02:01.514906Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.939303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T09:02:01.514961Z","caller":"traceutil/trace.go:171","msg":"trace[1937818893] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:7859; }","duration":"333.027575ms","start":"2026-04-02T09:02:01.181923Z","end":"2026-04-02T09:02:01.51495Z","steps":["trace[1937818893] 'agreement among raft nodes before linearized reading' (duration: 332.916122ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:01.514987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:01.181907Z","time spent":"333.072697ms","remote":"127.0.0.1:60870","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":28,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-04-02T09:02:01.515078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.169075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/probes/\" range_end:\"/registry/monitoring.coreos.com/probes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T09:02:01.515206Z","caller":"traceutil/trace.go:171","msg":"trace[685078983] range","detail":"{range_begin:/registry/monitoring.coreos.com/probes/; range_end:/registry/monitoring.coreos.com/probes0; response_count:0; response_revision:7859; }","duration":"114.3402ms","start":"2026-04-02T09:02:01.400841Z","end":"2026-04-02T09:02:01.515182Z","steps":["trace[685078983] 'agreement among raft nodes before linearized reading' (duration: 114.028291ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:01.515277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.633913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T09:02:01.515254Z","caller":"traceutil/trace.go:171","msg":"trace[131696753] transaction","detail":"{read_only:false; response_revision:7859; number_of_response:1; }","duration":"170.844756ms","start":"2026-04-02T09:02:01.344384Z","end":"2026-04-02T09:02:01.515229Z","steps":["trace[131696753] 'process raft request' (duration: 170.417954ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:02:01.515317Z","caller":"traceutil/trace.go:171","msg":"trace[481775040] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:0; response_revision:7859; }","duration":"143.725064ms","start":"2026-04-02T09:02:01.371581Z","end":"2026-04-02T09:02:01.515306Z","steps":["trace[481775040] 'agreement among raft nodes before linearized reading' (duration: 143.673873ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:01.515472Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"327.496429ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T09:02:01.515475Z","caller":"traceutil/trace.go:171","msg":"trace[1512565705] transaction","detail":"{read_only:false; response_revision:7858; number_of_response:1; }","duration":"184.663419ms","start":"2026-04-02T09:02:01.330775Z","end":"2026-04-02T09:02:01.515439Z","steps":["trace[1512565705] 'process raft request' (duration: 183.904568ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:02:01.515498Z","caller":"traceutil/trace.go:171","msg":"trace[528331637] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7859; }","duration":"327.52113ms","start":"2026-04-02T09:02:01.18797Z","end":"2026-04-02T09:02:01.515491Z","steps":["trace[528331637] 'agreement among raft nodes before linearized reading' (duration: 327.458888ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:01.515521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:01.187955Z","time spent":"327.56226ms","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-04-02T09:02:01.515239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.415832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" ","response":"range_response_count:1 size:12707"} {"level":"info","ts":"2026-04-02T09:02:01.515562Z","caller":"traceutil/trace.go:171","msg":"trace[208121067] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-endpoints; range_end:; response_count:1; response_revision:7859; }","duration":"139.781943ms","start":"2026-04-02T09:02:01.375767Z","end":"2026-04-02T09:02:01.515549Z","steps":["trace[208121067] 'agreement among raft nodes before linearized reading' (duration: 139.405383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:01.51561Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.158469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ct9bf\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-02T09:02:01.515634Z","caller":"traceutil/trace.go:171","msg":"trace[1595308040] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ct9bf; range_end:; response_count:1; response_revision:7859; }","duration":"333.19204ms","start":"2026-04-02T09:02:01.182435Z","end":"2026-04-02T09:02:01.515627Z","steps":["trace[1595308040] 'agreement among raft nodes before linearized reading' (duration: 333.128299ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:01.515652Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:01.18243Z","time spent":"333.219211ms","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-ct9bf\" "} {"level":"warn","ts":"2026-04-02T09:02:03.755088Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675782545125605,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T09:02:04.255513Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675782545125605,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T09:02:04.755719Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675782545125605,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T09:02:05.25592Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675782545125605,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T09:02:05.261238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000113259s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-04-02T09:02:05.261339Z","caller":"traceutil/trace.go:171","msg":"trace[1067400732] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000265354s","start":"2026-04-02T09:02:03.261045Z","end":"2026-04-02T09:02:05.26131Z","steps":["trace[1067400732] 'agreement among raft nodes before linearized reading' (duration: 2.00012279s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.26141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.261037Z","time spent":"2.000359726s","remote":"127.0.0.1:50586","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-04-02T09:02:05.425966Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"2.176539273s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T09:02:05.43305Z","caller":"traceutil/trace.go:171","msg":"trace[867988943] transaction","detail":"{read_only:false; response_revision:7865; number_of_response:1; }","duration":"2.183685357s","start":"2026-04-02T09:02:03.24933Z","end":"2026-04-02T09:02:05.433015Z","steps":["trace[867988943] 'process raft request' (duration: 2.177013637s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.433248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.24929Z","time spent":"2.183852061s","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T09:02:05.437577Z","caller":"traceutil/trace.go:171","msg":"trace[561108811] transaction","detail":"{read_only:false; response_revision:7866; number_of_response:1; }","duration":"1.915354199s","start":"2026-04-02T09:02:03.5222Z","end":"2026-04-02T09:02:05.437554Z","steps":["trace[561108811] 'process raft request' (duration: 1.915252767s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.437673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.52218Z","time spent":"1.915430812s","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T09:02:05.437734Z","caller":"traceutil/trace.go:171","msg":"trace[1750857876] transaction","detail":"{read_only:false; response_revision:7867; number_of_response:1; }","duration":"1.91430689s","start":"2026-04-02T09:02:03.523406Z","end":"2026-04-02T09:02:05.437713Z","steps":["trace[1750857876] 'process raft request' (duration: 1.914123315s)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:02:05.438005Z","caller":"traceutil/trace.go:171","msg":"trace[1084049530] transaction","detail":"{read_only:false; response_revision:7868; number_of_response:1; }","duration":"1.742217119s","start":"2026-04-02T09:02:03.69578Z","end":"2026-04-02T09:02:05.437997Z","steps":["trace[1084049530] 'process raft request' (duration: 1.741897199s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.438087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.523394Z","time spent":"1.914368872s","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:02:05.438102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.695752Z","time spent":"1.742275641s","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T09:02:05.439799Z","caller":"traceutil/trace.go:171","msg":"trace[757698958] transaction","detail":"{read_only:false; response_revision:7869; number_of_response:1; }","duration":"1.300535145s","start":"2026-04-02T09:02:04.139255Z","end":"2026-04-02T09:02:05.43979Z","steps":["trace[757698958] 'process raft request' (duration: 1.300447103s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.439854Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:04.139235Z","time spent":"1.300589986s","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":473,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:02:05.439889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.185272052s","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-04-02T09:02:05.439914Z","caller":"traceutil/trace.go:171","msg":"trace[1984133114] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:7870; }","duration":"2.185321453s","start":"2026-04-02T09:02:03.254585Z","end":"2026-04-02T09:02:05.439906Z","steps":["trace[1984133114] 'agreement among raft nodes before linearized reading' (duration: 2.185237481s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.439937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.254574Z","time spent":"2.185358534s","remote":"127.0.0.1:50996","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-02T09:02:05.439804Z","caller":"traceutil/trace.go:171","msg":"trace[471472242] linearizableReadLoop","detail":"{readStateIndex:8150; appliedIndex:8143; }","duration":"2.185178299s","start":"2026-04-02T09:02:03.254613Z","end":"2026-04-02T09:02:05.439791Z","steps":["trace[471472242] 'read index received' (duration: 2.171591163s)","trace[471472242] 'applied index is now lower than readState.Index' (duration: 13.586026ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:02:05.440116Z","caller":"traceutil/trace.go:171","msg":"trace[1281996562] transaction","detail":"{read_only:false; response_revision:7870; number_of_response:1; }","duration":"220.496336ms","start":"2026-04-02T09:02:05.219613Z","end":"2026-04-02T09:02:05.44011Z","steps":["trace[1281996562] 'process raft request' (duration: 220.144236ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.440193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.185537789s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T09:02:05.440221Z","caller":"traceutil/trace.go:171","msg":"trace[672452112] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:7870; }","duration":"2.185593301s","start":"2026-04-02T09:02:03.254619Z","end":"2026-04-02T09:02:05.440212Z","steps":["trace[672452112] 'agreement among raft nodes before linearized reading' (duration: 2.185537409s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.440243Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.254602Z","time spent":"2.185636213s","remote":"127.0.0.1:42720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true "} {"level":"warn","ts":"2026-04-02T09:02:05.440347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.508232479s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T09:02:05.44038Z","caller":"traceutil/trace.go:171","msg":"trace[2055207097] range","detail":"{range_begin:/registry/cilium.io/ciliumidentities/; range_end:/registry/cilium.io/ciliumidentities0; response_count:0; response_revision:7870; }","duration":"1.508258509s","start":"2026-04-02T09:02:03.932106Z","end":"2026-04-02T09:02:05.440365Z","steps":["trace[2055207097] 'agreement among raft nodes before linearized reading' (duration: 1.508178627s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.440401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.932058Z","time spent":"1.508337951s","remote":"127.0.0.1:60882","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":53,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumidentities/\" range_end:\"/registry/cilium.io/ciliumidentities0\" count_only:true "} {"level":"warn","ts":"2026-04-02T09:02:05.440533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.234196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T09:02:05.440556Z","caller":"traceutil/trace.go:171","msg":"trace[353890070] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7870; }","duration":"178.302198ms","start":"2026-04-02T09:02:05.262249Z","end":"2026-04-02T09:02:05.440552Z","steps":["trace[353890070] 'agreement among raft nodes before linearized reading' (duration: 178.269467ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.440665Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.918501238s","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":"warn","ts":"2026-04-02T09:02:05.440685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.079889543s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T09:02:05.440696Z","caller":"traceutil/trace.go:171","msg":"trace[1636334310] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:7870; }","duration":"1.91855763s","start":"2026-04-02T09:02:03.522131Z","end":"2026-04-02T09:02:05.440689Z","steps":["trace[1636334310] 'agreement among raft nodes before linearized reading' (duration: 1.918384085s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.440746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.522118Z","time spent":"1.918623643s","remote":"127.0.0.1:50818","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-04-02T09:02:05.440793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.284746147s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-04-02T09:02:05.440814Z","caller":"traceutil/trace.go:171","msg":"trace[783235671] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:7870; }","duration":"1.284790208s","start":"2026-04-02T09:02:04.156019Z","end":"2026-04-02T09:02:05.440809Z","steps":["trace[783235671] 'agreement among raft nodes before linearized reading' (duration: 1.284692285s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.44083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:04.156008Z","time spent":"1.284819029s","remote":"127.0.0.1:50818","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-04-02T09:02:05.440856Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.742622962s","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-04-02T09:02:05.440881Z","caller":"traceutil/trace.go:171","msg":"trace[493883077] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:7870; }","duration":"1.742685954s","start":"2026-04-02T09:02:03.698189Z","end":"2026-04-02T09:02:05.440875Z","steps":["trace[493883077] 'agreement among raft nodes before linearized reading' (duration: 1.742171688s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.440917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.698173Z","time spent":"1.742722754s","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-04-02T09:02:05.440935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.915024451s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-04-02T09:02:05.440992Z","caller":"traceutil/trace.go:171","msg":"trace[694521590] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7870; }","duration":"1.915071353s","start":"2026-04-02T09:02:03.525884Z","end":"2026-04-02T09:02:05.440955Z","steps":["trace[694521590] 'agreement among raft nodes before linearized reading' (duration: 1.91496794s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.441017Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.525874Z","time spent":"1.915136684s","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-02T09:02:05.440701Z","caller":"traceutil/trace.go:171","msg":"trace[1812082593] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:7870; }","duration":"1.079966686s","start":"2026-04-02T09:02:04.360729Z","end":"2026-04-02T09:02:05.440696Z","steps":["trace[1812082593] 'agreement among raft nodes before linearized reading' (duration: 1.079901844s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.441142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:04.360699Z","time spent":"1.0804361s","remote":"127.0.0.1:50734","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":53,"response size":30,"request content":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true "} {"level":"warn","ts":"2026-04-02T09:02:05.441151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.91534025s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ct9bf\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-02T09:02:05.44121Z","caller":"traceutil/trace.go:171","msg":"trace[499985224] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ct9bf; range_end:; response_count:1; response_revision:7870; }","duration":"1.915369351s","start":"2026-04-02T09:02:03.525795Z","end":"2026-04-02T09:02:05.441164Z","steps":["trace[499985224] 'agreement among raft nodes before linearized reading' (duration: 1.915282788s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.441236Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.525789Z","time spent":"1.915439652s","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-ct9bf\" "} {"level":"warn","ts":"2026-04-02T09:02:05.441277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.918584412s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" ","response":"range_response_count:1 size:12707"} {"level":"info","ts":"2026-04-02T09:02:05.441299Z","caller":"traceutil/trace.go:171","msg":"trace[176628564] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-endpoints; range_end:; response_count:1; response_revision:7870; }","duration":"1.918621643s","start":"2026-04-02T09:02:03.522671Z","end":"2026-04-02T09:02:05.441293Z","steps":["trace[176628564] 'agreement among raft nodes before linearized reading' (duration: 1.918554951s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.441318Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.522664Z","time spent":"1.918650574s","remote":"127.0.0.1:50800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":1,"response size":12730,"request content":"key:\"/registry/jobs/openstack/barbican-ks-endpoints\" "} {"level":"warn","ts":"2026-04-02T09:02:05.441535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.920033184s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/barbican-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T09:02:05.441563Z","caller":"traceutil/trace.go:171","msg":"trace[139154881] range","detail":"{range_begin:/registry/jobs/openstack/barbican-ks-user; range_end:; response_count:0; response_revision:7870; }","duration":"1.920084815s","start":"2026-04-02T09:02:03.521472Z","end":"2026-04-02T09:02:05.441557Z","steps":["trace[139154881] 'agreement among raft nodes before linearized reading' (duration: 1.920041673s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.441581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.521459Z","time spent":"1.920118956s","remote":"127.0.0.1:50800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":0,"response size":28,"request content":"key:\"/registry/jobs/openstack/barbican-ks-user\" "} {"level":"warn","ts":"2026-04-02T09:02:05.441619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.925197209s","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-04-02T09:02:05.441621Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.002751044s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T09:02:05.441642Z","caller":"traceutil/trace.go:171","msg":"trace[2037178478] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:7870; }","duration":"1.925263021s","start":"2026-04-02T09:02:03.516374Z","end":"2026-04-02T09:02:05.441637Z","steps":["trace[2037178478] 'agreement among raft nodes before linearized reading' (duration: 1.925207999s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.441664Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.516353Z","time spent":"1.925304483s","remote":"127.0.0.1:50818","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":"info","ts":"2026-04-02T09:02:05.441687Z","caller":"traceutil/trace.go:171","msg":"trace[669114683] range","detail":"{range_begin:/registry/runtimeclasses/; range_end:/registry/runtimeclasses0; response_count:0; response_revision:7870; }","duration":"2.002820806s","start":"2026-04-02T09:02:03.43882Z","end":"2026-04-02T09:02:05.441641Z","steps":["trace[669114683] 'agreement among raft nodes before linearized reading' (duration: 2.002776905s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:02:05.441777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:02:03.438799Z","time spent":"2.00294655s","remote":"127.0.0.1:50862","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":28,"request content":"key:\"/registry/runtimeclasses/\" range_end:\"/registry/runtimeclasses0\" count_only:true "} {"level":"info","ts":"2026-04-02T09:02:35.290631Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6024} {"level":"info","ts":"2026-04-02T09:02:35.409343Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6024,"took":"117.13911ms","hash":3039374874,"current-db-size-bytes":58425344,"current-db-size":"58 MB","current-db-size-in-use-bytes":21323776,"current-db-size-in-use":"21 MB"} {"level":"info","ts":"2026-04-02T09:02:35.409413Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3039374874,"revision":6024,"compact-revision":2964} {"level":"info","ts":"2026-04-02T09:03:00.732309Z","caller":"traceutil/trace.go:171","msg":"trace[702171075] transaction","detail":"{read_only:false; response_revision:8367; number_of_response:1; }","duration":"112.461157ms","start":"2026-04-02T09:03:00.619821Z","end":"2026-04-02T09:03:00.732282Z","steps":["trace[702171075] 'process raft request' (duration: 112.270261ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:03:13.846157Z","caller":"traceutil/trace.go:171","msg":"trace[660193547] transaction","detail":"{read_only:false; response_revision:8432; number_of_response:1; }","duration":"100.262118ms","start":"2026-04-02T09:03:13.745843Z","end":"2026-04-02T09:03:13.846105Z","steps":["trace[660193547] 'process raft request' (duration: 55.710973ms)","trace[660193547] 'compare' (duration: 44.240756ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:03:13.954329Z","caller":"traceutil/trace.go:171","msg":"trace[1719379113] transaction","detail":"{read_only:false; response_revision:8435; number_of_response:1; }","duration":"103.090979ms","start":"2026-04-02T09:03:13.851205Z","end":"2026-04-02T09:03:13.954296Z","steps":["trace[1719379113] 'process raft request' (duration: 102.860591ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:03:40.275558Z","caller":"traceutil/trace.go:171","msg":"trace[2094992342] linearizableReadLoop","detail":"{readStateIndex:8938; appliedIndex:8937; }","duration":"110.430106ms","start":"2026-04-02T09:03:40.165093Z","end":"2026-04-02T09:03:40.275523Z","steps":["trace[2094992342] 'read index received' (duration: 15.545311ms)","trace[2094992342] 'applied index is now lower than readState.Index' (duration: 94.883565ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:03:40.275734Z","caller":"traceutil/trace.go:171","msg":"trace[33518594] transaction","detail":"{read_only:false; response_revision:8632; number_of_response:1; }","duration":"115.670905ms","start":"2026-04-02T09:03:40.160044Z","end":"2026-04-02T09:03:40.275714Z","steps":["trace[33518594] 'process raft request' (duration: 20.550643ms)","trace[33518594] 'compare' (duration: 94.721231ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T09:03:40.275957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.849218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rook-ceph-detect-version-w7tt9\" ","response":"range_response_count:1 size:4454"} {"level":"info","ts":"2026-04-02T09:03:40.276017Z","caller":"traceutil/trace.go:171","msg":"trace[1299993495] range","detail":"{range_begin:/registry/pods/openstack/rook-ceph-detect-version-w7tt9; range_end:; response_count:1; response_revision:8632; }","duration":"110.92356ms","start":"2026-04-02T09:03:40.16508Z","end":"2026-04-02T09:03:40.276004Z","steps":["trace[1299993495] 'agreement among raft nodes before linearized reading' (duration: 110.745135ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:03:43.973016Z","caller":"traceutil/trace.go:171","msg":"trace[852418687] transaction","detail":"{read_only:false; response_revision:8649; number_of_response:1; }","duration":"110.720105ms","start":"2026-04-02T09:03:43.86226Z","end":"2026-04-02T09:03:43.97298Z","steps":["trace[852418687] 'process raft request' (duration: 110.462408ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:03:58.089656Z","caller":"traceutil/trace.go:171","msg":"trace[1875582032] transaction","detail":"{read_only:false; response_revision:8765; number_of_response:1; }","duration":"108.679377ms","start":"2026-04-02T09:03:57.98094Z","end":"2026-04-02T09:03:58.08962Z","steps":["trace[1875582032] 'process raft request' (duration: 100.165865ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:03:58.708996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.550096ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-04-02T09:03:58.709076Z","caller":"traceutil/trace.go:171","msg":"trace[683847856] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:8778; }","duration":"106.67342ms","start":"2026-04-02T09:03:58.602385Z","end":"2026-04-02T09:03:58.709058Z","steps":["trace[683847856] 'range keys from in-memory index tree' (duration: 106.391763ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:04:09.795514Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023dd.wal"} {"level":"warn","ts":"2026-04-02T09:05:22.721001Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.58998ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-02T09:05:22.721298Z","caller":"traceutil/trace.go:171","msg":"trace[1191009058] transaction","detail":"{read_only:false; response_revision:9562; number_of_response:1; }","duration":"259.467628ms","start":"2026-04-02T09:05:22.461808Z","end":"2026-04-02T09:05:22.721276Z","steps":["trace[1191009058] 'process raft request' (duration: 123.488228ms)","trace[1191009058] 'compare' (duration: 135.313601ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:05:22.721378Z","caller":"traceutil/trace.go:171","msg":"trace[1370679895] transaction","detail":"{read_only:false; response_revision:9563; number_of_response:1; }","duration":"258.87422ms","start":"2026-04-02T09:05:22.462474Z","end":"2026-04-02T09:05:22.721348Z","steps":["trace[1370679895] 'process raft request' (duration: 258.728886ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:05:22.899683Z","caller":"traceutil/trace.go:171","msg":"trace[1939896612] transaction","detail":"{read_only:false; response_revision:9567; number_of_response:1; }","duration":"172.376459ms","start":"2026-04-02T09:05:22.727281Z","end":"2026-04-02T09:05:22.899658Z","steps":["trace[1939896612] 'process raft request' (duration: 172.298986ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:05:22.899922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.87966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/\" range_end:\"/registry/monitoring.coreos.com/prometheuses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-02T09:05:22.899988Z","caller":"traceutil/trace.go:171","msg":"trace[1258249371] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/; range_end:/registry/monitoring.coreos.com/prometheuses0; response_count:0; response_revision:9568; }","duration":"128.997683ms","start":"2026-04-02T09:05:22.77097Z","end":"2026-04-02T09:05:22.899968Z","steps":["trace[1258249371] 'agreement among raft nodes before linearized reading' (duration: 128.87389ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:05:22.89993Z","caller":"traceutil/trace.go:171","msg":"trace[379337764] transaction","detail":"{read_only:false; response_revision:9568; number_of_response:1; }","duration":"172.596385ms","start":"2026-04-02T09:05:22.727319Z","end":"2026-04-02T09:05:22.899916Z","steps":["trace[379337764] 'process raft request' (duration: 172.298776ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:05:22.899766Z","caller":"traceutil/trace.go:171","msg":"trace[1421269301] transaction","detail":"{read_only:false; response_revision:9566; number_of_response:1; }","duration":"172.880973ms","start":"2026-04-02T09:05:22.726849Z","end":"2026-04-02T09:05:22.89973Z","steps":["trace[1421269301] 'process raft request' (duration: 172.693217ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:05:22.899819Z","caller":"traceutil/trace.go:171","msg":"trace[1055397829] linearizableReadLoop","detail":"{readStateIndex:9902; appliedIndex:9897; }","duration":"128.788807ms","start":"2026-04-02T09:05:22.771018Z","end":"2026-04-02T09:05:22.899807Z","steps":["trace[1055397829] 'read index received' (duration: 4.341272ms)","trace[1055397829] 'applied index is now lower than readState.Index' (duration: 124.445605ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:05:22.899727Z","caller":"traceutil/trace.go:171","msg":"trace[1520666922] transaction","detail":"{read_only:false; response_revision:9565; number_of_response:1; }","duration":"173.776598ms","start":"2026-04-02T09:05:22.725923Z","end":"2026-04-02T09:05:22.8997Z","steps":["trace[1520666922] 'process raft request' (duration: 173.456058ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:05:35.486135Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"791a7e25b533cd26","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-02T09:05:35.49914Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-02T09:05:35.499242Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-02T09:07:35.29604Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8048} {"level":"info","ts":"2026-04-02T09:07:35.359212Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8048,"took":"60.642378ms","hash":3659032597,"current-db-size-bytes":58425344,"current-db-size":"58 MB","current-db-size-in-use-bytes":28160000,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-02T09:07:35.359416Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3659032597,"revision":8048,"compact-revision":6024} {"level":"warn","ts":"2026-04-02T09:08:34.251065Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675782545133385,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T09:08:34.335439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.548207ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-02T09:08:34.351936Z","caller":"traceutil/trace.go:171","msg":"trace[1998028723] linearizableReadLoop","detail":"{readStateIndex:11139; appliedIndex:11134; }","duration":"600.917549ms","start":"2026-04-02T09:08:33.750993Z","end":"2026-04-02T09:08:34.351911Z","steps":["trace[1998028723] 'read index received' (duration: 598.736397ms)","trace[1998028723] 'applied index is now lower than readState.Index' (duration: 2.180332ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:08:34.352237Z","caller":"traceutil/trace.go:171","msg":"trace[1815463908] transaction","detail":"{read_only:false; response_revision:10757; number_of_response:1; }","duration":"601.998478ms","start":"2026-04-02T09:08:33.750229Z","end":"2026-04-02T09:08:34.352227Z","steps":["trace[1815463908] 'process raft request' (duration: 601.448222ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:08:34.352357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:08:33.750209Z","time spent":"602.059241ms","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:08:34.352417Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.403562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1990"} {"level":"info","ts":"2026-04-02T09:08:34.352496Z","caller":"traceutil/trace.go:171","msg":"trace[810142051] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:10760; }","duration":"601.512255ms","start":"2026-04-02T09:08:33.750965Z","end":"2026-04-02T09:08:34.352477Z","steps":["trace[810142051] 'agreement among raft nodes before linearized reading' (duration: 601.358021ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:08:34.352535Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:08:33.750957Z","time spent":"601.569977ms","remote":"127.0.0.1:50750","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":1,"response size":2013,"request content":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" "} {"level":"info","ts":"2026-04-02T09:08:34.352714Z","caller":"traceutil/trace.go:171","msg":"trace[1531806254] transaction","detail":"{read_only:false; response_revision:10758; number_of_response:1; }","duration":"602.40394ms","start":"2026-04-02T09:08:33.750302Z","end":"2026-04-02T09:08:34.352706Z","steps":["trace[1531806254] 'process raft request' (duration: 601.516034ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:08:34.352798Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:08:33.750282Z","time spent":"602.455422ms","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T09:08:34.352768Z","caller":"traceutil/trace.go:171","msg":"trace[1016073216] transaction","detail":"{read_only:false; response_revision:10756; number_of_response:1; }","duration":"603.565984ms","start":"2026-04-02T09:08:33.746557Z","end":"2026-04-02T09:08:34.350123Z","steps":["trace[1016073216] 'process raft request' (duration: 603.269156ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:08:34.352901Z","caller":"traceutil/trace.go:171","msg":"trace[1165788198] transaction","detail":"{read_only:false; response_revision:10759; number_of_response:1; }","duration":"602.590655ms","start":"2026-04-02T09:08:33.750304Z","end":"2026-04-02T09:08:34.352894Z","steps":["trace[1165788198] 'process raft request' (duration: 601.551085ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:08:34.352953Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:08:33.750283Z","time spent":"602.646367ms","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T09:08:34.353055Z","caller":"traceutil/trace.go:171","msg":"trace[1612787906] transaction","detail":"{read_only:false; response_revision:10760; number_of_response:1; }","duration":"602.589745ms","start":"2026-04-02T09:08:33.750454Z","end":"2026-04-02T09:08:34.353043Z","steps":["trace[1612787906] 'process raft request' (duration: 601.426853ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:08:34.353065Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:08:33.746537Z","time spent":"606.352423ms","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:08:34.353104Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:08:33.750421Z","time spent":"602.656387ms","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:08:34.352538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"590.258177ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T09:08:34.353221Z","caller":"traceutil/trace.go:171","msg":"trace[508130577] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:10760; }","duration":"590.977797ms","start":"2026-04-02T09:08:33.762235Z","end":"2026-04-02T09:08:34.353213Z","steps":["trace[508130577] 'agreement among raft nodes before linearized reading' (duration: 590.277327ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:08:34.353251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:08:33.762214Z","time spent":"591.029059ms","remote":"127.0.0.1:50586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T09:09:30.891925Z","caller":"traceutil/trace.go:171","msg":"trace[612362701] linearizableReadLoop","detail":"{readStateIndex:11564; appliedIndex:11560; }","duration":"116.60018ms","start":"2026-04-02T09:09:30.775303Z","end":"2026-04-02T09:09:30.891904Z","steps":["trace[612362701] 'read index received' (duration: 22.253868ms)","trace[612362701] 'applied index is now lower than readState.Index' (duration: 94.345212ms)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:09:30.892292Z","caller":"traceutil/trace.go:171","msg":"trace[1545212860] transaction","detail":"{read_only:false; response_revision:11172; number_of_response:1; }","duration":"143.250922ms","start":"2026-04-02T09:09:30.748993Z","end":"2026-04-02T09:09:30.892244Z","steps":["trace[1545212860] 'process raft request' (duration: 142.869532ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:30.892617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.29199ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-02T09:09:30.892687Z","caller":"traceutil/trace.go:171","msg":"trace[1599878346] transaction","detail":"{read_only:false; response_revision:11171; number_of_response:1; }","duration":"143.756198ms","start":"2026-04-02T09:09:30.748904Z","end":"2026-04-02T09:09:30.89266Z","steps":["trace[1599878346] 'process raft request' (duration: 142.900083ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:09:30.892718Z","caller":"traceutil/trace.go:171","msg":"trace[1254816970] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11172; }","duration":"117.441024ms","start":"2026-04-02T09:09:30.775256Z","end":"2026-04-02T09:09:30.892697Z","steps":["trace[1254816970] 'agreement among raft nodes before linearized reading' (duration: 117.234248ms)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:09:30.89265Z","caller":"traceutil/trace.go:171","msg":"trace[2031843793] transaction","detail":"{read_only:false; response_revision:11170; number_of_response:1; }","duration":"144.848788ms","start":"2026-04-02T09:09:30.747788Z","end":"2026-04-02T09:09:30.892637Z","steps":["trace[2031843793] 'process raft request' (duration: 110.304703ms)","trace[2031843793] 'compare' (duration: 33.587728ms)"],"step_count":2} {"level":"warn","ts":"2026-04-02T09:09:48.971223Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675782545134793,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T09:09:49.471558Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":14782675782545134793,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-04-02T09:09:49.611294Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.15081343s","expected-duration":"1s"} {"level":"warn","ts":"2026-04-02T09:09:49.612285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.460329Z","time spent":"1.151946732s","remote":"127.0.0.1:50634","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-04-02T09:09:50.684811Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.066889293s","expected-duration":"1s"} {"level":"info","ts":"2026-04-02T09:09:50.685338Z","caller":"traceutil/trace.go:171","msg":"trace[1226877932] linearizableReadLoop","detail":"{readStateIndex:11699; appliedIndex:11693; }","duration":"2.214859223s","start":"2026-04-02T09:09:48.470454Z","end":"2026-04-02T09:09:50.685314Z","steps":["trace[1226877932] 'read index received' (duration: 1.141491058s)","trace[1226877932] 'applied index is now lower than readState.Index' (duration: 1.073367185s)"],"step_count":2} {"level":"info","ts":"2026-04-02T09:09:50.685627Z","caller":"traceutil/trace.go:171","msg":"trace[611213661] transaction","detail":"{read_only:false; response_revision:11301; number_of_response:1; }","duration":"1.977288742s","start":"2026-04-02T09:09:48.708281Z","end":"2026-04-02T09:09:50.68557Z","steps":["trace[611213661] 'process raft request' (duration: 1.976955333s)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:09:50.685669Z","caller":"traceutil/trace.go:171","msg":"trace[1229644737] transaction","detail":"{read_only:false; response_revision:11299; number_of_response:1; }","duration":"2.194525658s","start":"2026-04-02T09:09:48.491116Z","end":"2026-04-02T09:09:50.685642Z","steps":["trace[1229644737] 'process raft request' (duration: 2.193916141s)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:09:50.685709Z","caller":"traceutil/trace.go:171","msg":"trace[551768853] transaction","detail":"{read_only:false; response_revision:11300; number_of_response:1; }","duration":"1.999038106s","start":"2026-04-02T09:09:48.686629Z","end":"2026-04-02T09:09:50.685667Z","steps":["trace[551768853] 'process raft request' (duration: 1.998556972s)"],"step_count":1} {"level":"info","ts":"2026-04-02T09:09:50.685739Z","caller":"traceutil/trace.go:171","msg":"trace[1162350568] transaction","detail":"{read_only:false; response_revision:11302; number_of_response:1; }","duration":"1.907512483s","start":"2026-04-02T09:09:48.778215Z","end":"2026-04-02T09:09:50.685727Z","steps":["trace[1162350568] 'process raft request' (duration: 1.90704982s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.685807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.708254Z","time spent":"1.977447356s","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:09:50.685789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.215300735s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/staffeln-db-sync\" ","response":"range_response_count:1 size:5127"} {"level":"warn","ts":"2026-04-02T09:09:50.685814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.49109Z","time spent":"2.194625391s","remote":"127.0.0.1:50740","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":19129,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T09:09:50.685615Z","caller":"traceutil/trace.go:171","msg":"trace[839179781] transaction","detail":"{read_only:false; response_revision:11303; number_of_response:1; }","duration":"1.641331995s","start":"2026-04-02T09:09:49.044239Z","end":"2026-04-02T09:09:50.685571Z","steps":["trace[839179781] 'process raft request' (duration: 1.641048227s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.685906Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.778187Z","time spent":"1.907625096s","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-02T09:09:50.685874Z","caller":"traceutil/trace.go:171","msg":"trace[1706364625] range","detail":"{range_begin:/registry/jobs/openstack/staffeln-db-sync; range_end:; response_count:1; response_revision:11303; }","duration":"2.215421428s","start":"2026-04-02T09:09:48.470433Z","end":"2026-04-02T09:09:50.685854Z","steps":["trace[1706364625] 'agreement among raft nodes before linearized reading' (duration: 2.21515098s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.685972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:49.044217Z","time spent":"1.641706876s","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:09:50.685874Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.686611Z","time spent":"1.999164989s","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:09:50.685995Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.470424Z","time spent":"2.215558782s","remote":"127.0.0.1:50800","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":5150,"request content":"key:\"/registry/jobs/openstack/staffeln-db-sync\" "} {"level":"warn","ts":"2026-04-02T09:09:50.686726Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.022879627s","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-04-02T09:09:50.686907Z","caller":"traceutil/trace.go:171","msg":"trace[525933566] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:11304; }","duration":"2.023111264s","start":"2026-04-02T09:09:48.663771Z","end":"2026-04-02T09:09:50.686883Z","steps":["trace[525933566] 'agreement among raft nodes before linearized reading' (duration: 2.022855926s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.686966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.663748Z","time spent":"2.023204047s","remote":"127.0.0.1:46432","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-04-02T09:09:50.687121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.692072366s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-04-02T09:09:50.687187Z","caller":"traceutil/trace.go:171","msg":"trace[1300959303] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:11304; }","duration":"1.692190629s","start":"2026-04-02T09:09:48.994976Z","end":"2026-04-02T09:09:50.687166Z","steps":["trace[1300959303] 'agreement among raft nodes before linearized reading' (duration: 1.691783007s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.687208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.695615437s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-04-02T09:09:50.687227Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.994955Z","time spent":"1.692262882s","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-02T09:09:50.687289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.795413452s","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-04-02T09:09:50.687335Z","caller":"traceutil/trace.go:171","msg":"trace[1516675929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:11304; }","duration":"1.795494454s","start":"2026-04-02T09:09:48.891829Z","end":"2026-04-02T09:09:50.687324Z","steps":["trace[1516675929] 'agreement among raft nodes before linearized reading' (duration: 1.79534598s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.687374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.891809Z","time spent":"1.795556065s","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-04-02T09:09:50.687419Z","caller":"traceutil/trace.go:171","msg":"trace[1529413417] transaction","detail":"{read_only:false; response_revision:11304; number_of_response:1; }","duration":"1.073558241s","start":"2026-04-02T09:09:49.613845Z","end":"2026-04-02T09:09:50.687403Z","steps":["trace[1529413417] 'process raft request' (duration: 1.072610034s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.687501Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:49.613797Z","time spent":"1.073663695s","remote":"127.0.0.1:50634","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":119,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-02T09:09:50.687529Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.694149965s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-02T09:09:50.687571Z","caller":"traceutil/trace.go:171","msg":"trace[134268786] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:11304; }","duration":"1.694240567s","start":"2026-04-02T09:09:48.993318Z","end":"2026-04-02T09:09:50.687559Z","steps":["trace[134268786] 'agreement among raft nodes before linearized reading' (duration: 1.694003491s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.687622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.993292Z","time spent":"1.694298959s","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-04-02T09:09:50.687637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.849305211s","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-04-02T09:09:50.687675Z","caller":"traceutil/trace.go:171","msg":"trace[339447392] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:11304; }","duration":"1.849382273s","start":"2026-04-02T09:09:48.838283Z","end":"2026-04-02T09:09:50.687665Z","steps":["trace[339447392] 'agreement among raft nodes before linearized reading' (duration: 1.84926872s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.687685Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.918928ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-04-02T09:09:50.687712Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.838262Z","time spent":"1.849444915s","remote":"127.0.0.1:50818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-04-02T09:09:50.687726Z","caller":"traceutil/trace.go:171","msg":"trace[1292794184] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11304; }","duration":"350.991071ms","start":"2026-04-02T09:09:50.336724Z","end":"2026-04-02T09:09:50.687715Z","steps":["trace[1292794184] 'agreement among raft nodes before linearized reading' (duration: 350.926139ms)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.687756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:50.336706Z","time spent":"351.043913ms","remote":"127.0.0.1:50586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T09:09:50.687264Z","caller":"traceutil/trace.go:171","msg":"trace[1653004874] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:11304; }","duration":"1.69569336s","start":"2026-04-02T09:09:48.99155Z","end":"2026-04-02T09:09:50.687243Z","steps":["trace[1653004874] 'agreement among raft nodes before linearized reading' (duration: 1.695439232s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.687884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:48.99153Z","time spent":"1.696338277s","remote":"127.0.0.1:50818","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":"warn","ts":"2026-04-02T09:09:50.687991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.531385754s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-ct9bf\" ","response":"range_response_count:1 size:6527"} {"level":"info","ts":"2026-04-02T09:09:50.68803Z","caller":"traceutil/trace.go:171","msg":"trace[628251111] range","detail":"{range_begin:/registry/pods/openstack/keepalived-ct9bf; range_end:; response_count:1; response_revision:11304; }","duration":"1.531470167s","start":"2026-04-02T09:09:49.156551Z","end":"2026-04-02T09:09:50.688021Z","steps":["trace[628251111] 'agreement among raft nodes before linearized reading' (duration: 1.531361554s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.688063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:49.156521Z","time spent":"1.531535249s","remote":"127.0.0.1:50742","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6550,"request content":"key:\"/registry/pods/openstack/keepalived-ct9bf\" "} {"level":"warn","ts":"2026-04-02T09:09:50.688129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.614610851s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-02T09:09:50.688168Z","caller":"traceutil/trace.go:171","msg":"trace[2082805830] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11304; }","duration":"1.614692394s","start":"2026-04-02T09:09:49.073465Z","end":"2026-04-02T09:09:50.688157Z","steps":["trace[2082805830] 'agreement among raft nodes before linearized reading' (duration: 1.614638383s)"],"step_count":1} {"level":"warn","ts":"2026-04-02T09:09:50.688201Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-02T09:09:49.073443Z","time spent":"1.614751175s","remote":"127.0.0.1:50586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-02T09:12:35.303479Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10463} {"level":"info","ts":"2026-04-02T09:12:35.393665Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10463,"took":"87.143906ms","hash":1841601924,"current-db-size-bytes":58425344,"current-db-size":"58 MB","current-db-size-in-use-bytes":22241280,"current-db-size-in-use":"22 MB"} {"level":"info","ts":"2026-04-02T09:12:35.393756Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1841601924,"revision":10463,"compact-revision":8048}