{"level":"warn","ts":"2026-08-31T07:19:03.655268Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-31T07:19:03.655373Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.118:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://162.253.55.118:2380","--initial-cluster=instance=https://162.253.55.118:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.118:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.118: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-08-31T07:19:03.65545Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-31T07:19:03.655465Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.118:2380"]} {"level":"info","ts":"2026-08-31T07:19:03.655568Z","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-08-31T07:19:03.656275Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"]} {"level":"info","ts":"2026-08-31T07:19:03.656364Z","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://162.253.55.118:2380"],"listen-peer-urls":["https://162.253.55.118:2380"],"advertise-client-urls":["https://162.253.55.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.118: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-08-31T07:19:03.660807Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.195569ms"} {"level":"info","ts":"2026-08-31T07:19:03.673918Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"39c975536cfc1086","cluster-id":"ac0a95643fc422f0"} {"level":"info","ts":"2026-08-31T07:19:03.674051Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=()"} {"level":"info","ts":"2026-08-31T07:19:03.674083Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became follower at term 0"} {"level":"info","ts":"2026-08-31T07:19:03.674114Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 39c975536cfc1086 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-31T07:19:03.674136Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became follower at term 1"} {"level":"info","ts":"2026-08-31T07:19:03.674214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=(4163988331651928198)"} {"level":"warn","ts":"2026-08-31T07:19:03.684851Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-31T07:19:03.686395Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-31T07:19:03.687898Z","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-08-31T07:19:03.689988Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"39c975536cfc1086","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-31T07:19:03.693641Z","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-08-31T07:19:03.69368Z","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-08-31T07:19:03.693693Z","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-08-31T07:19:03.69395Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"39c975536cfc1086","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-31T07:19:03.694521Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 switched to configuration voters=(4163988331651928198)"} {"level":"info","ts":"2026-08-31T07:19:03.697551Z","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-08-31T07:19:03.697733Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"39c975536cfc1086","initial-advertise-peer-urls":["https://162.253.55.118:2380"],"listen-peer-urls":["https://162.253.55.118:2380"],"advertise-client-urls":["https://162.253.55.118:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.118:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-31T07:19:03.697764Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-31T07:19:03.697706Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"ac0a95643fc422f0","local-member-id":"39c975536cfc1086","added-peer-id":"39c975536cfc1086","added-peer-peer-urls":["https://162.253.55.118:2380"]} {"level":"info","ts":"2026-08-31T07:19:03.697827Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.118:2380"} {"level":"info","ts":"2026-08-31T07:19:03.697847Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.118:2380"} {"level":"info","ts":"2026-08-31T07:19:04.375125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 is starting a new election at term 1"} {"level":"info","ts":"2026-08-31T07:19:04.375214Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became pre-candidate at term 1"} {"level":"info","ts":"2026-08-31T07:19:04.375272Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 received MsgPreVoteResp from 39c975536cfc1086 at term 1"} {"level":"info","ts":"2026-08-31T07:19:04.375298Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became candidate at term 2"} {"level":"info","ts":"2026-08-31T07:19:04.375317Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 received MsgVoteResp from 39c975536cfc1086 at term 2"} {"level":"info","ts":"2026-08-31T07:19:04.375337Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"39c975536cfc1086 became leader at term 2"} {"level":"info","ts":"2026-08-31T07:19:04.375356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 39c975536cfc1086 elected leader 39c975536cfc1086 at term 2"} {"level":"info","ts":"2026-08-31T07:19:04.376989Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T07:19:04.377972Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"39c975536cfc1086","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.118:2379]}","request-path":"/0/members/39c975536cfc1086/attributes","cluster-id":"ac0a95643fc422f0","publish-timeout":"7s"} {"level":"info","ts":"2026-08-31T07:19:04.378195Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T07:19:04.378288Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-31T07:19:04.378898Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"ac0a95643fc422f0","local-member-id":"39c975536cfc1086","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T07:19:04.379095Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T07:19:04.379145Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-31T07:19:04.379631Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-31T07:19:04.379697Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-31T07:19:04.38137Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.118:2379"} {"level":"info","ts":"2026-08-31T07:19:04.381873Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-31T07:19:07.71459Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.379789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:19:07.714783Z","caller":"traceutil/trace.go:171","msg":"trace[156900592] transaction","detail":"{read_only:false; response_revision:178; number_of_response:1; }","duration":"280.178382ms","start":"2026-08-31T07:19:07.434579Z","end":"2026-08-31T07:19:07.714757Z","steps":["trace[156900592] 'process raft request' (duration: 51.711582ms)","trace[156900592] 'compare' (duration: 227.164524ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:19:08.242662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.276557ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:19:08.24279Z","caller":"traceutil/trace.go:171","msg":"trace[1292530944] linearizableReadLoop","detail":"{readStateIndex:184; appliedIndex:183; }","duration":"523.868837ms","start":"2026-08-31T07:19:07.718889Z","end":"2026-08-31T07:19:08.242758Z","steps":["trace[1292530944] 'read index received' (duration: 142.324803ms)","trace[1292530944] 'applied index is now lower than readState.Index' (duration: 381.542894ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:19:08.242879Z","caller":"traceutil/trace.go:171","msg":"trace[873140067] transaction","detail":"{read_only:false; response_revision:179; number_of_response:1; }","duration":"724.034309ms","start":"2026-08-31T07:19:07.518778Z","end":"2026-08-31T07:19:08.242812Z","steps":["trace[873140067] 'process raft request' (duration: 342.541807ms)","trace[873140067] 'compare' (duration: 381.102242ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:19:08.242968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"416.947147ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:19:08.243051Z","caller":"traceutil/trace.go:171","msg":"trace[2118571258] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:179; }","duration":"417.075151ms","start":"2026-08-31T07:19:07.825957Z","end":"2026-08-31T07:19:08.243032Z","steps":["trace[2118571258] 'agreement among raft nodes before linearized reading' (duration: 416.933368ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:19:08.243087Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:19:07.82593Z","time spent":"417.144663ms","remote":"127.0.0.1:48262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-31T07:19:08.243141Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:19:07.518754Z","time spent":"724.257345ms","remote":"127.0.0.1:48402","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-31T07:19:08.243095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"524.159765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/system:discovery\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:19:08.243235Z","caller":"traceutil/trace.go:171","msg":"trace[926302906] range","detail":"{range_begin:/registry/clusterrolebindings/system:discovery; range_end:; response_count:0; response_revision:179; }","duration":"524.37803ms","start":"2026-08-31T07:19:07.718829Z","end":"2026-08-31T07:19:08.243207Z","steps":["trace[926302906] 'agreement among raft nodes before linearized reading' (duration: 524.082952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:19:08.243292Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:19:07.718806Z","time spent":"524.472644ms","remote":"127.0.0.1:48586","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":0,"response size":28,"request content":"key:\"/registry/clusterrolebindings/system:discovery\" "} {"level":"warn","ts":"2026-08-31T07:19:08.585451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.604817ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:19:08.585632Z","caller":"traceutil/trace.go:171","msg":"trace[957560964] transaction","detail":"{read_only:false; response_revision:181; number_of_response:1; }","duration":"338.203671ms","start":"2026-08-31T07:19:08.24741Z","end":"2026-08-31T07:19:08.585613Z","steps":["trace[957560964] 'process raft request' (duration: 338.142819ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:19:08.585645Z","caller":"traceutil/trace.go:171","msg":"trace[1691331755] transaction","detail":"{read_only:false; response_revision:180; number_of_response:1; }","duration":"338.752297ms","start":"2026-08-31T07:19:08.246869Z","end":"2026-08-31T07:19:08.585622Z","steps":["trace[1691331755] 'process raft request' (duration: 180.861422ms)","trace[1691331755] 'compare' (duration: 157.444413ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:19:08.585695Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:19:08.247397Z","time spent":"338.274155ms","remote":"127.0.0.1:48494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-31T07:19:08.585737Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:19:08.246838Z","time spent":"338.84664ms","remote":"127.0.0.1:48586","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":679,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T07:19:15.346852Z","caller":"traceutil/trace.go:171","msg":"trace[418982657] transaction","detail":"{read_only:false; response_revision:369; number_of_response:1; }","duration":"109.365944ms","start":"2026-08-31T07:19:15.237456Z","end":"2026-08-31T07:19:15.346822Z","steps":["trace[418982657] 'process raft request' (duration: 109.010935ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:19:15.34689Z","caller":"traceutil/trace.go:171","msg":"trace[1749401828] transaction","detail":"{read_only:false; response_revision:368; number_of_response:1; }","duration":"111.237342ms","start":"2026-08-31T07:19:15.23563Z","end":"2026-08-31T07:19:15.346867Z","steps":["trace[1749401828] 'process raft request' (duration: 83.409903ms)","trace[1749401828] 'compare' (duration: 27.323876ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:19:43.875683Z","caller":"traceutil/trace.go:171","msg":"trace[103883396] transaction","detail":"{read_only:false; response_revision:528; number_of_response:1; }","duration":"199.56045ms","start":"2026-08-31T07:19:43.676079Z","end":"2026-08-31T07:19:43.875639Z","steps":["trace[103883396] 'process raft request' (duration: 199.426787ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:19:43.88318Z","caller":"traceutil/trace.go:171","msg":"trace[1748305477] transaction","detail":"{read_only:false; response_revision:529; number_of_response:1; }","duration":"195.170294ms","start":"2026-08-31T07:19:43.687983Z","end":"2026-08-31T07:19:43.883153Z","steps":["trace[1748305477] 'process raft request' (duration: 194.884058ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:19:43.883883Z","caller":"traceutil/trace.go:171","msg":"trace[650978022] transaction","detail":"{read_only:false; response_revision:530; number_of_response:1; }","duration":"195.793808ms","start":"2026-08-31T07:19:43.688062Z","end":"2026-08-31T07:19:43.883856Z","steps":["trace[650978022] 'process raft request' (duration: 194.94266ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:19:43.884086Z","caller":"traceutil/trace.go:171","msg":"trace[139296382] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"188.200165ms","start":"2026-08-31T07:19:43.695864Z","end":"2026-08-31T07:19:43.884065Z","steps":["trace[139296382] 'process raft request' (duration: 187.824757ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:19:43.885043Z","caller":"traceutil/trace.go:171","msg":"trace[175712429] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"187.577153ms","start":"2026-08-31T07:19:43.697455Z","end":"2026-08-31T07:19:43.885032Z","steps":["trace[175712429] 'process raft request' (duration: 187.259536ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:19:43.885134Z","caller":"traceutil/trace.go:171","msg":"trace[459283291] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"187.718075ms","start":"2026-08-31T07:19:43.697407Z","end":"2026-08-31T07:19:43.885126Z","steps":["trace[459283291] 'process raft request' (duration: 187.124973ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:19:43.885248Z","caller":"traceutil/trace.go:171","msg":"trace[1006683887] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"186.362237ms","start":"2026-08-31T07:19:43.698879Z","end":"2026-08-31T07:19:43.885241Z","steps":["trace[1006683887] 'process raft request' (duration: 185.965198ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:19:43.885259Z","caller":"traceutil/trace.go:171","msg":"trace[1731064792] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"186.847097ms","start":"2026-08-31T07:19:43.698391Z","end":"2026-08-31T07:19:43.885238Z","steps":["trace[1731064792] 'process raft request' (duration: 186.377426ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:19:43.885388Z","caller":"traceutil/trace.go:171","msg":"trace[1847214496] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"181.39644ms","start":"2026-08-31T07:19:43.703954Z","end":"2026-08-31T07:19:43.885351Z","steps":["trace[1847214496] 'process raft request' (duration: 180.9657ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:19:43.886224Z","caller":"traceutil/trace.go:171","msg":"trace[1667098804] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"170.782423ms","start":"2026-08-31T07:19:43.715421Z","end":"2026-08-31T07:19:43.886204Z","steps":["trace[1667098804] 'process raft request' (duration: 169.630808ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:19:43.886473Z","caller":"traceutil/trace.go:171","msg":"trace[1704190122] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"157.776454ms","start":"2026-08-31T07:19:43.728682Z","end":"2026-08-31T07:19:43.886458Z","steps":["trace[1704190122] 'process raft request' (duration: 157.261113ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:19:50.429655Z","caller":"traceutil/trace.go:171","msg":"trace[1618408795] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"229.834335ms","start":"2026-08-31T07:19:50.199791Z","end":"2026-08-31T07:19:50.429625Z","steps":["trace[1618408795] 'process raft request' (duration: 192.858724ms)","trace[1618408795] 'compare' (duration: 36.625293ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:19:50.442602Z","caller":"traceutil/trace.go:171","msg":"trace[1613475558] transaction","detail":"{read_only:false; response_revision:628; number_of_response:1; }","duration":"214.277505ms","start":"2026-08-31T07:19:50.228293Z","end":"2026-08-31T07:19:50.44257Z","steps":["trace[1613475558] 'process raft request' (duration: 213.896847ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:19:51.01401Z","caller":"traceutil/trace.go:171","msg":"trace[1017909849] linearizableReadLoop","detail":"{readStateIndex:656; appliedIndex:655; }","duration":"148.989751ms","start":"2026-08-31T07:19:50.864998Z","end":"2026-08-31T07:19:51.013988Z","steps":["trace[1017909849] 'read index received' (duration: 145.063121ms)","trace[1017909849] 'applied index is now lower than readState.Index' (duration: 3.92598ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:19:51.014186Z","caller":"traceutil/trace.go:171","msg":"trace[1105032363] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"507.172164ms","start":"2026-08-31T07:19:50.506993Z","end":"2026-08-31T07:19:51.014165Z","steps":["trace[1105032363] 'process raft request' (duration: 503.116441ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:19:51.014259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.257056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-31T07:19:51.014283Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:19:50.50696Z","time spent":"507.274536ms","remote":"127.0.0.1:48556","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":897,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-31T07:19:51.014312Z","caller":"traceutil/trace.go:171","msg":"trace[1906632591] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"149.352668ms","start":"2026-08-31T07:19:50.864951Z","end":"2026-08-31T07:19:51.014303Z","steps":["trace[1906632591] 'agreement among raft nodes before linearized reading' (duration: 149.125063ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:20:36.93876Z","caller":"traceutil/trace.go:171","msg":"trace[1743172085] transaction","detail":"{read_only:false; response_revision:1203; number_of_response:1; }","duration":"166.429187ms","start":"2026-08-31T07:20:36.772307Z","end":"2026-08-31T07:20:36.938736Z","steps":["trace[1743172085] 'process raft request' (duration: 166.254305ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:20:37.083935Z","caller":"traceutil/trace.go:171","msg":"trace[740575633] transaction","detail":"{read_only:false; response_revision:1204; number_of_response:1; }","duration":"137.647647ms","start":"2026-08-31T07:20:36.946265Z","end":"2026-08-31T07:20:37.083913Z","steps":["trace[740575633] 'process raft request' (duration: 137.516355ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:21:27.112363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.211784ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-31T07:21:27.112527Z","caller":"traceutil/trace.go:171","msg":"trace[1220469394] linearizableReadLoop","detail":"{readStateIndex:1968; appliedIndex:1967; }","duration":"397.461798ms","start":"2026-08-31T07:21:26.715042Z","end":"2026-08-31T07:21:27.112503Z","steps":["trace[1220469394] 'read index received' (duration: 116.555885ms)","trace[1220469394] 'applied index is now lower than readState.Index' (duration: 280.903773ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:21:27.112638Z","caller":"traceutil/trace.go:171","msg":"trace[377288242] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"528.792755ms","start":"2026-08-31T07:21:26.583722Z","end":"2026-08-31T07:21:27.112514Z","steps":["trace[377288242] 'process raft request' (duration: 248.000444ms)","trace[377288242] 'compare' (duration: 279.940559ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:21:27.112907Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:21:26.583667Z","time spent":"529.093741ms","remote":"127.0.0.1:48494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-31T07:21:27.112937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"397.889695ms","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-08-31T07:21:27.112931Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"380.017009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f2c711f9-1f90-4276-aa85-e260d5df9e17\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-31T07:21:27.113007Z","caller":"traceutil/trace.go:171","msg":"trace[760865967] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1891; }","duration":"397.987038ms","start":"2026-08-31T07:21:26.714979Z","end":"2026-08-31T07:21:27.112966Z","steps":["trace[760865967] 'agreement among raft nodes before linearized reading' (duration: 397.676863ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:21:27.113007Z","caller":"traceutil/trace.go:171","msg":"trace[1405824881] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f2c711f9-1f90-4276-aa85-e260d5df9e17; range_end:; response_count:1; response_revision:1891; }","duration":"380.10786ms","start":"2026-08-31T07:21:26.732881Z","end":"2026-08-31T07:21:27.112988Z","steps":["trace[1405824881] 'agreement among raft nodes before linearized reading' (duration: 379.876407ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:21:27.113067Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:21:26.732868Z","time spent":"380.187051ms","remote":"127.0.0.1:48402","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f2c711f9-1f90-4276-aa85-e260d5df9e17\" "} {"level":"warn","ts":"2026-08-31T07:21:27.113104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.652025ms","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-08-31T07:21:27.113159Z","caller":"traceutil/trace.go:171","msg":"trace[1641382084] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1891; }","duration":"295.744286ms","start":"2026-08-31T07:21:26.817398Z","end":"2026-08-31T07:21:27.113142Z","steps":["trace[1641382084] 'agreement among raft nodes before linearized reading' (duration: 295.585704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:21:27.113524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:21:26.714951Z","time spent":"398.10132ms","remote":"127.0.0.1:48494","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-08-31T07:21:27.639813Z","caller":"traceutil/trace.go:171","msg":"trace[84748669] linearizableReadLoop","detail":"{readStateIndex:1973; appliedIndex:1972; }","duration":"282.518304ms","start":"2026-08-31T07:21:27.357264Z","end":"2026-08-31T07:21:27.639783Z","steps":["trace[84748669] 'read index received' (duration: 282.407522ms)","trace[84748669] 'applied index is now lower than readState.Index' (duration: 109.272µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:21:27.63991Z","caller":"traceutil/trace.go:171","msg":"trace[846610088] transaction","detail":"{read_only:false; response_revision:1896; number_of_response:1; }","duration":"299.259964ms","start":"2026-08-31T07:21:27.340608Z","end":"2026-08-31T07:21:27.639868Z","steps":["trace[846610088] 'process raft request' (duration: 299.01003ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:21:27.639995Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"282.704636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:21:27.640066Z","caller":"traceutil/trace.go:171","msg":"trace[1520264972] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1896; }","duration":"282.845419ms","start":"2026-08-31T07:21:27.357204Z","end":"2026-08-31T07:21:27.640049Z","steps":["trace[1520264972] 'agreement among raft nodes before linearized reading' (duration: 282.720797ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:21:27.644654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.856141ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-f2c711f9-1f90-4276-aa85-e260d5df9e17\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-08-31T07:21:27.644734Z","caller":"traceutil/trace.go:171","msg":"trace[604602942] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-f2c711f9-1f90-4276-aa85-e260d5df9e17; range_end:; response_count:1; response_revision:1897; }","duration":"231.002644ms","start":"2026-08-31T07:21:27.413714Z","end":"2026-08-31T07:21:27.644716Z","steps":["trace[604602942] 'agreement among raft nodes before linearized reading' (duration: 230.79215ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:21:41.592773Z","caller":"traceutil/trace.go:171","msg":"trace[2069176091] linearizableReadLoop","detail":"{readStateIndex:2088; appliedIndex:2087; }","duration":"237.218023ms","start":"2026-08-31T07:21:41.355524Z","end":"2026-08-31T07:21:41.592742Z","steps":["trace[2069176091] 'read index received' (duration: 236.881568ms)","trace[2069176091] 'applied index is now lower than readState.Index' (duration: 335.315µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:21:41.592947Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.248895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:21:41.592987Z","caller":"traceutil/trace.go:171","msg":"trace[1069414823] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2007; }","duration":"237.319975ms","start":"2026-08-31T07:21:41.355653Z","end":"2026-08-31T07:21:41.592973Z","steps":["trace[1069414823] 'agreement among raft nodes before linearized reading' (duration: 237.240354ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:21:41.593039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"237.513528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-31T07:21:41.59312Z","caller":"traceutil/trace.go:171","msg":"trace[1478899617] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2007; }","duration":"237.67475ms","start":"2026-08-31T07:21:41.355432Z","end":"2026-08-31T07:21:41.593107Z","steps":["trace[1478899617] 'agreement among raft nodes before linearized reading' (duration: 237.438367ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:21:41.593579Z","caller":"traceutil/trace.go:171","msg":"trace[1613930756] transaction","detail":"{read_only:false; response_revision:2007; number_of_response:1; }","duration":"302.300487ms","start":"2026-08-31T07:21:41.291262Z","end":"2026-08-31T07:21:41.593562Z","steps":["trace[1613930756] 'process raft request' (duration: 301.247381ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:21:41.593703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-31T07:21:41.291243Z","time spent":"302.394659ms","remote":"127.0.0.1:48494","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-31T07:21:44.268086Z","caller":"traceutil/trace.go:171","msg":"trace[225140419] transaction","detail":"{read_only:false; response_revision:2036; number_of_response:1; }","duration":"227.592999ms","start":"2026-08-31T07:21:44.040442Z","end":"2026-08-31T07:21:44.268035Z","steps":["trace[225140419] 'process raft request' (duration: 227.449377ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:21:44.269226Z","caller":"traceutil/trace.go:171","msg":"trace[1615272843] transaction","detail":"{read_only:false; response_revision:2037; number_of_response:1; }","duration":"220.327584ms","start":"2026-08-31T07:21:44.048886Z","end":"2026-08-31T07:21:44.269213Z","steps":["trace[1615272843] 'process raft request' (duration: 220.274573ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:22:07.984197Z","caller":"traceutil/trace.go:171","msg":"trace[277893989] transaction","detail":"{read_only:false; response_revision:2167; number_of_response:1; }","duration":"120.338772ms","start":"2026-08-31T07:22:07.863819Z","end":"2026-08-31T07:22:07.984158Z","steps":["trace[277893989] 'process raft request' (duration: 92.515086ms)","trace[277893989] 'compare' (duration: 27.499232ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:22:08.639674Z","caller":"traceutil/trace.go:171","msg":"trace[427094247] linearizableReadLoop","detail":"{readStateIndex:2255; appliedIndex:2254; }","duration":"147.266313ms","start":"2026-08-31T07:22:08.492381Z","end":"2026-08-31T07:22:08.639648Z","steps":["trace[427094247] 'read index received' (duration: 147.04272ms)","trace[427094247] 'applied index is now lower than readState.Index' (duration: 222.293µs)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:22:08.639871Z","caller":"traceutil/trace.go:171","msg":"trace[417900329] transaction","detail":"{read_only:false; response_revision:2169; number_of_response:1; }","duration":"164.61678ms","start":"2026-08-31T07:22:08.475111Z","end":"2026-08-31T07:22:08.639727Z","steps":["trace[417900329] 'process raft request' (duration: 164.391927ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:22:08.640259Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.741349ms","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-08-31T07:22:08.640329Z","caller":"traceutil/trace.go:171","msg":"trace[154701413] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2169; }","duration":"147.968072ms","start":"2026-08-31T07:22:08.492338Z","end":"2026-08-31T07:22:08.640306Z","steps":["trace[154701413] 'agreement among raft nodes before linearized reading' (duration: 147.416434ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:22:08.927705Z","caller":"traceutil/trace.go:171","msg":"trace[1700216128] transaction","detail":"{read_only:false; response_revision:2170; number_of_response:1; }","duration":"112.091792ms","start":"2026-08-31T07:22:08.81557Z","end":"2026-08-31T07:22:08.927662Z","steps":["trace[1700216128] 'process raft request' (duration: 111.94521ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:22:09.507629Z","caller":"traceutil/trace.go:171","msg":"trace[1547223963] transaction","detail":"{read_only:false; response_revision:2171; number_of_response:1; }","duration":"200.267173ms","start":"2026-08-31T07:22:09.307336Z","end":"2026-08-31T07:22:09.507603Z","steps":["trace[1547223963] 'process raft request' (duration: 130.211499ms)","trace[1547223963] 'compare' (duration: 69.618439ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:22:09.507665Z","caller":"traceutil/trace.go:171","msg":"trace[1070450695] linearizableReadLoop","detail":"{readStateIndex:2259; appliedIndex:2257; }","duration":"152.043179ms","start":"2026-08-31T07:22:09.355598Z","end":"2026-08-31T07:22:09.507641Z","steps":["trace[1070450695] 'read index received' (duration: 81.909453ms)","trace[1070450695] 'applied index is now lower than readState.Index' (duration: 70.132896ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:22:09.507853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.248892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:22:09.508044Z","caller":"traceutil/trace.go:171","msg":"trace[670966783] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2172; }","duration":"152.481215ms","start":"2026-08-31T07:22:09.355547Z","end":"2026-08-31T07:22:09.508028Z","steps":["trace[670966783] 'agreement among raft nodes before linearized reading' (duration: 152.254742ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:22:09.50813Z","caller":"traceutil/trace.go:171","msg":"trace[374783821] transaction","detail":"{read_only:false; response_revision:2172; number_of_response:1; }","duration":"158.348528ms","start":"2026-08-31T07:22:09.349739Z","end":"2026-08-31T07:22:09.508087Z","steps":["trace[374783821] 'process raft request' (duration: 157.798341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:22:09.780951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.155306ms","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-08-31T07:22:09.781041Z","caller":"traceutil/trace.go:171","msg":"trace[69393172] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2173; }","duration":"132.279567ms","start":"2026-08-31T07:22:09.648726Z","end":"2026-08-31T07:22:09.781005Z","steps":["trace[69393172] 'range keys from in-memory index tree' (duration: 131.868511ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:22:09.781129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.061873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/percona-xtradb-haproxy-0.18d0d2e0b5cbe85c\" ","response":"range_response_count:1 size:846"} {"level":"info","ts":"2026-08-31T07:22:09.781211Z","caller":"traceutil/trace.go:171","msg":"trace[290255773] range","detail":"{range_begin:/registry/events/openstack/percona-xtradb-haproxy-0.18d0d2e0b5cbe85c; range_end:; response_count:1; response_revision:2173; }","duration":"141.197625ms","start":"2026-08-31T07:22:09.639995Z","end":"2026-08-31T07:22:09.781192Z","steps":["trace[290255773] 'range keys from in-memory index tree' (duration: 140.882901ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:23:37.190035Z","caller":"traceutil/trace.go:171","msg":"trace[247289679] transaction","detail":"{read_only:false; response_revision:2743; number_of_response:1; }","duration":"100.986394ms","start":"2026-08-31T07:23:37.089023Z","end":"2026-08-31T07:23:37.190009Z","steps":["trace[247289679] 'process raft request' (duration: 100.756425ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:24:08.62081Z","caller":"traceutil/trace.go:171","msg":"trace[745185126] transaction","detail":"{read_only:false; response_revision:2918; number_of_response:1; }","duration":"129.335423ms","start":"2026-08-31T07:24:08.491446Z","end":"2026-08-31T07:24:08.620782Z","steps":["trace[745185126] 'process raft request' (duration: 110.982923ms)","trace[745185126] 'compare' (duration: 17.923365ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:24:08.912107Z","caller":"traceutil/trace.go:171","msg":"trace[144373246] transaction","detail":"{read_only:false; response_revision:2919; number_of_response:1; }","duration":"271.725355ms","start":"2026-08-31T07:24:08.640356Z","end":"2026-08-31T07:24:08.912082Z","steps":["trace[144373246] 'process raft request' (duration: 184.022777ms)","trace[144373246] 'compare' (duration: 87.590334ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:24:14.334855Z","caller":"traceutil/trace.go:171","msg":"trace[1968038084] transaction","detail":"{read_only:false; response_revision:2945; number_of_response:1; }","duration":"163.81295ms","start":"2026-08-31T07:24:14.171018Z","end":"2026-08-31T07:24:14.334831Z","steps":["trace[1968038084] 'process raft request' (duration: 163.604793ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:24:14.540745Z","caller":"traceutil/trace.go:171","msg":"trace[190068414] linearizableReadLoop","detail":"{readStateIndex:3061; appliedIndex:3059; }","duration":"160.855094ms","start":"2026-08-31T07:24:14.379871Z","end":"2026-08-31T07:24:14.540726Z","steps":["trace[190068414] 'read index received' (duration: 88.665823ms)","trace[190068414] 'applied index is now lower than readState.Index' (duration: 72.188431ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:24:14.540819Z","caller":"traceutil/trace.go:171","msg":"trace[1106272630] transaction","detail":"{read_only:false; response_revision:2947; number_of_response:1; }","duration":"193.632735ms","start":"2026-08-31T07:24:14.347156Z","end":"2026-08-31T07:24:14.540789Z","steps":["trace[1106272630] 'process raft request' (duration: 193.495021ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:24:14.540971Z","caller":"traceutil/trace.go:171","msg":"trace[663241978] transaction","detail":"{read_only:false; response_revision:2946; number_of_response:1; }","duration":"214.818804ms","start":"2026-08-31T07:24:14.326086Z","end":"2026-08-31T07:24:14.540905Z","steps":["trace[663241978] 'process raft request' (duration: 142.455438ms)","trace[663241978] 'compare' (duration: 71.90604ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:24:14.541304Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.096042ms","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-08-31T07:24:14.541343Z","caller":"traceutil/trace.go:171","msg":"trace[796601397] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2947; }","duration":"161.495985ms","start":"2026-08-31T07:24:14.379837Z","end":"2026-08-31T07:24:14.541333Z","steps":["trace[796601397] 'agreement among raft nodes before linearized reading' (duration: 161.04209ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:27:00.414552Z","caller":"traceutil/trace.go:171","msg":"trace[367762345] transaction","detail":"{read_only:false; response_revision:3898; number_of_response:1; }","duration":"148.346643ms","start":"2026-08-31T07:27:00.266178Z","end":"2026-08-31T07:27:00.414525Z","steps":["trace[367762345] 'process raft request' (duration: 148.166609ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:27:00.430833Z","caller":"traceutil/trace.go:171","msg":"trace[14965574] transaction","detail":"{read_only:false; response_revision:3899; number_of_response:1; }","duration":"132.596424ms","start":"2026-08-31T07:27:00.298203Z","end":"2026-08-31T07:27:00.430799Z","steps":["trace[14965574] 'process raft request' (duration: 132.38743ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:44.116135Z","caller":"traceutil/trace.go:171","msg":"trace[1816590023] linearizableReadLoop","detail":"{readStateIndex:5235; appliedIndex:5234; }","duration":"100.416822ms","start":"2026-08-31T07:28:44.015693Z","end":"2026-08-31T07:28:44.11611Z","steps":["trace[1816590023] 'read index received' (duration: 98.966315ms)","trace[1816590023] 'applied index is now lower than readState.Index' (duration: 1.449537ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:28:44.11623Z","caller":"traceutil/trace.go:171","msg":"trace[328847317] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"118.07172ms","start":"2026-08-31T07:28:43.998126Z","end":"2026-08-31T07:28:44.116197Z","steps":["trace[328847317] 'process raft request' (duration: 116.545581ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:28:44.116425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.724549ms","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-08-31T07:28:44.116524Z","caller":"traceutil/trace.go:171","msg":"trace[1331913321] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5037; }","duration":"100.83255ms","start":"2026-08-31T07:28:44.015652Z","end":"2026-08-31T07:28:44.116484Z","steps":["trace[1331913321] 'agreement among raft nodes before linearized reading' (duration: 100.576106ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:44.842368Z","caller":"traceutil/trace.go:171","msg":"trace[297383997] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"156.15497ms","start":"2026-08-31T07:28:44.686177Z","end":"2026-08-31T07:28:44.842332Z","steps":["trace[297383997] 'process raft request' (duration: 156.015497ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:44.847119Z","caller":"traceutil/trace.go:171","msg":"trace[1810361271] transaction","detail":"{read_only:false; response_revision:5051; number_of_response:1; }","duration":"157.664828ms","start":"2026-08-31T07:28:44.689439Z","end":"2026-08-31T07:28:44.847104Z","steps":["trace[1810361271] 'process raft request' (duration: 157.547446ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:44.847433Z","caller":"traceutil/trace.go:171","msg":"trace[242258661] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"144.042258ms","start":"2026-08-31T07:28:44.703384Z","end":"2026-08-31T07:28:44.847427Z","steps":["trace[242258661] 'process raft request' (duration: 143.686801ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:44.847614Z","caller":"traceutil/trace.go:171","msg":"trace[708526888] linearizableReadLoop","detail":"{readStateIndex:5251; appliedIndex:5248; }","duration":"128.184954ms","start":"2026-08-31T07:28:44.719413Z","end":"2026-08-31T07:28:44.847597Z","steps":["trace[708526888] 'read index received' (duration: 122.799551ms)","trace[708526888] 'applied index is now lower than readState.Index' (duration: 5.384293ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:28:44.847819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.392479ms","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-08-31T07:28:44.847862Z","caller":"traceutil/trace.go:171","msg":"trace[204225858] 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:5053; }","duration":"128.496361ms","start":"2026-08-31T07:28:44.719352Z","end":"2026-08-31T07:28:44.847848Z","steps":["trace[204225858] 'agreement among raft nodes before linearized reading' (duration: 128.305017ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:49.878862Z","caller":"traceutil/trace.go:171","msg":"trace[553985986] transaction","detail":"{read_only:false; response_revision:5146; number_of_response:1; }","duration":"132.776072ms","start":"2026-08-31T07:28:49.746025Z","end":"2026-08-31T07:28:49.878801Z","steps":["trace[553985986] 'process raft request' (duration: 132.09301ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:50.071537Z","caller":"traceutil/trace.go:171","msg":"trace[696335062] transaction","detail":"{read_only:false; response_revision:5147; number_of_response:1; }","duration":"173.850555ms","start":"2026-08-31T07:28:49.897541Z","end":"2026-08-31T07:28:50.071392Z","steps":["trace[696335062] 'process raft request' (duration: 141.272583ms)","trace[696335062] 'compare' (duration: 32.414708ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:28:50.078762Z","caller":"traceutil/trace.go:171","msg":"trace[1962213324] transaction","detail":"{read_only:false; response_revision:5148; number_of_response:1; }","duration":"179.566643ms","start":"2026-08-31T07:28:49.899171Z","end":"2026-08-31T07:28:50.078738Z","steps":["trace[1962213324] 'process raft request' (duration: 179.38877ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:50.257585Z","caller":"traceutil/trace.go:171","msg":"trace[883112263] transaction","detail":"{read_only:false; response_revision:5152; number_of_response:1; }","duration":"114.257007ms","start":"2026-08-31T07:28:50.143297Z","end":"2026-08-31T07:28:50.257554Z","steps":["trace[883112263] 'process raft request' (duration: 114.033632ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:50.257595Z","caller":"traceutil/trace.go:171","msg":"trace[626507937] linearizableReadLoop","detail":"{readStateIndex:5353; appliedIndex:5351; }","duration":"106.648964ms","start":"2026-08-31T07:28:50.150923Z","end":"2026-08-31T07:28:50.257572Z","steps":["trace[626507937] 'read index received' (duration: 22.653293ms)","trace[626507937] 'applied index is now lower than readState.Index' (duration: 83.992951ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:28:50.25796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.01986ms","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-08-31T07:28:50.258042Z","caller":"traceutil/trace.go:171","msg":"trace[1002162568] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5153; }","duration":"107.137392ms","start":"2026-08-31T07:28:50.150886Z","end":"2026-08-31T07:28:50.258024Z","steps":["trace[1002162568] 'agreement among raft nodes before linearized reading' (duration: 106.951779ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:50.258171Z","caller":"traceutil/trace.go:171","msg":"trace[1737205458] transaction","detail":"{read_only:false; response_revision:5151; number_of_response:1; }","duration":"118.192272ms","start":"2026-08-31T07:28:50.139913Z","end":"2026-08-31T07:28:50.258105Z","steps":["trace[1737205458] 'process raft request' (duration: 34.10879ms)","trace[1737205458] 'compare' (duration: 83.129994ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:28:50.451963Z","caller":"traceutil/trace.go:171","msg":"trace[449603763] linearizableReadLoop","detail":"{readStateIndex:5356; appliedIndex:5354; }","duration":"173.357593ms","start":"2026-08-31T07:28:50.27858Z","end":"2026-08-31T07:28:50.451938Z","steps":["trace[449603763] 'read index received' (duration: 104.535671ms)","trace[449603763] 'applied index is now lower than readState.Index' (duration: 68.820522ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:28:50.452164Z","caller":"traceutil/trace.go:171","msg":"trace[63008893] transaction","detail":"{read_only:false; response_revision:5154; number_of_response:1; }","duration":"188.37505ms","start":"2026-08-31T07:28:50.263769Z","end":"2026-08-31T07:28:50.452144Z","steps":["trace[63008893] 'process raft request' (duration: 119.232812ms)","trace[63008893] 'compare' (duration: 68.541717ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:28:50.452193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.608829ms","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-08-31T07:28:50.452242Z","caller":"traceutil/trace.go:171","msg":"trace[1612639737] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5155; }","duration":"173.724561ms","start":"2026-08-31T07:28:50.278503Z","end":"2026-08-31T07:28:50.452228Z","steps":["trace[1612639737] 'agreement among raft nodes before linearized reading' (duration: 173.537047ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:28:50.452274Z","caller":"traceutil/trace.go:171","msg":"trace[852345446] transaction","detail":"{read_only:false; response_revision:5155; number_of_response:1; }","duration":"187.956423ms","start":"2026-08-31T07:28:50.264298Z","end":"2026-08-31T07:28:50.452255Z","steps":["trace[852345446] 'process raft request' (duration: 187.511884ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:29:04.44616Z","caller":"traceutil/trace.go:171","msg":"trace[446712821] transaction","detail":"{read_only:false; response_revision:5341; number_of_response:1; }","duration":"132.733385ms","start":"2026-08-31T07:29:04.313373Z","end":"2026-08-31T07:29:04.446107Z","steps":["trace[446712821] 'process raft request' (duration: 89.02583ms)","trace[446712821] 'compare' (duration: 43.280267ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:29:04.483746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.827612ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:29:04.483855Z","caller":"traceutil/trace.go:171","msg":"trace[1788962259] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5342; }","duration":"128.007244ms","start":"2026-08-31T07:29:04.355814Z","end":"2026-08-31T07:29:04.483821Z","steps":["trace[1788962259] 'agreement among raft nodes before linearized reading' (duration: 90.370334ms)","trace[1788962259] 'range keys from in-memory index tree' (duration: 37.473788ms)"],"step_count":2} {"level":"info","ts":"2026-08-31T07:29:04.501895Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2894} {"level":"warn","ts":"2026-08-31T07:29:04.501979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.330432ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-c99f8\" ","response":"range_response_count:1 size:9686"} {"level":"info","ts":"2026-08-31T07:29:04.50205Z","caller":"traceutil/trace.go:171","msg":"trace[1526194180] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-c99f8; range_end:; response_count:1; response_revision:5342; }","duration":"119.433704ms","start":"2026-08-31T07:29:04.382579Z","end":"2026-08-31T07:29:04.502013Z","steps":["trace[1526194180] 'agreement among raft nodes before linearized reading' (duration: 101.142028ms)","trace[1526194180] 'range keys from in-memory index tree' (duration: 18.100912ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:29:04.647222Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.596089ms","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-08-31T07:29:04.647318Z","caller":"traceutil/trace.go:171","msg":"trace[25399717] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5342; }","duration":"136.752841ms","start":"2026-08-31T07:29:04.510538Z","end":"2026-08-31T07:29:04.647291Z","steps":["trace[25399717] 'range keys from in-memory index tree' (duration: 136.405935ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:29:04.666978Z","caller":"traceutil/trace.go:171","msg":"trace[383825273] transaction","detail":"{read_only:false; response_revision:5344; number_of_response:1; }","duration":"107.172122ms","start":"2026-08-31T07:29:04.559728Z","end":"2026-08-31T07:29:04.666901Z","steps":["trace[383825273] 'process raft request' (duration: 106.57306ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:29:04.667068Z","caller":"traceutil/trace.go:171","msg":"trace[988501147] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"154.048057ms","start":"2026-08-31T07:29:04.512985Z","end":"2026-08-31T07:29:04.667033Z","steps":["trace[988501147] 'process raft request' (duration: 77.37696ms)","trace[988501147] 'compare' (duration: 56.496557ms)","trace[988501147] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-c99f8; req_size:9478; } (duration: 19.277882ms)"],"step_count":3} {"level":"info","ts":"2026-08-31T07:29:04.667409Z","caller":"traceutil/trace.go:171","msg":"trace[1863654696] linearizableReadLoop","detail":"{readStateIndex:5550; appliedIndex:5549; }","duration":"133.403687ms","start":"2026-08-31T07:29:04.53399Z","end":"2026-08-31T07:29:04.667394Z","steps":["trace[1863654696] 'read index received' (duration: 56.383594ms)","trace[1863654696] 'applied index is now lower than readState.Index' (duration: 77.017953ms)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:29:04.667623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.617221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:29:04.667696Z","caller":"traceutil/trace.go:171","msg":"trace[1455526851] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:5345; }","duration":"133.735503ms","start":"2026-08-31T07:29:04.533927Z","end":"2026-08-31T07:29:04.667662Z","steps":["trace[1455526851] 'agreement among raft nodes before linearized reading' (duration: 133.635471ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:29:04.667944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.184959ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-31T07:29:04.669602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.441805ms","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-08-31T07:29:04.669697Z","caller":"traceutil/trace.go:171","msg":"trace[1935283495] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5345; }","duration":"101.595358ms","start":"2026-08-31T07:29:04.568079Z","end":"2026-08-31T07:29:04.669674Z","steps":["trace[1935283495] 'agreement among raft nodes before linearized reading' (duration: 101.417845ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:29:04.667997Z","caller":"traceutil/trace.go:171","msg":"trace[922769636] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5345; }","duration":"128.292661ms","start":"2026-08-31T07:29:04.539694Z","end":"2026-08-31T07:29:04.667987Z","steps":["trace[922769636] 'agreement among raft nodes before linearized reading' (duration: 128.202979ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:29:04.783264Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2894,"took":"279.865461ms","hash":2046100956,"current-db-size-bytes":53055488,"current-db-size":"53 MB","current-db-size-in-use-bytes":27648000,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-31T07:29:04.783337Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2046100956,"revision":2894,"compact-revision":-1} {"level":"info","ts":"2026-08-31T07:29:42.752594Z","caller":"traceutil/trace.go:171","msg":"trace[866480168] linearizableReadLoop","detail":"{readStateIndex:5894; appliedIndex:5893; }","duration":"131.806382ms","start":"2026-08-31T07:29:42.620752Z","end":"2026-08-31T07:29:42.752558Z","steps":["trace[866480168] 'read index received' (duration: 131.471636ms)","trace[866480168] 'applied index is now lower than readState.Index' (duration: 333.116µs)"],"step_count":2} {"level":"warn","ts":"2026-08-31T07:29:42.752918Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.087758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"warn","ts":"2026-08-31T07:29:42.752991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.015266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:29:42.753046Z","caller":"traceutil/trace.go:171","msg":"trace[640520499] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5679; }","duration":"126.165609ms","start":"2026-08-31T07:29:42.626864Z","end":"2026-08-31T07:29:42.75303Z","steps":["trace[640520499] 'agreement among raft nodes before linearized reading' (duration: 126.036366ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:29:42.753351Z","caller":"traceutil/trace.go:171","msg":"trace[1901288747] transaction","detail":"{read_only:false; response_revision:5679; number_of_response:1; }","duration":"137.331185ms","start":"2026-08-31T07:29:42.615299Z","end":"2026-08-31T07:29:42.752631Z","steps":["trace[1901288747] 'process raft request' (duration: 137.026909ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:29:42.753039Z","caller":"traceutil/trace.go:171","msg":"trace[26358730] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5679; }","duration":"132.302361ms","start":"2026-08-31T07:29:42.620716Z","end":"2026-08-31T07:29:42.753019Z","steps":["trace[26358730] 'agreement among raft nodes before linearized reading' (duration: 131.971085ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:29:42.753587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.967415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6084"} {"level":"info","ts":"2026-08-31T07:29:42.753652Z","caller":"traceutil/trace.go:171","msg":"trace[2039060202] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5679; }","duration":"113.071716ms","start":"2026-08-31T07:29:42.640564Z","end":"2026-08-31T07:29:42.753635Z","steps":["trace[2039060202] 'agreement among raft nodes before linearized reading' (duration: 112.864093ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:29:42.753928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.154439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:29:42.754263Z","caller":"traceutil/trace.go:171","msg":"trace[171522960] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5679; }","duration":"126.566717ms","start":"2026-08-31T07:29:42.627609Z","end":"2026-08-31T07:29:42.754176Z","steps":["trace[171522960] 'agreement among raft nodes before linearized reading' (duration: 126.110568ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:32:53.682385Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.640566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:1 size:4784"} {"level":"info","ts":"2026-08-31T07:32:53.682806Z","caller":"traceutil/trace.go:171","msg":"trace[1014015662] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:1; response_revision:6990; }","duration":"140.104475ms","start":"2026-08-31T07:32:53.542681Z","end":"2026-08-31T07:32:53.682785Z","steps":["trace[1014015662] 'range keys from in-memory index tree' (duration: 139.476081ms)"],"step_count":1} {"level":"warn","ts":"2026-08-31T07:33:12.342876Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/thanosrulers/\" range_end:\"/registry/monitoring.coreos.com/thanosrulers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-31T07:33:12.34295Z","caller":"traceutil/trace.go:171","msg":"trace[1870722565] range","detail":"{range_begin:/registry/monitoring.coreos.com/thanosrulers/; range_end:/registry/monitoring.coreos.com/thanosrulers0; response_count:0; response_revision:7140; }","duration":"188.613884ms","start":"2026-08-31T07:33:12.154321Z","end":"2026-08-31T07:33:12.342935Z","steps":["trace[1870722565] 'count revisions from in-memory index tree' (duration: 188.422977ms)"],"step_count":1} {"level":"info","ts":"2026-08-31T07:34:04.508961Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5342} {"level":"info","ts":"2026-08-31T07:34:04.620388Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5342,"took":"110.179066ms","hash":1058268722,"current-db-size-bytes":53055488,"current-db-size":"53 MB","current-db-size-in-use-bytes":19726336,"current-db-size-in-use":"20 MB"} {"level":"info","ts":"2026-08-31T07:34:04.62052Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1058268722,"revision":5342,"compact-revision":2894}