{"level":"warn","ts":"2026-06-29T00:10:54.76346Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-29T00:10:54.763545Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.113:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.113:2380","--initial-cluster=instance=https://199.204.45.113:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.113:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.113: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-06-29T00:10:54.763626Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-29T00:10:54.763641Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-06-29T00:10:54.763665Z","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-06-29T00:10:54.764367Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"]} {"level":"info","ts":"2026-06-29T00:10:54.764478Z","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.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.113: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-06-29T00:10:54.768331Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.569148ms"} {"level":"info","ts":"2026-06-29T00:10:54.77319Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"a31e123290fcf0df","cluster-id":"37661d6ee45ed9e8"} {"level":"info","ts":"2026-06-29T00:10:54.773273Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=()"} {"level":"info","ts":"2026-06-29T00:10:54.773307Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 0"} {"level":"info","ts":"2026-06-29T00:10:54.773325Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft a31e123290fcf0df [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-29T00:10:54.773346Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became follower at term 1"} {"level":"info","ts":"2026-06-29T00:10:54.773405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"warn","ts":"2026-06-29T00:10:54.778545Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-29T00:10:54.779886Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-29T00:10:54.78097Z","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-06-29T00:10:54.786637Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"a31e123290fcf0df","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-29T00:10:54.787496Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"a31e123290fcf0df","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-29T00:10:54.787939Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df switched to configuration voters=(11753852085873733855)"} {"level":"info","ts":"2026-06-29T00:10:54.789243Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","added-peer-id":"a31e123290fcf0df","added-peer-peer-urls":["https://199.204.45.113:2380"]} {"level":"info","ts":"2026-06-29T00:10:54.789435Z","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-06-29T00:10:54.789478Z","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-06-29T00:10:54.789508Z","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-06-29T00:10:54.790972Z","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-06-29T00:10:54.791102Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-06-29T00:10:54.791155Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.113:2380"} {"level":"info","ts":"2026-06-29T00:10:54.791265Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"a31e123290fcf0df","initial-advertise-peer-urls":["https://199.204.45.113:2380"],"listen-peer-urls":["https://199.204.45.113:2380"],"advertise-client-urls":["https://199.204.45.113:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.113:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-29T00:10:54.791306Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-29T00:10:54.974386Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df is starting a new election at term 1"} {"level":"info","ts":"2026-06-29T00:10:54.974522Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became pre-candidate at term 1"} {"level":"info","ts":"2026-06-29T00:10:54.974548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgPreVoteResp from a31e123290fcf0df at term 1"} {"level":"info","ts":"2026-06-29T00:10:54.97456Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became candidate at term 2"} {"level":"info","ts":"2026-06-29T00:10:54.974566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df received MsgVoteResp from a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-06-29T00:10:54.974652Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"a31e123290fcf0df became leader at term 2"} {"level":"info","ts":"2026-06-29T00:10:54.974674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: a31e123290fcf0df elected leader a31e123290fcf0df at term 2"} {"level":"info","ts":"2026-06-29T00:10:54.975858Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-29T00:10:54.977135Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-29T00:10:54.977134Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"a31e123290fcf0df","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.113:2379]}","request-path":"/0/members/a31e123290fcf0df/attributes","cluster-id":"37661d6ee45ed9e8","publish-timeout":"7s"} {"level":"info","ts":"2026-06-29T00:10:54.977497Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"37661d6ee45ed9e8","local-member-id":"a31e123290fcf0df","cluster-version":"3.5"} {"level":"info","ts":"2026-06-29T00:10:54.977678Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-29T00:10:54.977746Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-29T00:10:54.977766Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-29T00:10:54.978314Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-29T00:10:54.978351Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-29T00:10:54.983063Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.113:2379"} {"level":"info","ts":"2026-06-29T00:10:54.983849Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-06-29T00:11:07.060789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"384.184076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" ","response":"range_response_count:1 size:6965"} {"level":"info","ts":"2026-06-29T00:11:07.060911Z","caller":"traceutil/trace.go:171","msg":"trace[1104224578] range","detail":"{range_begin:/registry/pods/kube-system/kube-controller-manager-instance; range_end:; response_count:1; response_revision:356; }","duration":"384.36455ms","start":"2026-06-29T00:11:06.676529Z","end":"2026-06-29T00:11:07.060893Z","steps":["trace[1104224578] 'range keys from in-memory index tree' (duration: 383.841278ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T00:11:07.061035Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T00:11:06.676509Z","time spent":"384.515253ms","remote":"127.0.0.1:36610","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":1,"response size":6988,"request content":"key:\"/registry/pods/kube-system/kube-controller-manager-instance\" "} {"level":"warn","ts":"2026-06-29T00:11:07.061761Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.904126ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-29T00:11:07.06191Z","caller":"traceutil/trace.go:171","msg":"trace[540286233] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"234.256474ms","start":"2026-06-29T00:11:06.827634Z","end":"2026-06-29T00:11:07.061891Z","steps":["trace[540286233] 'read index received' (duration: 47.982µs)","trace[540286233] 'applied index is now lower than readState.Index' (duration: 234.207132ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T00:11:07.062042Z","caller":"traceutil/trace.go:171","msg":"trace[209182793] transaction","detail":"{read_only:false; response_revision:357; number_of_response:1; }","duration":"308.063828ms","start":"2026-06-29T00:11:06.753966Z","end":"2026-06-29T00:11:07.06203Z","steps":["trace[209182793] 'process raft request' (duration: 18.888992ms)","trace[209182793] 'compare' (duration: 287.756333ms)"],"step_count":2} {"level":"warn","ts":"2026-06-29T00:11:07.062335Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.647773ms","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-06-29T00:11:07.062849Z","caller":"traceutil/trace.go:171","msg":"trace[827667434] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:357; }","duration":"235.252847ms","start":"2026-06-29T00:11:06.827576Z","end":"2026-06-29T00:11:07.062829Z","steps":["trace[827667434] 'agreement among raft nodes before linearized reading' (duration: 234.654573ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T00:11:07.062845Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T00:11:06.753951Z","time spent":"308.818205ms","remote":"127.0.0.1:36678","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-29T00:11:07.248065Z","caller":"traceutil/trace.go:171","msg":"trace[2042697466] transaction","detail":"{read_only:false; response_revision:358; number_of_response:1; }","duration":"182.131468ms","start":"2026-06-29T00:11:07.065915Z","end":"2026-06-29T00:11:07.248047Z","steps":["trace[2042697466] 'process raft request' (duration: 170.41841ms)","trace[2042697466] 'compare' (duration: 11.635326ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T00:11:07.253123Z","caller":"traceutil/trace.go:171","msg":"trace[76664357] transaction","detail":"{read_only:false; response_revision:359; number_of_response:1; }","duration":"187.118595ms","start":"2026-06-29T00:11:07.065996Z","end":"2026-06-29T00:11:07.253115Z","steps":["trace[76664357] 'process raft request' (duration: 187.028613ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:11:07.257248Z","caller":"traceutil/trace.go:171","msg":"trace[1813976008] linearizableReadLoop","detail":"{readStateIndex:372; appliedIndex:369; }","duration":"164.47179ms","start":"2026-06-29T00:11:07.092724Z","end":"2026-06-29T00:11:07.257195Z","steps":["trace[1813976008] 'read index received' (duration: 143.625287ms)","trace[1813976008] 'applied index is now lower than readState.Index' (duration: 20.845193ms)"],"step_count":2} {"level":"warn","ts":"2026-06-29T00:11:07.257415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.678005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-29T00:11:07.257335Z","caller":"traceutil/trace.go:171","msg":"trace[434703750] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"186.630814ms","start":"2026-06-29T00:11:07.070652Z","end":"2026-06-29T00:11:07.257283Z","steps":["trace[434703750] 'process raft request' (duration: 182.430304ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:11:07.2575Z","caller":"traceutil/trace.go:171","msg":"trace[2014044493] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:360; }","duration":"164.759967ms","start":"2026-06-29T00:11:07.092681Z","end":"2026-06-29T00:11:07.257441Z","steps":["trace[2014044493] 'agreement among raft nodes before linearized reading' (duration: 164.632774ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:11:30.678964Z","caller":"traceutil/trace.go:171","msg":"trace[2110757041] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"163.912847ms","start":"2026-06-29T00:11:30.515033Z","end":"2026-06-29T00:11:30.678946Z","steps":["trace[2110757041] 'process raft request' (duration: 163.835666ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:11:30.684684Z","caller":"traceutil/trace.go:171","msg":"trace[1409469230] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"155.403175ms","start":"2026-06-29T00:11:30.529253Z","end":"2026-06-29T00:11:30.684657Z","steps":["trace[1409469230] 'process raft request' (duration: 155.180651ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:11:30.684833Z","caller":"traceutil/trace.go:171","msg":"trace[1326700714] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"151.42949ms","start":"2026-06-29T00:11:30.533387Z","end":"2026-06-29T00:11:30.684816Z","steps":["trace[1326700714] 'process raft request' (duration: 151.146335ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:11:30.685271Z","caller":"traceutil/trace.go:171","msg":"trace[668404595] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"143.971158ms","start":"2026-06-29T00:11:30.541022Z","end":"2026-06-29T00:11:30.684993Z","steps":["trace[668404595] 'process raft request' (duration: 143.792835ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:11:30.685345Z","caller":"traceutil/trace.go:171","msg":"trace[725629684] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"146.915475ms","start":"2026-06-29T00:11:30.538043Z","end":"2026-06-29T00:11:30.684958Z","steps":["trace[725629684] 'process raft request' (duration: 146.580178ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:11:30.688574Z","caller":"traceutil/trace.go:171","msg":"trace[720389004] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"145.332014ms","start":"2026-06-29T00:11:30.543074Z","end":"2026-06-29T00:11:30.688406Z","steps":["trace[720389004] 'process raft request' (duration: 142.673033ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:11:30.690173Z","caller":"traceutil/trace.go:171","msg":"trace[1612253503] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"146.1875ms","start":"2026-06-29T00:11:30.543964Z","end":"2026-06-29T00:11:30.690152Z","steps":["trace[1612253503] 'process raft request' (duration: 144.166162ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:11:30.690386Z","caller":"traceutil/trace.go:171","msg":"trace[767880181] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"145.236231ms","start":"2026-06-29T00:11:30.545132Z","end":"2026-06-29T00:11:30.690368Z","steps":["trace[767880181] 'process raft request' (duration: 143.440077ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:11:30.690593Z","caller":"traceutil/trace.go:171","msg":"trace[210961334] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"141.560922ms","start":"2026-06-29T00:11:30.549016Z","end":"2026-06-29T00:11:30.690577Z","steps":["trace[210961334] 'process raft request' (duration: 140.799097ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:11:36.862105Z","caller":"traceutil/trace.go:171","msg":"trace[850409972] transaction","detail":"{read_only:false; response_revision:589; number_of_response:1; }","duration":"103.00052ms","start":"2026-06-29T00:11:36.75908Z","end":"2026-06-29T00:11:36.862081Z","steps":["trace[850409972] 'process raft request' (duration: 102.694204ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:11:37.094825Z","caller":"traceutil/trace.go:171","msg":"trace[255384012] transaction","detail":"{read_only:false; response_revision:591; number_of_response:1; }","duration":"141.237107ms","start":"2026-06-29T00:11:36.953522Z","end":"2026-06-29T00:11:37.094759Z","steps":["trace[255384012] 'process raft request' (duration: 140.451563ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:11:37.661587Z","caller":"traceutil/trace.go:171","msg":"trace[956800548] linearizableReadLoop","detail":"{readStateIndex:615; appliedIndex:614; }","duration":"248.788105ms","start":"2026-06-29T00:11:37.412775Z","end":"2026-06-29T00:11:37.661563Z","steps":["trace[956800548] 'read index received' (duration: 192.957176ms)","trace[956800548] 'applied index is now lower than readState.Index' (duration: 55.829909ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T00:11:37.661787Z","caller":"traceutil/trace.go:171","msg":"trace[615622723] transaction","detail":"{read_only:false; response_revision:592; number_of_response:1; }","duration":"507.204722ms","start":"2026-06-29T00:11:37.154438Z","end":"2026-06-29T00:11:37.661643Z","steps":["trace[615622723] 'process raft request' (duration: 451.28144ms)","trace[615622723] 'compare' (duration: 55.485754ms)"],"step_count":2} {"level":"warn","ts":"2026-06-29T00:11:37.66186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.703925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-06-29T00:11:37.662003Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T00:11:37.154416Z","time spent":"507.499396ms","remote":"127.0.0.1:36662","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-06-29T00:11:37.66212Z","caller":"traceutil/trace.go:171","msg":"trace[601604852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:592; }","duration":"242.952629ms","start":"2026-06-29T00:11:37.419133Z","end":"2026-06-29T00:11:37.662086Z","steps":["trace[601604852] 'agreement among raft nodes before linearized reading' (duration: 242.587493ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T00:11:37.66241Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.6293ms","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-06-29T00:11:37.66262Z","caller":"traceutil/trace.go:171","msg":"trace[1074599824] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:592; }","duration":"249.779394ms","start":"2026-06-29T00:11:37.412708Z","end":"2026-06-29T00:11:37.662487Z","steps":["trace[1074599824] 'agreement among raft nodes before linearized reading' (duration: 248.997319ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:11:51.088717Z","caller":"traceutil/trace.go:171","msg":"trace[466770298] linearizableReadLoop","detail":"{readStateIndex:770; appliedIndex:769; }","duration":"145.880855ms","start":"2026-06-29T00:11:50.942813Z","end":"2026-06-29T00:11:51.088694Z","steps":["trace[466770298] 'read index received' (duration: 145.661961ms)","trace[466770298] 'applied index is now lower than readState.Index' (duration: 217.964µs)"],"step_count":2} {"level":"warn","ts":"2026-06-29T00:11:51.088977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.110878ms","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-06-29T00:11:51.089016Z","caller":"traceutil/trace.go:171","msg":"trace[1157670167] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:743; }","duration":"146.234941ms","start":"2026-06-29T00:11:50.942771Z","end":"2026-06-29T00:11:51.089006Z","steps":["trace[1157670167] 'agreement among raft nodes before linearized reading' (duration: 146.022207ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:11:51.089309Z","caller":"traceutil/trace.go:171","msg":"trace[536208983] transaction","detail":"{read_only:false; response_revision:743; number_of_response:1; }","duration":"245.507823ms","start":"2026-06-29T00:11:50.843761Z","end":"2026-06-29T00:11:51.089269Z","steps":["trace[536208983] 'process raft request' (duration: 244.808822ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:13:12.380234Z","caller":"traceutil/trace.go:171","msg":"trace[278027972] transaction","detail":"{read_only:false; response_revision:1505; number_of_response:1; }","duration":"122.260726ms","start":"2026-06-29T00:13:12.257946Z","end":"2026-06-29T00:13:12.380207Z","steps":["trace[278027972] 'process raft request' (duration: 122.025834ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T00:13:38.735282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"243.612878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-29T00:13:38.735499Z","caller":"traceutil/trace.go:171","msg":"trace[123793771] linearizableReadLoop","detail":"{readStateIndex:2003; appliedIndex:2002; }","duration":"233.442978ms","start":"2026-06-29T00:13:38.502031Z","end":"2026-06-29T00:13:38.735474Z","steps":["trace[123793771] 'read index received' (duration: 67.231µs)","trace[123793771] 'applied index is now lower than readState.Index' (duration: 233.372747ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T00:13:38.735495Z","caller":"traceutil/trace.go:171","msg":"trace[1169177258] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"270.717824ms","start":"2026-06-29T00:13:38.464746Z","end":"2026-06-29T00:13:38.735464Z","steps":["trace[1169177258] 'process raft request' (duration: 26.840623ms)","trace[1169177258] 'compare' (duration: 243.502227ms)"],"step_count":2} {"level":"warn","ts":"2026-06-29T00:13:38.735719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.67473ms","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-06-29T00:13:38.735768Z","caller":"traceutil/trace.go:171","msg":"trace[514738521] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1927; }","duration":"233.777481ms","start":"2026-06-29T00:13:38.50198Z","end":"2026-06-29T00:13:38.735758Z","steps":["trace[514738521] 'agreement among raft nodes before linearized reading' (duration: 233.628089ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T00:13:38.735905Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.346521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-06-29T00:13:38.735961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.415694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ded8fef6-c166-4b47-820f-b998a1c46979\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-06-29T00:13:38.735972Z","caller":"traceutil/trace.go:171","msg":"trace[2101253753] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1927; }","duration":"112.459903ms","start":"2026-06-29T00:13:38.623495Z","end":"2026-06-29T00:13:38.735955Z","steps":["trace[2101253753] 'agreement among raft nodes before linearized reading' (duration: 112.270351ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:13:38.736008Z","caller":"traceutil/trace.go:171","msg":"trace[1997762707] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ded8fef6-c166-4b47-820f-b998a1c46979; range_end:; response_count:1; response_revision:1927; }","duration":"207.477394ms","start":"2026-06-29T00:13:38.528515Z","end":"2026-06-29T00:13:38.735993Z","steps":["trace[1997762707] 'agreement among raft nodes before linearized reading' (duration: 207.263762ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T00:13:38.736187Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.068094ms","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-06-29T00:13:38.736792Z","caller":"traceutil/trace.go:171","msg":"trace[2075085076] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1927; }","duration":"234.206305ms","start":"2026-06-29T00:13:38.502071Z","end":"2026-06-29T00:13:38.736277Z","steps":["trace[2075085076] 'agreement among raft nodes before linearized reading' (duration: 233.65641ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T00:13:39.232607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.552312ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-29T00:13:39.23277Z","caller":"traceutil/trace.go:171","msg":"trace[1344934389] linearizableReadLoop","detail":"{readStateIndex:2008; appliedIndex:2007; }","duration":"331.297273ms","start":"2026-06-29T00:13:38.901448Z","end":"2026-06-29T00:13:39.232745Z","steps":["trace[1344934389] 'read index received' (duration: 34.394498ms)","trace[1344934389] 'applied index is now lower than readState.Index' (duration: 296.901135ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T00:13:39.232778Z","caller":"traceutil/trace.go:171","msg":"trace[643261696] transaction","detail":"{read_only:false; response_revision:1931; number_of_response:1; }","duration":"406.409839ms","start":"2026-06-29T00:13:38.826332Z","end":"2026-06-29T00:13:39.232742Z","steps":["trace[643261696] 'process raft request' (duration: 109.580714ms)","trace[643261696] 'compare' (duration: 296.326479ms)"],"step_count":2} {"level":"warn","ts":"2026-06-29T00:13:39.23302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.473702ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodes/\" range_end:\"/registry/cilium.io/ciliumnodes0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-29T00:13:39.233024Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T00:13:38.826295Z","time spent":"406.5756ms","remote":"127.0.0.1:36678","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-06-29T00:13:39.233073Z","caller":"traceutil/trace.go:171","msg":"trace[1848214096] range","detail":"{range_begin:/registry/cilium.io/ciliumnodes/; range_end:/registry/cilium.io/ciliumnodes0; response_count:0; response_revision:1931; }","duration":"295.568183ms","start":"2026-06-29T00:13:38.937488Z","end":"2026-06-29T00:13:39.233056Z","steps":["trace[1848214096] 'agreement among raft nodes before linearized reading' (duration: 295.404421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T00:13:39.233107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.632346ms","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-06-29T00:13:39.233228Z","caller":"traceutil/trace.go:171","msg":"trace[1759718548] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1931; }","duration":"331.805728ms","start":"2026-06-29T00:13:38.901386Z","end":"2026-06-29T00:13:39.233192Z","steps":["trace[1759718548] 'agreement among raft nodes before linearized reading' (duration: 331.461105ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T00:13:39.233307Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-29T00:13:38.90136Z","time spent":"331.927979ms","remote":"127.0.0.1:52810","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5435,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-06-29T00:13:39.233389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.931489ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-29T00:13:39.233447Z","caller":"traceutil/trace.go:171","msg":"trace[614746462] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:1931; }","duration":"231.02242ms","start":"2026-06-29T00:13:39.002414Z","end":"2026-06-29T00:13:39.233436Z","steps":["trace[614746462] 'agreement among raft nodes before linearized reading' (duration: 230.915259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T00:13:39.233419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.921458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-29T00:13:39.234034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.819831ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-29T00:13:39.234068Z","caller":"traceutil/trace.go:171","msg":"trace[1005405648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1931; }","duration":"140.576755ms","start":"2026-06-29T00:13:39.093467Z","end":"2026-06-29T00:13:39.234044Z","steps":["trace[1005405648] 'agreement among raft nodes before linearized reading' (duration: 139.570646ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:13:39.234126Z","caller":"traceutil/trace.go:171","msg":"trace[426881863] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1931; }","duration":"169.950223ms","start":"2026-06-29T00:13:39.064155Z","end":"2026-06-29T00:13:39.234105Z","steps":["trace[426881863] 'agreement among raft nodes before linearized reading' (duration: 169.235716ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:13:45.980778Z","caller":"traceutil/trace.go:171","msg":"trace[452252581] transaction","detail":"{read_only:false; response_revision:1998; number_of_response:1; }","duration":"116.580085ms","start":"2026-06-29T00:13:45.864171Z","end":"2026-06-29T00:13:45.980751Z","steps":["trace[452252581] 'process raft request' (duration: 116.393093ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:14:13.073324Z","caller":"traceutil/trace.go:171","msg":"trace[962000194] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"119.692522ms","start":"2026-06-29T00:14:12.953591Z","end":"2026-06-29T00:14:13.073283Z","steps":["trace[962000194] 'process raft request' (duration: 118.990486ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T00:14:13.339366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.234755ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-29T00:14:13.339648Z","caller":"traceutil/trace.go:171","msg":"trace[1302996918] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"259.765161ms","start":"2026-06-29T00:14:13.079858Z","end":"2026-06-29T00:14:13.339623Z","steps":["trace[1302996918] 'process raft request' (duration: 259.65036ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:14:13.339914Z","caller":"traceutil/trace.go:171","msg":"trace[2061496831] transaction","detail":"{read_only:false; response_revision:2168; number_of_response:1; }","duration":"140.369241ms","start":"2026-06-29T00:14:13.199518Z","end":"2026-06-29T00:14:13.339887Z","steps":["trace[2061496831] 'process raft request' (duration: 140.055088ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:14:13.339966Z","caller":"traceutil/trace.go:171","msg":"trace[2146722011] transaction","detail":"{read_only:false; response_revision:2166; number_of_response:1; }","duration":"262.645727ms","start":"2026-06-29T00:14:13.077304Z","end":"2026-06-29T00:14:13.339949Z","steps":["trace[2146722011] 'process raft request' (duration: 126.738576ms)","trace[2146722011] 'compare' (duration: 135.084714ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T00:14:13.586273Z","caller":"traceutil/trace.go:171","msg":"trace[748416003] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"242.613863ms","start":"2026-06-29T00:14:13.343638Z","end":"2026-06-29T00:14:13.586252Z","steps":["trace[748416003] 'process raft request' (duration: 166.479493ms)","trace[748416003] 'compare' (duration: 75.554255ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T00:14:13.586035Z","caller":"traceutil/trace.go:171","msg":"trace[1591755076] linearizableReadLoop","detail":"{readStateIndex:2253; appliedIndex:2252; }","duration":"153.911056ms","start":"2026-06-29T00:14:13.432101Z","end":"2026-06-29T00:14:13.586012Z","steps":["trace[1591755076] 'read index received' (duration: 78.007938ms)","trace[1591755076] 'applied index is now lower than readState.Index' (duration: 75.902068ms)"],"step_count":2} {"level":"warn","ts":"2026-06-29T00:14:13.673584Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.443062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-29T00:14:13.673687Z","caller":"traceutil/trace.go:171","msg":"trace[248703227] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2169; }","duration":"241.618834ms","start":"2026-06-29T00:14:13.432041Z","end":"2026-06-29T00:14:13.67366Z","steps":["trace[248703227] 'agreement among raft nodes before linearized reading' (duration: 154.536322ms)","trace[248703227] 'range keys from in-memory index tree' (duration: 86.9068ms)"],"step_count":2} {"level":"warn","ts":"2026-06-29T00:14:13.673699Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.227893ms","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":"warn","ts":"2026-06-29T00:14:13.673723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.419463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-29T00:14:13.673787Z","caller":"traceutil/trace.go:171","msg":"trace[263212166] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2169; }","duration":"199.370875ms","start":"2026-06-29T00:14:13.474391Z","end":"2026-06-29T00:14:13.673762Z","steps":["trace[263212166] 'agreement among raft nodes before linearized reading' (duration: 199.135043ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:14:13.673845Z","caller":"traceutil/trace.go:171","msg":"trace[803363989] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:2169; }","duration":"178.637574ms","start":"2026-06-29T00:14:13.495183Z","end":"2026-06-29T00:14:13.67382Z","steps":["trace[803363989] 'agreement among raft nodes before linearized reading' (duration: 178.378942ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T00:15:21.306176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.609494ms","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-06-29T00:15:21.30634Z","caller":"traceutil/trace.go:171","msg":"trace[1882863133] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2632; }","duration":"168.955781ms","start":"2026-06-29T00:15:21.137351Z","end":"2026-06-29T00:15:21.306306Z","steps":["trace[1882863133] 'range keys from in-memory index tree' (duration: 168.367938ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:19:40.169538Z","caller":"traceutil/trace.go:171","msg":"trace[948819203] transaction","detail":"{read_only:false; response_revision:4622; number_of_response:1; }","duration":"107.030171ms","start":"2026-06-29T00:19:40.062472Z","end":"2026-06-29T00:19:40.169502Z","steps":["trace[948819203] 'process raft request' (duration: 106.334633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-29T00:19:40.171386Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.893689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-06-29T00:19:40.171471Z","caller":"traceutil/trace.go:171","msg":"trace[1607804911] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4622; }","duration":"102.00886ms","start":"2026-06-29T00:19:40.069441Z","end":"2026-06-29T00:19:40.17145Z","steps":["trace[1607804911] 'agreement among raft nodes before linearized reading' (duration: 99.606261ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:19:40.530933Z","caller":"traceutil/trace.go:171","msg":"trace[563214263] transaction","detail":"{read_only:false; response_revision:4625; number_of_response:1; }","duration":"180.835408ms","start":"2026-06-29T00:19:40.350072Z","end":"2026-06-29T00:19:40.530907Z","steps":["trace[563214263] 'process raft request' (duration: 180.518434ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:19:40.530928Z","caller":"traceutil/trace.go:171","msg":"trace[1636297001] linearizableReadLoop","detail":"{readStateIndex:4804; appliedIndex:4803; }","duration":"118.040405ms","start":"2026-06-29T00:19:40.412832Z","end":"2026-06-29T00:19:40.530872Z","steps":["trace[1636297001] 'read index received' (duration: 116.589908ms)","trace[1636297001] 'applied index is now lower than readState.Index' (duration: 1.449307ms)"],"step_count":2} {"level":"warn","ts":"2026-06-29T00:19:40.531291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.405809ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-ee8fcaac-2f50-402b-bf4e-414b23e5e7fd\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-06-29T00:19:40.531346Z","caller":"traceutil/trace.go:171","msg":"trace[2115127156] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-ee8fcaac-2f50-402b-bf4e-414b23e5e7fd; range_end:; response_count:1; response_revision:4625; }","duration":"118.572661ms","start":"2026-06-29T00:19:40.412759Z","end":"2026-06-29T00:19:40.531332Z","steps":["trace[2115127156] 'agreement among raft nodes before linearized reading' (duration: 118.269108ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:19:48.383174Z","caller":"traceutil/trace.go:171","msg":"trace[1635807752] transaction","detail":"{read_only:false; response_revision:4807; number_of_response:1; }","duration":"169.429183ms","start":"2026-06-29T00:19:48.213725Z","end":"2026-06-29T00:19:48.383154Z","steps":["trace[1635807752] 'process raft request' (duration: 169.294852ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:19:57.465871Z","caller":"traceutil/trace.go:171","msg":"trace[2127247150] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"119.978055ms","start":"2026-06-29T00:19:57.345871Z","end":"2026-06-29T00:19:57.465849Z","steps":["trace[2127247150] 'process raft request' (duration: 85.861576ms)","trace[2127247150] 'compare' (duration: 33.926176ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T00:19:57.466152Z","caller":"traceutil/trace.go:171","msg":"trace[1095604815] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"119.637781ms","start":"2026-06-29T00:19:57.346484Z","end":"2026-06-29T00:19:57.466122Z","steps":["trace[1095604815] 'process raft request' (duration: 119.310747ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:19:57.466401Z","caller":"traceutil/trace.go:171","msg":"trace[665091358] transaction","detail":"{read_only:false; response_revision:4909; number_of_response:1; }","duration":"118.422305ms","start":"2026-06-29T00:19:57.347924Z","end":"2026-06-29T00:19:57.466347Z","steps":["trace[665091358] 'process raft request' (duration: 118.096142ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:19:57.467391Z","caller":"traceutil/trace.go:171","msg":"trace[439127892] transaction","detail":"{read_only:false; response_revision:4910; number_of_response:1; }","duration":"117.196021ms","start":"2026-06-29T00:19:57.350179Z","end":"2026-06-29T00:19:57.467375Z","steps":["trace[439127892] 'process raft request' (duration: 116.114529ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:20:25.450984Z","caller":"traceutil/trace.go:171","msg":"trace[1639940931] transaction","detail":"{read_only:false; response_revision:5157; number_of_response:1; }","duration":"148.858019ms","start":"2026-06-29T00:20:25.302098Z","end":"2026-06-29T00:20:25.450956Z","steps":["trace[1639940931] 'process raft request' (duration: 123.820757ms)","trace[1639940931] 'compare' (duration: 24.530656ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T00:20:25.451112Z","caller":"traceutil/trace.go:171","msg":"trace[1613495566] linearizableReadLoop","detail":"{readStateIndex:5352; appliedIndex:5351; }","duration":"147.623145ms","start":"2026-06-29T00:20:25.303447Z","end":"2026-06-29T00:20:25.45107Z","steps":["trace[1613495566] 'read index received' (duration: 122.488371ms)","trace[1613495566] 'applied index is now lower than readState.Index' (duration: 25.132594ms)"],"step_count":2} {"level":"warn","ts":"2026-06-29T00:20:25.451258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.793007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/roles/openstack/keystone-openstack-keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-29T00:20:25.451309Z","caller":"traceutil/trace.go:171","msg":"trace[1359906897] range","detail":"{range_begin:/registry/roles/openstack/keystone-openstack-keystone-db-sync; range_end:; response_count:0; response_revision:5158; }","duration":"147.889867ms","start":"2026-06-29T00:20:25.303406Z","end":"2026-06-29T00:20:25.451296Z","steps":["trace[1359906897] 'agreement among raft nodes before linearized reading' (duration: 147.779266ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:20:55.098364Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2820} {"level":"info","ts":"2026-06-29T00:20:55.199974Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2820,"took":"100.678212ms","hash":2073452273,"current-db-size-bytes":53018624,"current-db-size":"53 MB","current-db-size-in-use-bytes":28815360,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-06-29T00:20:55.200043Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2073452273,"revision":2820,"compact-revision":-1} {"level":"warn","ts":"2026-06-29T00:23:39.901431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.651772ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/horizon-db-sync-4r596\" ","response":"range_response_count:1 size:6548"} {"level":"info","ts":"2026-06-29T00:23:39.901516Z","caller":"traceutil/trace.go:171","msg":"trace[1507116937] range","detail":"{range_begin:/registry/pods/openstack/horizon-db-sync-4r596; range_end:; response_count:1; response_revision:6588; }","duration":"104.785426ms","start":"2026-06-29T00:23:39.796713Z","end":"2026-06-29T00:23:39.901498Z","steps":["trace[1507116937] 'range keys from in-memory index tree' (duration: 104.500608ms)"],"step_count":1} {"level":"info","ts":"2026-06-29T00:23:40.050457Z","caller":"traceutil/trace.go:171","msg":"trace[1186156666] transaction","detail":"{read_only:false; response_revision:6590; number_of_response:1; }","duration":"114.51972ms","start":"2026-06-29T00:23:39.935917Z","end":"2026-06-29T00:23:40.050437Z","steps":["trace[1186156666] 'process raft request' (duration: 90.176923ms)","trace[1186156666] 'compare' (duration: 24.007357ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T00:24:05.011017Z","caller":"traceutil/trace.go:171","msg":"trace[1377288278] transaction","detail":"{read_only:false; response_revision:6773; number_of_response:1; }","duration":"107.58577ms","start":"2026-06-29T00:24:04.903392Z","end":"2026-06-29T00:24:05.010978Z","steps":["trace[1377288278] 'process raft request' (duration: 57.92744ms)","trace[1377288278] 'compare' (duration: 49.222158ms)"],"step_count":2} {"level":"info","ts":"2026-06-29T00:25:55.10647Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5454} {"level":"info","ts":"2026-06-29T00:25:55.233709Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5454,"took":"125.652467ms","hash":815307721,"current-db-size-bytes":53018624,"current-db-size":"53 MB","current-db-size-in-use-bytes":18575360,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-06-29T00:25:55.233772Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":815307721,"revision":5454,"compact-revision":2820}