{"level":"warn","ts":"2026-07-09T13:09:14.347131Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T13:09:14.347285Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.196:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.196:2380","--initial-cluster=instance=https://199.19.213.196:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.196:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.196:2380","--name=instance","--peer-cert-file=/etc/kubernetes/pki/etcd/peer.crt","--peer-client-cert-auth=true","--peer-key-file=/etc/kubernetes/pki/etcd/peer.key","--peer-trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt","--snapshot-count=10000","--tls-min-version=TLS1.3","--trusted-ca-file=/etc/kubernetes/pki/etcd/ca.crt"]} {"level":"warn","ts":"2026-07-09T13:09:14.347408Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-09T13:09:14.347434Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.196:2380"]} {"level":"info","ts":"2026-07-09T13:09:14.347467Z","caller":"embed/etcd.go:494","msg":"starting with peer TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/peer.crt, key = /etc/kubernetes/pki/etcd/peer.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T13:09:14.348711Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.196:2379"]} {"level":"info","ts":"2026-07-09T13:09:14.348874Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.196:2380"],"listen-peer-urls":["https://199.19.213.196:2380"],"advertise-client-urls":["https://199.19.213.196:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.196:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.196:2380","initial-cluster-state":"new","initial-cluster-token":"etcd-cluster","quota-backend-bytes":2147483648,"max-request-bytes":1572864,"max-concurrent-streams":4294967295,"pre-vote":true,"initial-corrupt-check":true,"corrupt-check-time-interval":"0s","compact-check-time-enabled":false,"compact-check-time-interval":"1m0s","auto-compaction-mode":"periodic","auto-compaction-retention":"0s","auto-compaction-interval":"0s","discovery-url":"","discovery-proxy":"","downgrade-check-interval":"5s"} {"level":"info","ts":"2026-07-09T13:09:14.357997Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"8.704911ms"} {"level":"info","ts":"2026-07-09T13:09:14.457422Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"8f376d4d42da413a","cluster-id":"f5ef03c8da56d984"} {"level":"info","ts":"2026-07-09T13:09:14.457515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a switched to configuration voters=()"} {"level":"info","ts":"2026-07-09T13:09:14.457546Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a became follower at term 0"} {"level":"info","ts":"2026-07-09T13:09:14.457557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 8f376d4d42da413a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-09T13:09:14.457566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a became follower at term 1"} {"level":"info","ts":"2026-07-09T13:09:14.457646Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a switched to configuration voters=(10319837249744290106)"} {"level":"warn","ts":"2026-07-09T13:09:14.47338Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-09T13:09:14.474763Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-09T13:09:14.475979Z","caller":"etcdserver/quota.go:94","msg":"enabled backend quota with default value","quota-name":"v3-applier","quota-size-bytes":2147483648,"quota-size":"2.1 GB"} {"level":"info","ts":"2026-07-09T13:09:14.478663Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"8f376d4d42da413a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-09T13:09:14.478807Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"8f376d4d42da413a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-09T13:09:14.478965Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap.db","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T13:09:14.479081Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/snap","suffix":"snap","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T13:09:14.479099Z","caller":"fileutil/purge.go:50","msg":"started to purge file","dir":"/var/lib/etcd/member/wal","suffix":"wal","max":5,"interval":"30s"} {"level":"info","ts":"2026-07-09T13:09:14.481544Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a switched to configuration voters=(10319837249744290106)"} {"level":"info","ts":"2026-07-09T13:09:14.481812Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f5ef03c8da56d984","local-member-id":"8f376d4d42da413a","added-peer-id":"8f376d4d42da413a","added-peer-peer-urls":["https://199.19.213.196:2380"]} {"level":"info","ts":"2026-07-09T13:09:14.486248Z","caller":"embed/etcd.go:726","msg":"starting with client TLS","tls-info":"cert = /etc/kubernetes/pki/etcd/server.crt, key = /etc/kubernetes/pki/etcd/server.key, client-cert=, client-key=, trusted-ca = /etc/kubernetes/pki/etcd/ca.crt, client-cert-auth = true, crl-file = ","cipher-suites":[]} {"level":"info","ts":"2026-07-09T13:09:14.486618Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"8f376d4d42da413a","initial-advertise-peer-urls":["https://199.19.213.196:2380"],"listen-peer-urls":["https://199.19.213.196:2380"],"advertise-client-urls":["https://199.19.213.196:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.196:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-09T13:09:14.486658Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-09T13:09:14.486377Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.196:2380"} {"level":"info","ts":"2026-07-09T13:09:14.486686Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.196:2380"} {"level":"info","ts":"2026-07-09T13:09:15.058616Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a is starting a new election at term 1"} {"level":"info","ts":"2026-07-09T13:09:15.058673Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a became pre-candidate at term 1"} {"level":"info","ts":"2026-07-09T13:09:15.058696Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a received MsgPreVoteResp from 8f376d4d42da413a at term 1"} {"level":"info","ts":"2026-07-09T13:09:15.058715Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a became candidate at term 2"} {"level":"info","ts":"2026-07-09T13:09:15.058732Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a received MsgVoteResp from 8f376d4d42da413a at term 2"} {"level":"info","ts":"2026-07-09T13:09:15.058752Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"8f376d4d42da413a became leader at term 2"} {"level":"info","ts":"2026-07-09T13:09:15.05877Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 8f376d4d42da413a elected leader 8f376d4d42da413a at term 2"} {"level":"info","ts":"2026-07-09T13:09:15.059759Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"8f376d4d42da413a","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.196:2379]}","request-path":"/0/members/8f376d4d42da413a/attributes","cluster-id":"f5ef03c8da56d984","publish-timeout":"7s"} {"level":"info","ts":"2026-07-09T13:09:15.059961Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T13:09:15.060638Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T13:09:15.061123Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-09T13:09:15.061354Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f5ef03c8da56d984","local-member-id":"8f376d4d42da413a","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T13:09:15.06146Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T13:09:15.06149Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-09T13:09:15.063481Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-09T13:09:15.073429Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-09T13:09:15.07347Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-09T13:09:15.083122Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.196:2379"} {"level":"warn","ts":"2026-07-09T13:09:53.493057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.151856ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:09:53.493288Z","caller":"traceutil/trace.go:171","msg":"trace[1245761711] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:574; }","duration":"153.39821ms","start":"2026-07-09T13:09:53.339866Z","end":"2026-07-09T13:09:53.493265Z","steps":["trace[1245761711] 'range keys from in-memory index tree' (duration: 153.133586ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:09:53.493996Z","caller":"traceutil/trace.go:171","msg":"trace[329328679] transaction","detail":"{read_only:false; response_revision:575; number_of_response:1; }","duration":"245.370698ms","start":"2026-07-09T13:09:53.248594Z","end":"2026-07-09T13:09:53.493965Z","steps":["trace[329328679] 'process raft request' (duration: 237.05346ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:09:53.494778Z","caller":"traceutil/trace.go:171","msg":"trace[1069325671] linearizableReadLoop","detail":"{readStateIndex:598; appliedIndex:594; }","duration":"153.677784ms","start":"2026-07-09T13:09:53.341082Z","end":"2026-07-09T13:09:53.49476Z","steps":["trace[1069325671] 'read index received' (duration: 144.684986ms)","trace[1069325671] 'applied index is now lower than readState.Index' (duration: 8.991748ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:09:53.494927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.816656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-09T13:09:53.49496Z","caller":"traceutil/trace.go:171","msg":"trace[266682922] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:577; }","duration":"153.933058ms","start":"2026-07-09T13:09:53.341015Z","end":"2026-07-09T13:09:53.494948Z","steps":["trace[266682922] 'agreement among raft nodes before linearized reading' (duration: 153.832527ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:09:53.495233Z","caller":"traceutil/trace.go:171","msg":"trace[1445497054] transaction","detail":"{read_only:false; response_revision:577; number_of_response:1; }","duration":"227.797889ms","start":"2026-07-09T13:09:53.267425Z","end":"2026-07-09T13:09:53.495223Z","steps":["trace[1445497054] 'process raft request' (duration: 227.117569ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:09:53.495375Z","caller":"traceutil/trace.go:171","msg":"trace[1066981318] transaction","detail":"{read_only:false; response_revision:576; number_of_response:1; }","duration":"229.716979ms","start":"2026-07-09T13:09:53.26565Z","end":"2026-07-09T13:09:53.495367Z","steps":["trace[1066981318] 'process raft request' (duration: 228.333597ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:09:53.495503Z","caller":"traceutil/trace.go:171","msg":"trace[712726498] transaction","detail":"{read_only:false; number_of_response:1; response_revision:577; }","duration":"211.812474ms","start":"2026-07-09T13:09:53.28367Z","end":"2026-07-09T13:09:53.495482Z","steps":["trace[712726498] 'process raft request' (duration: 210.987602ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:09:59.644359Z","caller":"traceutil/trace.go:171","msg":"trace[1426098913] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"114.311719ms","start":"2026-07-09T13:09:59.530021Z","end":"2026-07-09T13:09:59.644333Z","steps":["trace[1426098913] 'process raft request' (duration: 114.152697ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:00.090921Z","caller":"traceutil/trace.go:171","msg":"trace[517316208] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"346.288385ms","start":"2026-07-09T13:09:59.744607Z","end":"2026-07-09T13:10:00.090896Z","steps":["trace[517316208] 'process raft request' (duration: 345.831958ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:00.091418Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:09:59.744585Z","time spent":"346.409076ms","remote":"127.0.0.1:53766","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:10:00.610458Z","caller":"traceutil/trace.go:171","msg":"trace[1818701857] transaction","detail":"{read_only:false; response_revision:640; number_of_response:1; }","duration":"459.653326ms","start":"2026-07-09T13:10:00.150778Z","end":"2026-07-09T13:10:00.610431Z","steps":["trace[1818701857] 'process raft request' (duration: 459.513514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:00.610679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:00.150753Z","time spent":"459.857908ms","remote":"127.0.0.1:53900","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-09T13:10:00.610829Z","caller":"traceutil/trace.go:171","msg":"trace[1370028630] linearizableReadLoop","detail":"{readStateIndex:663; appliedIndex:663; }","duration":"413.644067ms","start":"2026-07-09T13:10:00.197158Z","end":"2026-07-09T13:10:00.610802Z","steps":["trace[1370028630] 'read index received' (duration: 413.631707ms)","trace[1370028630] 'applied index is now lower than readState.Index' (duration: 10.05µs)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:10:00.610943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.781839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:10:00.610979Z","caller":"traceutil/trace.go:171","msg":"trace[300490701] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"413.86126ms","start":"2026-07-09T13:10:00.197108Z","end":"2026-07-09T13:10:00.61097Z","steps":["trace[300490701] 'agreement among raft nodes before linearized reading' (duration: 413.773338ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:00.611008Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:10:00.197086Z","time spent":"413.915791ms","remote":"127.0.0.1:53664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-09T13:10:00.698154Z","caller":"traceutil/trace.go:171","msg":"trace[1433364515] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"232.399371ms","start":"2026-07-09T13:10:00.465664Z","end":"2026-07-09T13:10:00.698064Z","steps":["trace[1433364515] 'process raft request' (duration: 232.200448ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:06.010532Z","caller":"traceutil/trace.go:171","msg":"trace[300433738] transaction","detail":"{read_only:false; response_revision:718; number_of_response:1; }","duration":"143.604979ms","start":"2026-07-09T13:10:05.866896Z","end":"2026-07-09T13:10:06.010501Z","steps":["trace[300433738] 'process raft request' (duration: 142.97622ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:17.011607Z","caller":"traceutil/trace.go:171","msg":"trace[2044449514] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:827; }","duration":"174.809262ms","start":"2026-07-09T13:10:16.836777Z","end":"2026-07-09T13:10:17.011587Z","steps":["trace[2044449514] 'read index received' (duration: 174.644889ms)","trace[2044449514] 'applied index is now lower than readState.Index' (duration: 163.663µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:10:17.0117Z","caller":"traceutil/trace.go:171","msg":"trace[25605881] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"223.623021ms","start":"2026-07-09T13:10:16.78807Z","end":"2026-07-09T13:10:17.011693Z","steps":["trace[25605881] 'process raft request' (duration: 223.411298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:10:17.01213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.219469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-09T13:10:17.012095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.269329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T13:10:17.012218Z","caller":"traceutil/trace.go:171","msg":"trace[1078524366] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:801; }","duration":"175.35776ms","start":"2026-07-09T13:10:16.836842Z","end":"2026-07-09T13:10:17.012199Z","steps":["trace[1078524366] 'agreement among raft nodes before linearized reading' (duration: 175.113217ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:10:17.01262Z","caller":"traceutil/trace.go:171","msg":"trace[129667682] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:801; }","duration":"175.828888ms","start":"2026-07-09T13:10:16.836744Z","end":"2026-07-09T13:10:17.012573Z","steps":["trace[129667682] 'agreement among raft nodes before linearized reading' (duration: 175.197829ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:11:36.286672Z","caller":"traceutil/trace.go:171","msg":"trace[39947997] linearizableReadLoop","detail":"{readStateIndex:1955; appliedIndex:1954; }","duration":"149.578441ms","start":"2026-07-09T13:11:36.137069Z","end":"2026-07-09T13:11:36.286648Z","steps":["trace[39947997] 'read index received' (duration: 149.433099ms)","trace[39947997] 'applied index is now lower than readState.Index' (duration: 144.592µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:11:36.286869Z","caller":"traceutil/trace.go:171","msg":"trace[1299806587] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"190.506348ms","start":"2026-07-09T13:11:36.096334Z","end":"2026-07-09T13:11:36.28684Z","steps":["trace[1299806587] 'process raft request' (duration: 190.179313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:36.286939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.847545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5a1ffa50-7dd9-4694-8095-1c149da08eb1\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-09T13:11:36.287001Z","caller":"traceutil/trace.go:171","msg":"trace[1930137678] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5a1ffa50-7dd9-4694-8095-1c149da08eb1; range_end:; response_count:1; response_revision:1883; }","duration":"149.957197ms","start":"2026-07-09T13:11:36.13703Z","end":"2026-07-09T13:11:36.286987Z","steps":["trace[1930137678] 'agreement among raft nodes before linearized reading' (duration: 149.748564ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:11:36.418535Z","caller":"traceutil/trace.go:171","msg":"trace[1579705020] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"124.106824ms","start":"2026-07-09T13:11:36.294397Z","end":"2026-07-09T13:11:36.418504Z","steps":["trace[1579705020] 'process raft request' (duration: 123.881861ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:11:36.794502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.780362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-09T13:11:36.794571Z","caller":"traceutil/trace.go:171","msg":"trace[357589068] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1886; }","duration":"148.896033ms","start":"2026-07-09T13:11:36.645659Z","end":"2026-07-09T13:11:36.794555Z","steps":["trace[357589068] 'range keys from in-memory index tree' (duration: 148.61246ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:05.163623Z","caller":"traceutil/trace.go:171","msg":"trace[111982345] transaction","detail":"{read_only:false; response_revision:2102; number_of_response:1; }","duration":"180.058695ms","start":"2026-07-09T13:12:04.983521Z","end":"2026-07-09T13:12:05.16358Z","steps":["trace[111982345] 'process raft request' (duration: 128.044224ms)","trace[111982345] 'compare' (duration: 51.889838ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:12:05.963013Z","caller":"traceutil/trace.go:171","msg":"trace[10227368] transaction","detail":"{read_only:false; response_revision:2104; number_of_response:1; }","duration":"142.20713ms","start":"2026-07-09T13:12:05.820784Z","end":"2026-07-09T13:12:05.962991Z","steps":["trace[10227368] 'process raft request' (duration: 141.963936ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:05.963067Z","caller":"traceutil/trace.go:171","msg":"trace[1360650509] transaction","detail":"{read_only:false; response_revision:2105; number_of_response:1; }","duration":"141.271297ms","start":"2026-07-09T13:12:05.821771Z","end":"2026-07-09T13:12:05.963042Z","steps":["trace[1360650509] 'process raft request' (duration: 141.143026ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:05.963203Z","caller":"traceutil/trace.go:171","msg":"trace[159800372] transaction","detail":"{read_only:false; response_revision:2106; number_of_response:1; }","duration":"138.412126ms","start":"2026-07-09T13:12:05.824622Z","end":"2026-07-09T13:12:05.963034Z","steps":["trace[159800372] 'process raft request' (duration: 138.357886ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:06.695074Z","caller":"traceutil/trace.go:171","msg":"trace[926238889] linearizableReadLoop","detail":"{readStateIndex:2186; appliedIndex:2185; }","duration":"129.862638ms","start":"2026-07-09T13:12:06.565191Z","end":"2026-07-09T13:12:06.695054Z","steps":["trace[926238889] 'read index received' (duration: 129.729876ms)","trace[926238889] 'applied index is now lower than readState.Index' (duration: 132.142µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:12:06.695158Z","caller":"traceutil/trace.go:171","msg":"trace[1499293936] transaction","detail":"{read_only:false; response_revision:2107; number_of_response:1; }","duration":"219.962754ms","start":"2026-07-09T13:12:06.475188Z","end":"2026-07-09T13:12:06.695151Z","steps":["trace[1499293936] 'process raft request' (duration: 219.743471ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:06.695393Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.196122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-07-09T13:12:06.695419Z","caller":"traceutil/trace.go:171","msg":"trace[519393191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2107; }","duration":"130.273453ms","start":"2026-07-09T13:12:06.565139Z","end":"2026-07-09T13:12:06.695413Z","steps":["trace[519393191] 'agreement among raft nodes before linearized reading' (duration: 130.135691ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:06.857909Z","caller":"traceutil/trace.go:171","msg":"trace[1427454441] transaction","detail":"{read_only:false; response_revision:2109; number_of_response:1; }","duration":"158.083387ms","start":"2026-07-09T13:12:06.699803Z","end":"2026-07-09T13:12:06.857887Z","steps":["trace[1427454441] 'process raft request' (duration: 158.009886ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:06.858001Z","caller":"traceutil/trace.go:171","msg":"trace[1640016514] transaction","detail":"{read_only:false; response_revision:2108; number_of_response:1; }","duration":"180.142204ms","start":"2026-07-09T13:12:06.677832Z","end":"2026-07-09T13:12:06.857975Z","steps":["trace[1640016514] 'process raft request' (duration: 169.877262ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:06.858047Z","caller":"traceutil/trace.go:171","msg":"trace[1289409311] linearizableReadLoop","detail":"{readStateIndex:2187; appliedIndex:2186; }","duration":"162.909704ms","start":"2026-07-09T13:12:06.695125Z","end":"2026-07-09T13:12:06.858035Z","steps":["trace[1289409311] 'read index received' (duration: 152.626623ms)","trace[1289409311] 'applied index is now lower than readState.Index' (duration: 10.282071ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:12:06.858533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.240847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-09T13:12:06.858566Z","caller":"traceutil/trace.go:171","msg":"trace[1722738096] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2109; }","duration":"209.329597ms","start":"2026-07-09T13:12:06.649229Z","end":"2026-07-09T13:12:06.858558Z","steps":["trace[1722738096] 'agreement among raft nodes before linearized reading' (duration: 208.84442ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:07.408749Z","caller":"traceutil/trace.go:171","msg":"trace[696456618] transaction","detail":"{read_only:false; response_revision:2111; number_of_response:1; }","duration":"240.010982ms","start":"2026-07-09T13:12:07.168716Z","end":"2026-07-09T13:12:07.408727Z","steps":["trace[696456618] 'process raft request' (duration: 162.123854ms)","trace[696456618] 'compare' (duration: 77.690805ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:12:07.408904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.389119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/clusterissuers/\" range_end:\"/registry/cert-manager.io/clusterissuers0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T13:12:07.408947Z","caller":"traceutil/trace.go:171","msg":"trace[1192892073] range","detail":"{range_begin:/registry/cert-manager.io/clusterissuers/; range_end:/registry/cert-manager.io/clusterissuers0; response_count:0; response_revision:2111; }","duration":"238.46808ms","start":"2026-07-09T13:12:07.170466Z","end":"2026-07-09T13:12:07.408934Z","steps":["trace[1192892073] 'agreement among raft nodes before linearized reading' (duration: 238.324498ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:12:07.408744Z","caller":"traceutil/trace.go:171","msg":"trace[2092885129] linearizableReadLoop","detail":"{readStateIndex:2190; appliedIndex:2189; }","duration":"238.227027ms","start":"2026-07-09T13:12:07.17049Z","end":"2026-07-09T13:12:07.408717Z","steps":["trace[2092885129] 'read index received' (duration: 160.369029ms)","trace[2092885129] 'applied index is now lower than readState.Index' (duration: 77.856718ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:12:07.980479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"258.74595ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:12:07.980592Z","caller":"traceutil/trace.go:171","msg":"trace[805011533] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2190; }","duration":"571.526888ms","start":"2026-07-09T13:12:07.409045Z","end":"2026-07-09T13:12:07.980572Z","steps":["trace[805011533] 'read index received' (duration: 312.378673ms)","trace[805011533] 'applied index is now lower than readState.Index' (duration: 259.146795ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:12:07.980671Z","caller":"traceutil/trace.go:171","msg":"trace[1764163182] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"788.422058ms","start":"2026-07-09T13:12:07.19223Z","end":"2026-07-09T13:12:07.980652Z","steps":["trace[1764163182] 'process raft request' (duration: 529.279373ms)","trace[1764163182] 'compare' (duration: 258.278413ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:12:07.9813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"784.098739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:12:07.980753Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:07.19221Z","time spent":"788.50372ms","remote":"127.0.0.1:53922","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-09T13:12:07.981382Z","caller":"traceutil/trace.go:171","msg":"trace[152721663] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2112; }","duration":"784.183111ms","start":"2026-07-09T13:12:07.197177Z","end":"2026-07-09T13:12:07.98136Z","steps":["trace[152721663] 'agreement among raft nodes before linearized reading' (duration: 784.064339ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:07.981435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-09T13:12:07.197099Z","time spent":"784.326602ms","remote":"127.0.0.1:53664","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-07-09T13:12:08.010478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.073038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-09T13:12:08.010553Z","caller":"traceutil/trace.go:171","msg":"trace[2099376261] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2113; }","duration":"145.193269ms","start":"2026-07-09T13:12:07.865341Z","end":"2026-07-09T13:12:08.010534Z","steps":["trace[2099376261] 'agreement among raft nodes before linearized reading' (duration: 145.039527ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:12:08.010551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.368189ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-09T13:12:08.010793Z","caller":"traceutil/trace.go:171","msg":"trace[1989447716] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2113; }","duration":"178.652962ms","start":"2026-07-09T13:12:07.832115Z","end":"2026-07-09T13:12:08.010768Z","steps":["trace[1989447716] 'agreement among raft nodes before linearized reading' (duration: 178.255767ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:23.64833Z","caller":"traceutil/trace.go:171","msg":"trace[1584058706] linearizableReadLoop","detail":"{readStateIndex:2721; appliedIndex:2720; }","duration":"195.179414ms","start":"2026-07-09T13:13:23.453128Z","end":"2026-07-09T13:13:23.648308Z","steps":["trace[1584058706] 'read index received' (duration: 195.048539ms)","trace[1584058706] 'applied index is now lower than readState.Index' (duration: 129.655µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:13:23.648445Z","caller":"traceutil/trace.go:171","msg":"trace[1250049417] transaction","detail":"{read_only:false; response_revision:2623; number_of_response:1; }","duration":"237.104219ms","start":"2026-07-09T13:13:23.411301Z","end":"2026-07-09T13:13:23.648406Z","steps":["trace[1250049417] 'process raft request' (duration: 236.853281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:23.648492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.346409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:13:23.648538Z","caller":"traceutil/trace.go:171","msg":"trace[1859305990] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2623; }","duration":"195.449013ms","start":"2026-07-09T13:13:23.453075Z","end":"2026-07-09T13:13:23.648524Z","steps":["trace[1859305990] 'agreement among raft nodes before linearized reading' (duration: 195.357469ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:13:56.011376Z","caller":"traceutil/trace.go:171","msg":"trace[1842318138] linearizableReadLoop","detail":"{readStateIndex:2908; appliedIndex:2907; }","duration":"161.688387ms","start":"2026-07-09T13:13:55.849662Z","end":"2026-07-09T13:13:56.01135Z","steps":["trace[1842318138] 'read index received' (duration: 161.503791ms)","trace[1842318138] 'applied index is now lower than readState.Index' (duration: 155.715µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:13:56.011535Z","caller":"traceutil/trace.go:171","msg":"trace[1965896861] transaction","detail":"{read_only:false; response_revision:2803; number_of_response:1; }","duration":"185.253489ms","start":"2026-07-09T13:13:55.826229Z","end":"2026-07-09T13:13:56.011482Z","steps":["trace[1965896861] 'process raft request' (duration: 184.935108ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:13:56.011671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.968818ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-07-09T13:13:56.011722Z","caller":"traceutil/trace.go:171","msg":"trace[403865759] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2803; }","duration":"162.129093ms","start":"2026-07-09T13:13:55.849582Z","end":"2026-07-09T13:13:56.011712Z","steps":["trace[403865759] 'agreement among raft nodes before linearized reading' (duration: 161.923626ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:01.389983Z","caller":"traceutil/trace.go:171","msg":"trace[1175982106] transaction","detail":"{read_only:false; response_revision:2831; number_of_response:1; }","duration":"107.98526ms","start":"2026-07-09T13:14:01.281976Z","end":"2026-07-09T13:14:01.389961Z","steps":["trace[1175982106] 'process raft request' (duration: 107.66537ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:14:58.525157Z","caller":"traceutil/trace.go:171","msg":"trace[1505910059] transaction","detail":"{read_only:false; response_revision:3132; number_of_response:1; }","duration":"100.067893ms","start":"2026-07-09T13:14:58.425071Z","end":"2026-07-09T13:14:58.525139Z","steps":["trace[1505910059] 'process raft request' (duration: 99.945549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:00.860486Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.989069ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:15:00.860829Z","caller":"traceutil/trace.go:171","msg":"trace[1113820322] transaction","detail":"{read_only:false; response_revision:3143; number_of_response:1; }","duration":"169.396914ms","start":"2026-07-09T13:15:00.691221Z","end":"2026-07-09T13:15:00.860618Z","steps":["trace[1113820322] 'process raft request' (duration: 68.149377ms)","trace[1113820322] 'compare' (duration: 100.766243ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:01.097862Z","caller":"traceutil/trace.go:171","msg":"trace[685864171] linearizableReadLoop","detail":"{readStateIndex:3263; appliedIndex:3262; }","duration":"103.065366ms","start":"2026-07-09T13:15:00.994774Z","end":"2026-07-09T13:15:01.097839Z","steps":["trace[685864171] 'read index received' (duration: 87.947589ms)","trace[685864171] 'applied index is now lower than readState.Index' (duration: 15.117167ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:15:01.097993Z","caller":"traceutil/trace.go:171","msg":"trace[1071186423] transaction","detail":"{read_only:false; response_revision:3144; number_of_response:1; }","duration":"153.53214ms","start":"2026-07-09T13:15:00.944447Z","end":"2026-07-09T13:15:01.097979Z","steps":["trace[1071186423] 'process raft request' (duration: 138.329421ms)","trace[1071186423] 'compare' (duration: 14.968832ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:15:01.098276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.481118ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-09T13:15:01.098303Z","caller":"traceutil/trace.go:171","msg":"trace[1231790302] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3144; }","duration":"103.57571ms","start":"2026-07-09T13:15:00.994721Z","end":"2026-07-09T13:15:01.098296Z","steps":["trace[1231790302] 'agreement among raft nodes before linearized reading' (duration: 103.456507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:01.098569Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.724384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-09T13:15:01.098661Z","caller":"traceutil/trace.go:171","msg":"trace[710733143] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3144; }","duration":"103.877619ms","start":"2026-07-09T13:15:00.994763Z","end":"2026-07-09T13:15:01.09864Z","steps":["trace[710733143] 'agreement among raft nodes before linearized reading' (duration: 103.666843ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:15:03.213966Z","caller":"traceutil/trace.go:171","msg":"trace[1166198675] transaction","detail":"{read_only:false; response_revision:3154; number_of_response:1; }","duration":"108.3627ms","start":"2026-07-09T13:15:03.10558Z","end":"2026-07-09T13:15:03.213943Z","steps":["trace[1166198675] 'process raft request' (duration: 108.148904ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:03.422829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.181432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-09T13:15:03.422925Z","caller":"traceutil/trace.go:171","msg":"trace[1225298359] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:3156; }","duration":"112.323847ms","start":"2026-07-09T13:15:03.310579Z","end":"2026-07-09T13:15:03.422903Z","steps":["trace[1225298359] 'range keys from in-memory index tree' (duration: 111.996167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:15:03.665267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.036802ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1317"} {"level":"info","ts":"2026-07-09T13:15:03.665331Z","caller":"traceutil/trace.go:171","msg":"trace[1481984168] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:3157; }","duration":"140.151016ms","start":"2026-07-09T13:15:03.525166Z","end":"2026-07-09T13:15:03.665317Z","steps":["trace[1481984168] 'range keys from in-memory index tree' (duration: 139.897558ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:18:04.431958Z","caller":"traceutil/trace.go:171","msg":"trace[1161599507] transaction","detail":"{read_only:false; response_revision:4835; number_of_response:1; }","duration":"114.683478ms","start":"2026-07-09T13:18:04.317248Z","end":"2026-07-09T13:18:04.431931Z","steps":["trace[1161599507] 'process raft request' (duration: 114.511824ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:18:09.985842Z","caller":"traceutil/trace.go:171","msg":"trace[568571999] linearizableReadLoop","detail":"{readStateIndex:5065; appliedIndex:5064; }","duration":"120.295029ms","start":"2026-07-09T13:18:09.865521Z","end":"2026-07-09T13:18:09.985816Z","steps":["trace[568571999] 'read index received' (duration: 68.765863ms)","trace[568571999] 'applied index is now lower than readState.Index' (duration: 51.528026ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:18:09.986019Z","caller":"traceutil/trace.go:171","msg":"trace[2090814688] transaction","detail":"{read_only:false; response_revision:4879; number_of_response:1; }","duration":"188.626812ms","start":"2026-07-09T13:18:09.797374Z","end":"2026-07-09T13:18:09.986001Z","steps":["trace[2090814688] 'process raft request' (duration: 136.884781ms)","trace[2090814688] 'compare' (duration: 51.437764ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:18:09.986268Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.727768ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/\" range_end:\"/registry/persistentvolumeclaims0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T13:18:09.987026Z","caller":"traceutil/trace.go:171","msg":"trace[1337526823] range","detail":"{range_begin:/registry/persistentvolumeclaims/; range_end:/registry/persistentvolumeclaims0; response_count:0; response_revision:4879; }","duration":"120.983484ms","start":"2026-07-09T13:18:09.865481Z","end":"2026-07-09T13:18:09.986465Z","steps":["trace[1337526823] 'agreement among raft nodes before linearized reading' (duration: 120.712308ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:18:10.515548Z","caller":"traceutil/trace.go:171","msg":"trace[1435859612] linearizableReadLoop","detail":"{readStateIndex:5077; appliedIndex:5076; }","duration":"168.446334ms","start":"2026-07-09T13:18:10.346997Z","end":"2026-07-09T13:18:10.515443Z","steps":["trace[1435859612] 'read index received' (duration: 168.22642ms)","trace[1435859612] 'applied index is now lower than readState.Index' (duration: 218.524µs)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:18:10.515851Z","caller":"traceutil/trace.go:171","msg":"trace[989620562] transaction","detail":"{read_only:false; response_revision:4891; number_of_response:1; }","duration":"202.14141ms","start":"2026-07-09T13:18:10.313683Z","end":"2026-07-09T13:18:10.515825Z","steps":["trace[989620562] 'process raft request' (duration: 201.526316ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:18:10.515891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.862764ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-09T13:18:10.515953Z","caller":"traceutil/trace.go:171","msg":"trace[1416172921] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4891; }","duration":"168.955255ms","start":"2026-07-09T13:18:10.346979Z","end":"2026-07-09T13:18:10.515934Z","steps":["trace[1416172921] 'agreement among raft nodes before linearized reading' (duration: 168.649788ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:18:10.573144Z","caller":"traceutil/trace.go:171","msg":"trace[1835156542] transaction","detail":"{read_only:false; response_revision:4892; number_of_response:1; }","duration":"122.323131ms","start":"2026-07-09T13:18:10.45078Z","end":"2026-07-09T13:18:10.573103Z","steps":["trace[1835156542] 'process raft request' (duration: 122.113856ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:18:10.796498Z","caller":"traceutil/trace.go:171","msg":"trace[1045608505] transaction","detail":"{read_only:false; response_revision:4893; number_of_response:1; }","duration":"268.489087ms","start":"2026-07-09T13:18:10.527981Z","end":"2026-07-09T13:18:10.79647Z","steps":["trace[1045608505] 'process raft request' (duration: 246.627627ms)","trace[1045608505] 'compare' (duration: 21.708157ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:18:10.796539Z","caller":"traceutil/trace.go:171","msg":"trace[1847369601] linearizableReadLoop","detail":"{readStateIndex:5082; appliedIndex:5078; }","duration":"223.463278ms","start":"2026-07-09T13:18:10.573063Z","end":"2026-07-09T13:18:10.796526Z","steps":["trace[1847369601] 'read index received' (duration: 201.472645ms)","trace[1847369601] 'applied index is now lower than readState.Index' (duration: 21.990093ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:18:10.79675Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"268.62052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-09T13:18:10.796801Z","caller":"traceutil/trace.go:171","msg":"trace[2114729305] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"268.21736ms","start":"2026-07-09T13:18:10.528557Z","end":"2026-07-09T13:18:10.796774Z","steps":["trace[2114729305] 'process raft request' (duration: 267.941854ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:18:10.796828Z","caller":"traceutil/trace.go:171","msg":"trace[894213360] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"268.475856ms","start":"2026-07-09T13:18:10.528329Z","end":"2026-07-09T13:18:10.796805Z","steps":["trace[894213360] 'process raft request' (duration: 268.126499ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:18:10.796782Z","caller":"traceutil/trace.go:171","msg":"trace[918501498] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:4896; }","duration":"268.683782ms","start":"2026-07-09T13:18:10.528089Z","end":"2026-07-09T13:18:10.796773Z","steps":["trace[918501498] 'agreement among raft nodes before linearized reading' (duration: 268.474377ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:18:10.796715Z","caller":"traceutil/trace.go:171","msg":"trace[372891312] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"268.483627ms","start":"2026-07-09T13:18:10.528203Z","end":"2026-07-09T13:18:10.796687Z","steps":["trace[372891312] 'process raft request' (duration: 268.213631ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:18:17.319594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.231733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-09T13:18:17.319751Z","caller":"traceutil/trace.go:171","msg":"trace[335298290] linearizableReadLoop","detail":"{readStateIndex:5239; appliedIndex:5238; }","duration":"122.813881ms","start":"2026-07-09T13:18:17.196913Z","end":"2026-07-09T13:18:17.319727Z","steps":["trace[335298290] 'read index received' (duration: 55.512µs)","trace[335298290] 'applied index is now lower than readState.Index' (duration: 122.756119ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:18:17.319829Z","caller":"traceutil/trace.go:171","msg":"trace[437792897] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"266.835112ms","start":"2026-07-09T13:18:17.052917Z","end":"2026-07-09T13:18:17.319752Z","steps":["trace[437792897] 'process raft request' (duration: 88.347924ms)","trace[437792897] 'compare' (duration: 178.10146ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:18:17.319875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.975145ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-09T13:18:17.32003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.729186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4d08b03a-eee7-476a-b43e-1a9cc7c15c94\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-09T13:18:17.320106Z","caller":"traceutil/trace.go:171","msg":"trace[1119301058] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4d08b03a-eee7-476a-b43e-1a9cc7c15c94; range_end:; response_count:1; response_revision:5049; }","duration":"112.832058ms","start":"2026-07-09T13:18:17.207256Z","end":"2026-07-09T13:18:17.320088Z","steps":["trace[1119301058] 'agreement among raft nodes before linearized reading' (duration: 112.610363ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:18:17.320093Z","caller":"traceutil/trace.go:171","msg":"trace[1789506572] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5049; }","duration":"123.219019ms","start":"2026-07-09T13:18:17.196845Z","end":"2026-07-09T13:18:17.320064Z","steps":["trace[1789506572] 'agreement among raft nodes before linearized reading' (duration: 122.967114ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:18:17.320254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.92097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-4d08b03a-eee7-476a-b43e-1a9cc7c15c94\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-09T13:18:17.320361Z","caller":"traceutil/trace.go:171","msg":"trace[1259988502] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-4d08b03a-eee7-476a-b43e-1a9cc7c15c94; range_end:; response_count:1; response_revision:5049; }","duration":"113.080223ms","start":"2026-07-09T13:18:17.207256Z","end":"2026-07-09T13:18:17.320336Z","steps":["trace[1259988502] 'agreement among raft nodes before linearized reading' (duration: 112.657094ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:18:17.564704Z","caller":"traceutil/trace.go:171","msg":"trace[312554078] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"234.989553ms","start":"2026-07-09T13:18:17.329678Z","end":"2026-07-09T13:18:17.564667Z","steps":["trace[312554078] 'process raft request' (duration: 234.736848ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:18:56.759417Z","caller":"traceutil/trace.go:171","msg":"trace[1037847680] linearizableReadLoop","detail":"{readStateIndex:5590; appliedIndex:5589; }","duration":"138.682449ms","start":"2026-07-09T13:18:56.620713Z","end":"2026-07-09T13:18:56.759396Z","steps":["trace[1037847680] 'read index received' (duration: 47.7593ms)","trace[1037847680] 'applied index is now lower than readState.Index' (duration: 90.922279ms)"],"step_count":2} {"level":"info","ts":"2026-07-09T13:18:56.759519Z","caller":"traceutil/trace.go:171","msg":"trace[93494285] transaction","detail":"{read_only:false; response_revision:5390; number_of_response:1; }","duration":"138.902043ms","start":"2026-07-09T13:18:56.620604Z","end":"2026-07-09T13:18:56.759506Z","steps":["trace[93494285] 'process raft request' (duration: 47.857381ms)","trace[93494285] 'compare' (duration: 90.798717ms)"],"step_count":2} {"level":"warn","ts":"2026-07-09T13:18:56.759697Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.965195ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:3958"} {"level":"info","ts":"2026-07-09T13:18:56.759772Z","caller":"traceutil/trace.go:171","msg":"trace[1718125965] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5390; }","duration":"139.074107ms","start":"2026-07-09T13:18:56.620679Z","end":"2026-07-09T13:18:56.759753Z","steps":["trace[1718125965] 'agreement among raft nodes before linearized reading' (duration: 138.875563ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:18:56.75993Z","caller":"traceutil/trace.go:171","msg":"trace[502429124] transaction","detail":"{read_only:false; response_revision:5391; number_of_response:1; }","duration":"138.453965ms","start":"2026-07-09T13:18:56.621464Z","end":"2026-07-09T13:18:56.759918Z","steps":["trace[502429124] 'process raft request' (duration: 138.377043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:18:56.771964Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.071893ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-09T13:18:56.772028Z","caller":"traceutil/trace.go:171","msg":"trace[518655586] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:0; response_revision:5392; }","duration":"151.160045ms","start":"2026-07-09T13:18:56.620853Z","end":"2026-07-09T13:18:56.772013Z","steps":["trace[518655586] 'agreement among raft nodes before linearized reading' (duration: 151.064663ms)"],"step_count":1} {"level":"warn","ts":"2026-07-09T13:18:56.772352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.538182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4726"} {"level":"info","ts":"2026-07-09T13:18:56.772411Z","caller":"traceutil/trace.go:171","msg":"trace[204843933] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:5392; }","duration":"143.622583ms","start":"2026-07-09T13:18:56.628777Z","end":"2026-07-09T13:18:56.7724Z","steps":["trace[204843933] 'agreement among raft nodes before linearized reading' (duration: 143.499021ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:19:15.08763Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2903} {"level":"info","ts":"2026-07-09T13:19:15.171974Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2903,"took":"83.094101ms","hash":1206337534,"current-db-size-bytes":53149696,"current-db-size":"53 MB","current-db-size-in-use-bytes":28745728,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-09T13:19:15.172037Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1206337534,"revision":2903,"compact-revision":-1} {"level":"info","ts":"2026-07-09T13:19:20.771257Z","caller":"traceutil/trace.go:171","msg":"trace[1993227015] transaction","detail":"{read_only:false; response_revision:5581; number_of_response:1; }","duration":"123.364762ms","start":"2026-07-09T13:19:20.647873Z","end":"2026-07-09T13:19:20.771237Z","steps":["trace[1993227015] 'process raft request' (duration: 123.214229ms)"],"step_count":1} {"level":"info","ts":"2026-07-09T13:24:15.09413Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5547} {"level":"info","ts":"2026-07-09T13:24:15.180348Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5547,"took":"84.851821ms","hash":1899655575,"current-db-size-bytes":53149696,"current-db-size":"53 MB","current-db-size-in-use-bytes":18759680,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-07-09T13:24:15.180415Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1899655575,"revision":5547,"compact-revision":2903}