{"level":"warn","ts":"2026-08-28T04:01:33.947567Z","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-28T04:01:33.94765Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.27: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.27:2380","--initial-cluster=instance=https://162.253.55.27:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.27:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.27: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-28T04:01:33.947704Z","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-28T04:01:33.947711Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-08-28T04:01:33.94773Z","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-28T04:01:33.948301Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"]} {"level":"info","ts":"2026-08-28T04:01:33.948388Z","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.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.27: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-28T04:01:33.952932Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.367632ms"} {"level":"info","ts":"2026-08-28T04:01:33.96002Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d9b565cb482924cf","cluster-id":"5bc3f8d4cf836b6c"} {"level":"info","ts":"2026-08-28T04:01:33.960151Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=()"} {"level":"info","ts":"2026-08-28T04:01:33.960185Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 0"} {"level":"info","ts":"2026-08-28T04:01:33.960197Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d9b565cb482924cf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-28T04:01:33.96021Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 1"} {"level":"info","ts":"2026-08-28T04:01:33.960265Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"warn","ts":"2026-08-28T04:01:33.968911Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-28T04:01:33.970921Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-28T04:01:33.972335Z","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-28T04:01:33.975598Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d9b565cb482924cf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-28T04:01:33.976095Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d9b565cb482924cf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-28T04:01:33.976204Z","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-28T04:01:33.976265Z","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-28T04:01:33.976277Z","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-28T04:01:33.98772Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"info","ts":"2026-08-28T04:01:33.987839Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","added-peer-id":"d9b565cb482924cf","added-peer-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-08-28T04:01:33.988468Z","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-28T04:01:33.988731Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d9b565cb482924cf","initial-advertise-peer-urls":["https://162.253.55.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-28T04:01:33.988772Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-28T04:01:33.988863Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-08-28T04:01:33.988878Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-08-28T04:01:34.361047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf is starting a new election at term 1"} {"level":"info","ts":"2026-08-28T04:01:34.361107Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became pre-candidate at term 1"} {"level":"info","ts":"2026-08-28T04:01:34.361129Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgPreVoteResp from d9b565cb482924cf at term 1"} {"level":"info","ts":"2026-08-28T04:01:34.361148Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became candidate at term 2"} {"level":"info","ts":"2026-08-28T04:01:34.361159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgVoteResp from d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-08-28T04:01:34.361175Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became leader at term 2"} {"level":"info","ts":"2026-08-28T04:01:34.361189Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d9b565cb482924cf elected leader d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-08-28T04:01:34.363007Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d9b565cb482924cf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.27:2379]}","request-path":"/0/members/d9b565cb482924cf/attributes","cluster-id":"5bc3f8d4cf836b6c","publish-timeout":"7s"} {"level":"info","ts":"2026-08-28T04:01:34.363171Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T04:01:34.363257Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-28T04:01:34.363595Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T04:01:34.365224Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T04:01:34.365422Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T04:01:34.365525Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-28T04:01:34.366494Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.27:2379"} {"level":"info","ts":"2026-08-28T04:01:34.367183Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-28T04:01:34.367266Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-28T04:01:34.368597Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-08-28T04:01:42.26059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.790696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-28T04:01:42.260832Z","caller":"traceutil/trace.go:171","msg":"trace[214708923] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:287; }","duration":"293.087164ms","start":"2026-08-28T04:01:41.967721Z","end":"2026-08-28T04:01:42.260808Z","steps":["trace[214708923] 'range keys from in-memory index tree' (duration: 292.696914ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:01:42.430276Z","caller":"traceutil/trace.go:171","msg":"trace[1129087370] linearizableReadLoop","detail":"{readStateIndex:299; appliedIndex:298; }","duration":"149.938937ms","start":"2026-08-28T04:01:42.280317Z","end":"2026-08-28T04:01:42.430256Z","steps":["trace[1129087370] 'read index received' (duration: 149.608539ms)","trace[1129087370] 'applied index is now lower than readState.Index' (duration: 329.538µs)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:01:42.430461Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.121151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-08-28T04:01:42.430487Z","caller":"traceutil/trace.go:171","msg":"trace[222017907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:288; }","duration":"150.204933ms","start":"2026-08-28T04:01:42.280274Z","end":"2026-08-28T04:01:42.430479Z","steps":["trace[222017907] 'agreement among raft nodes before linearized reading' (duration: 150.08411ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:01:42.430536Z","caller":"traceutil/trace.go:171","msg":"trace[1947602675] transaction","detail":"{read_only:false; response_revision:288; number_of_response:1; }","duration":"164.749165ms","start":"2026-08-28T04:01:42.26556Z","end":"2026-08-28T04:01:42.430309Z","steps":["trace[1947602675] 'process raft request' (duration: 164.53209ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:01:42.591332Z","caller":"traceutil/trace.go:171","msg":"trace[941473527] transaction","detail":"{read_only:false; response_revision:289; number_of_response:1; }","duration":"154.902733ms","start":"2026-08-28T04:01:42.436394Z","end":"2026-08-28T04:01:42.591296Z","steps":["trace[941473527] 'process raft request' (duration: 151.617126ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:01:42.591286Z","caller":"traceutil/trace.go:171","msg":"trace[1613825311] linearizableReadLoop","detail":"{readStateIndex:300; appliedIndex:299; }","duration":"152.774432ms","start":"2026-08-28T04:01:42.438478Z","end":"2026-08-28T04:01:42.591252Z","steps":["trace[1613825311] 'read index received' (duration: 149.478355ms)","trace[1613825311] 'applied index is now lower than readState.Index' (duration: 3.294517ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:01:42.59193Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.381367ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:1 size:351"} {"level":"info","ts":"2026-08-28T04:01:42.592053Z","caller":"traceutil/trace.go:171","msg":"trace[2144413684] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:1; response_revision:289; }","duration":"153.565392ms","start":"2026-08-28T04:01:42.438461Z","end":"2026-08-28T04:01:42.592026Z","steps":["trace[2144413684] 'agreement among raft nodes before linearized reading' (duration: 153.253895ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:02:12.133375Z","caller":"traceutil/trace.go:171","msg":"trace[160750044] transaction","detail":"{read_only:false; response_revision:482; number_of_response:1; }","duration":"111.788668ms","start":"2026-08-28T04:02:12.021565Z","end":"2026-08-28T04:02:12.133353Z","steps":["trace[160750044] 'process raft request' (duration: 111.639215ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:02:12.142415Z","caller":"traceutil/trace.go:171","msg":"trace[314465422] transaction","detail":"{read_only:false; response_revision:483; number_of_response:1; }","duration":"116.737244ms","start":"2026-08-28T04:02:12.025662Z","end":"2026-08-28T04:02:12.142399Z","steps":["trace[314465422] 'process raft request' (duration: 116.547539ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:02:12.14271Z","caller":"traceutil/trace.go:171","msg":"trace[1701640080] transaction","detail":"{read_only:false; response_revision:484; number_of_response:1; }","duration":"103.991656ms","start":"2026-08-28T04:02:12.038704Z","end":"2026-08-28T04:02:12.142696Z","steps":["trace[1701640080] 'process raft request' (duration: 103.595466ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:02:12.144003Z","caller":"traceutil/trace.go:171","msg":"trace[1356635087] transaction","detail":"{read_only:false; response_revision:485; number_of_response:1; }","duration":"101.350523ms","start":"2026-08-28T04:02:12.042627Z","end":"2026-08-28T04:02:12.143978Z","steps":["trace[1356635087] 'process raft request' (duration: 99.733864ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:02:12.285226Z","caller":"traceutil/trace.go:171","msg":"trace[2103705172] transaction","detail":"{read_only:false; response_revision:496; number_of_response:1; }","duration":"134.404977ms","start":"2026-08-28T04:02:12.150774Z","end":"2026-08-28T04:02:12.285179Z","steps":["trace[2103705172] 'process raft request' (duration: 122.850317ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:02:12.285442Z","caller":"traceutil/trace.go:171","msg":"trace[1814267716] transaction","detail":"{read_only:false; response_revision:501; number_of_response:1; }","duration":"114.815678ms","start":"2026-08-28T04:02:12.1706Z","end":"2026-08-28T04:02:12.285415Z","steps":["trace[1814267716] 'process raft request' (duration: 114.229154ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:02:12.285643Z","caller":"traceutil/trace.go:171","msg":"trace[129710551] transaction","detail":"{read_only:false; response_revision:497; number_of_response:1; }","duration":"134.796015ms","start":"2026-08-28T04:02:12.150829Z","end":"2026-08-28T04:02:12.285626Z","steps":["trace[129710551] 'process raft request' (duration: 133.759511ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:02:12.285851Z","caller":"traceutil/trace.go:171","msg":"trace[2142884767] transaction","detail":"{read_only:false; response_revision:498; number_of_response:1; }","duration":"134.98319ms","start":"2026-08-28T04:02:12.150855Z","end":"2026-08-28T04:02:12.285838Z","steps":["trace[2142884767] 'process raft request' (duration: 133.778232ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:02:12.286219Z","caller":"traceutil/trace.go:171","msg":"trace[215432802] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"130.686649ms","start":"2026-08-28T04:02:12.155497Z","end":"2026-08-28T04:02:12.286183Z","steps":["trace[215432802] 'process raft request' (duration: 129.224825ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:02:12.286508Z","caller":"traceutil/trace.go:171","msg":"trace[1432977712] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"123.423369ms","start":"2026-08-28T04:02:12.163069Z","end":"2026-08-28T04:02:12.286492Z","steps":["trace[1432977712] 'process raft request' (duration: 121.717589ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:02:12.286731Z","caller":"traceutil/trace.go:171","msg":"trace[340011372] transaction","detail":"{read_only:false; response_revision:502; number_of_response:1; }","duration":"115.714829ms","start":"2026-08-28T04:02:12.170995Z","end":"2026-08-28T04:02:12.28671Z","steps":["trace[340011372] 'process raft request' (duration: 113.930157ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:02:12.28672Z","caller":"traceutil/trace.go:171","msg":"trace[462398029] transaction","detail":"{read_only:false; response_revision:503; number_of_response:1; }","duration":"112.204487ms","start":"2026-08-28T04:02:12.174485Z","end":"2026-08-28T04:02:12.286689Z","steps":["trace[462398029] 'process raft request' (duration: 110.555978ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:02:12.286819Z","caller":"traceutil/trace.go:171","msg":"trace[1656835972] transaction","detail":"{read_only:false; response_revision:504; number_of_response:1; }","duration":"112.075614ms","start":"2026-08-28T04:02:12.17473Z","end":"2026-08-28T04:02:12.286806Z","steps":["trace[1656835972] 'process raft request' (duration: 110.403545ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:02:12.287022Z","caller":"traceutil/trace.go:171","msg":"trace[376263193] transaction","detail":"{read_only:false; response_revision:505; number_of_response:1; }","duration":"107.717422ms","start":"2026-08-28T04:02:12.17928Z","end":"2026-08-28T04:02:12.286998Z","steps":["trace[376263193] 'process raft request' (duration: 105.94753ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:02:18.794809Z","caller":"traceutil/trace.go:171","msg":"trace[1530818821] transaction","detail":"{read_only:false; response_revision:578; number_of_response:1; }","duration":"336.852001ms","start":"2026-08-28T04:02:18.457932Z","end":"2026-08-28T04:02:18.794784Z","steps":["trace[1530818821] 'process raft request' (duration: 335.49741ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:02:18.795481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:02:18.457902Z","time spent":"336.989334ms","remote":"127.0.0.1:42658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T04:02:19.749301Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2652514930329455929,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-28T04:02:20.147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.035470785s","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:02:20.147193Z","caller":"traceutil/trace.go:171","msg":"trace[762232522] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:582; }","duration":"1.03566082s","start":"2026-08-28T04:02:19.111477Z","end":"2026-08-28T04:02:20.147138Z","steps":["trace[762232522] 'range keys from in-memory index tree' (duration: 1.035448804s)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:02:20.147634Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.700213ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:02:20.147861Z","caller":"traceutil/trace.go:171","msg":"trace[1665300553] transaction","detail":"{read_only:false; response_revision:583; number_of_response:1; }","duration":"1.20452652s","start":"2026-08-28T04:02:18.943307Z","end":"2026-08-28T04:02:20.147834Z","steps":["trace[1665300553] 'process raft request' (duration: 933.102482ms)","trace[1665300553] 'compare' (duration: 270.346103ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:02:20.147932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:02:18.943295Z","time spent":"1.204598942s","remote":"127.0.0.1:42736","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17130,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:02:20.206254Z","caller":"traceutil/trace.go:171","msg":"trace[781775823] linearizableReadLoop","detail":"{readStateIndex:606; appliedIndex:604; }","duration":"957.782368ms","start":"2026-08-28T04:02:19.24845Z","end":"2026-08-28T04:02:20.206233Z","steps":["trace[781775823] 'read index received' (duration: 628.046844ms)","trace[781775823] 'applied index is now lower than readState.Index' (duration: 329.734514ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:02:20.206426Z","caller":"traceutil/trace.go:171","msg":"trace[1714096322] transaction","detail":"{read_only:false; response_revision:584; number_of_response:1; }","duration":"870.827084ms","start":"2026-08-28T04:02:19.335513Z","end":"2026-08-28T04:02:20.20634Z","steps":["trace[1714096322] 'process raft request' (duration: 870.087187ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:02:20.206654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"958.172937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-08-28T04:02:20.206694Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:02:19.33548Z","time spent":"871.068789ms","remote":"127.0.0.1:42658","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036157,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:02:20.206761Z","caller":"traceutil/trace.go:171","msg":"trace[205015610] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:584; }","duration":"958.352342ms","start":"2026-08-28T04:02:19.248384Z","end":"2026-08-28T04:02:20.206736Z","steps":["trace[205015610] 'agreement among raft nodes before linearized reading' (duration: 957.982783ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:02:20.206817Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:02:19.248356Z","time spent":"958.451273ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-28T04:02:20.210835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"717.782084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:02:20.210899Z","caller":"traceutil/trace.go:171","msg":"trace[548688174] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:585; }","duration":"717.875625ms","start":"2026-08-28T04:02:19.493007Z","end":"2026-08-28T04:02:20.210882Z","steps":["trace[548688174] 'agreement among raft nodes before linearized reading' (duration: 717.746202ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:02:20.210932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:02:19.49298Z","time spent":"717.945247ms","remote":"127.0.0.1:42570","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-28T04:03:34.709881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.229622ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:03:34.710013Z","caller":"traceutil/trace.go:171","msg":"trace[607552873] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1435; }","duration":"215.480048ms","start":"2026-08-28T04:03:34.494506Z","end":"2026-08-28T04:03:34.709986Z","steps":["trace[607552873] 'range keys from in-memory index tree' (duration: 215.072818ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:03:34.710202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.225159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:03:34.710352Z","caller":"traceutil/trace.go:171","msg":"trace[1061760226] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:1435; }","duration":"275.474855ms","start":"2026-08-28T04:03:34.434842Z","end":"2026-08-28T04:03:34.710317Z","steps":["trace[1061760226] 'count revisions from in-memory index tree' (duration: 275.017435ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.398433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.623396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:00.39856Z","caller":"traceutil/trace.go:171","msg":"trace[597316152] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:1849; }","duration":"297.844723ms","start":"2026-08-28T04:04:00.100688Z","end":"2026-08-28T04:04:00.398532Z","steps":["trace[597316152] 'count revisions from in-memory index tree' (duration: 297.492942ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.398768Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.258801ms","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-28T04:04:00.398869Z","caller":"traceutil/trace.go:171","msg":"trace[1213087554] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1849; }","duration":"199.397654ms","start":"2026-08-28T04:04:00.199456Z","end":"2026-08-28T04:04:00.398853Z","steps":["trace[1213087554] 'range keys from in-memory index tree' (duration: 199.098476ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.399036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.903881ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-08-28T04:04:00.399085Z","caller":"traceutil/trace.go:171","msg":"trace[1239288581] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1849; }","duration":"332.999534ms","start":"2026-08-28T04:04:00.066068Z","end":"2026-08-28T04:04:00.399067Z","steps":["trace[1239288581] 'range keys from in-memory index tree' (duration: 332.748837ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:00.399124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:04:00.066041Z","time spent":"333.070356ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-28T04:04:08.658906Z","caller":"traceutil/trace.go:171","msg":"trace[14169614] transaction","detail":"{read_only:false; response_revision:1926; number_of_response:1; }","duration":"105.125371ms","start":"2026-08-28T04:04:08.553754Z","end":"2026-08-28T04:04:08.65888Z","steps":["trace[14169614] 'process raft request' (duration: 104.874803ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:12.605487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.139424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:12.605626Z","caller":"traceutil/trace.go:171","msg":"trace[494141632] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1950; }","duration":"113.31688ms","start":"2026-08-28T04:04:12.492288Z","end":"2026-08-28T04:04:12.605605Z","steps":["trace[494141632] 'range keys from in-memory index tree' (duration: 113.074872ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:12.605764Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.626528ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-08-28T04:04:12.605886Z","caller":"traceutil/trace.go:171","msg":"trace[1288560967] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1950; }","duration":"117.758722ms","start":"2026-08-28T04:04:12.488072Z","end":"2026-08-28T04:04:12.605831Z","steps":["trace[1288560967] 'range keys from in-memory index tree' (duration: 117.203727ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:12.606158Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.216225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-08-28T04:04:12.6062Z","caller":"traceutil/trace.go:171","msg":"trace[1580359702] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1950; }","duration":"116.296188ms","start":"2026-08-28T04:04:12.489891Z","end":"2026-08-28T04:04:12.606187Z","steps":["trace[1580359702] 'range keys from in-memory index tree' (duration: 115.35068ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:04:29.236908Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.438909ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:29.237062Z","caller":"traceutil/trace.go:171","msg":"trace[608271118] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2057; }","duration":"125.630674ms","start":"2026-08-28T04:04:29.111414Z","end":"2026-08-28T04:04:29.237045Z","steps":["trace[608271118] 'range keys from in-memory index tree' (duration: 125.418648ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:35.253992Z","caller":"traceutil/trace.go:171","msg":"trace[506350645] transaction","detail":"{read_only:false; response_revision:2089; number_of_response:1; }","duration":"125.82877ms","start":"2026-08-28T04:04:35.128129Z","end":"2026-08-28T04:04:35.253958Z","steps":["trace[506350645] 'process raft request' (duration: 125.592724ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:35.404568Z","caller":"traceutil/trace.go:171","msg":"trace[2071560763] transaction","detail":"{read_only:false; response_revision:2091; number_of_response:1; }","duration":"115.077366ms","start":"2026-08-28T04:04:35.289459Z","end":"2026-08-28T04:04:35.404537Z","steps":["trace[2071560763] 'process raft request' (duration: 114.975684ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:04:35.404795Z","caller":"traceutil/trace.go:171","msg":"trace[1291498680] transaction","detail":"{read_only:false; response_revision:2090; number_of_response:1; }","duration":"262.451715ms","start":"2026-08-28T04:04:35.142315Z","end":"2026-08-28T04:04:35.404766Z","steps":["trace[1291498680] 'process raft request' (duration: 209.48883ms)","trace[1291498680] 'compare' (duration: 52.45449ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:04:35.690846Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.294642ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:04:35.690924Z","caller":"traceutil/trace.go:171","msg":"trace[833408116] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:2091; }","duration":"128.389465ms","start":"2026-08-28T04:04:35.562517Z","end":"2026-08-28T04:04:35.690907Z","steps":["trace[833408116] 'count revisions from in-memory index tree' (duration: 128.21826ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:06:17.947773Z","caller":"traceutil/trace.go:171","msg":"trace[488379568] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"182.428915ms","start":"2026-08-28T04:06:17.765321Z","end":"2026-08-28T04:06:17.94775Z","steps":["trace[488379568] 'process raft request' (duration: 182.284391ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:06:18.16706Z","caller":"traceutil/trace.go:171","msg":"trace[1907215846] linearizableReadLoop","detail":"{readStateIndex:2851; appliedIndex:2850; }","duration":"192.539979ms","start":"2026-08-28T04:06:17.974494Z","end":"2026-08-28T04:06:18.167034Z","steps":["trace[1907215846] 'read index received' (duration: 120.080118ms)","trace[1907215846] 'applied index is now lower than readState.Index' (duration: 72.45895ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:06:18.167273Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.765225ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.27\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-28T04:06:18.167623Z","caller":"traceutil/trace.go:171","msg":"trace[1490650387] range","detail":"{range_begin:/registry/masterleases/162.253.55.27; range_end:; response_count:1; response_revision:2750; }","duration":"193.165695ms","start":"2026-08-28T04:06:17.974442Z","end":"2026-08-28T04:06:18.167607Z","steps":["trace[1490650387] 'agreement among raft nodes before linearized reading' (duration: 192.713364ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:06:18.167266Z","caller":"traceutil/trace.go:171","msg":"trace[157604511] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"357.312747ms","start":"2026-08-28T04:06:17.809926Z","end":"2026-08-28T04:06:18.167238Z","steps":["trace[157604511] 'process raft request' (duration: 284.707272ms)","trace[157604511] 'compare' (duration: 72.259145ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:06:18.167903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:06:17.809908Z","time spent":"357.879502ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-28T04:11:02.539811Z","caller":"traceutil/trace.go:171","msg":"trace[1762533662] transaction","detail":"{read_only:false; response_revision:4761; number_of_response:1; }","duration":"103.4352ms","start":"2026-08-28T04:11:02.436321Z","end":"2026-08-28T04:11:02.539756Z","steps":["trace[1762533662] 'process raft request' (duration: 103.123062ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:11:17.102609Z","caller":"traceutil/trace.go:171","msg":"trace[1645231449] transaction","detail":"{read_only:false; response_revision:5020; number_of_response:1; }","duration":"118.110284ms","start":"2026-08-28T04:11:16.984463Z","end":"2026-08-28T04:11:17.102573Z","steps":["trace[1645231449] 'process raft request' (duration: 26.506965ms)","trace[1645231449] 'compare' (duration: 91.348873ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:11:17.642819Z","caller":"traceutil/trace.go:171","msg":"trace[1247868680] linearizableReadLoop","detail":"{readStateIndex:5215; appliedIndex:5214; }","duration":"229.019593ms","start":"2026-08-28T04:11:17.413768Z","end":"2026-08-28T04:11:17.642787Z","steps":["trace[1247868680] 'read index received' (duration: 228.702316ms)","trace[1247868680] 'applied index is now lower than readState.Index' (duration: 316.107µs)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:11:17.642928Z","caller":"traceutil/trace.go:171","msg":"trace[1341131972] transaction","detail":"{read_only:false; response_revision:5022; number_of_response:1; }","duration":"242.02669ms","start":"2026-08-28T04:11:17.400855Z","end":"2026-08-28T04:11:17.642882Z","steps":["trace[1341131972] 'process raft request' (duration: 241.707023ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:11:17.64314Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.222032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-08-28T04:11:17.643128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.483438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:11:17.643186Z","caller":"traceutil/trace.go:171","msg":"trace[2051006374] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5022; }","duration":"209.333425ms","start":"2026-08-28T04:11:17.433837Z","end":"2026-08-28T04:11:17.64317Z","steps":["trace[2051006374] 'agreement among raft nodes before linearized reading' (duration: 209.111859ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:11:17.643176Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.393632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-08-28T04:11:17.643244Z","caller":"traceutil/trace.go:171","msg":"trace[719878399] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5022; }","duration":"149.681453ms","start":"2026-08-28T04:11:17.493536Z","end":"2026-08-28T04:11:17.643218Z","steps":["trace[719878399] 'agreement among raft nodes before linearized reading' (duration: 149.460707ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:11:17.643254Z","caller":"traceutil/trace.go:171","msg":"trace[1008432445] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5022; }","duration":"229.557777ms","start":"2026-08-28T04:11:17.413676Z","end":"2026-08-28T04:11:17.643234Z","steps":["trace[1008432445] 'agreement among raft nodes before linearized reading' (duration: 229.249918ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:11:17.96904Z","caller":"traceutil/trace.go:171","msg":"trace[1374495082] linearizableReadLoop","detail":"{readStateIndex:5217; appliedIndex:5215; }","duration":"287.22981ms","start":"2026-08-28T04:11:17.681781Z","end":"2026-08-28T04:11:17.969011Z","steps":["trace[1374495082] 'read index received' (duration: 218.084317ms)","trace[1374495082] 'applied index is now lower than readState.Index' (duration: 69.144133ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:11:17.969345Z","caller":"traceutil/trace.go:171","msg":"trace[654884570] transaction","detail":"{read_only:false; response_revision:5023; number_of_response:1; }","duration":"320.480469ms","start":"2026-08-28T04:11:17.648849Z","end":"2026-08-28T04:11:17.969329Z","steps":["trace[654884570] 'process raft request' (duration: 250.998567ms)","trace[654884570] 'compare' (duration: 68.947968ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:11:17.969526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:11:17.648822Z","time spent":"320.590122ms","remote":"127.0.0.1:42818","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-08-28T04:11:17.969765Z","caller":"traceutil/trace.go:171","msg":"trace[899653434] transaction","detail":"{read_only:false; response_revision:5024; number_of_response:1; }","duration":"320.196583ms","start":"2026-08-28T04:11:17.649555Z","end":"2026-08-28T04:11:17.969752Z","steps":["trace[899653434] 'process raft request' (duration: 319.411603ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:11:17.969847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-28T04:11:17.649523Z","time spent":"320.289455ms","remote":"127.0.0.1:42818","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-28T04:11:17.97021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"288.43254ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-08-28T04:11:17.970257Z","caller":"traceutil/trace.go:171","msg":"trace[1111017370] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5024; }","duration":"288.526852ms","start":"2026-08-28T04:11:17.68172Z","end":"2026-08-28T04:11:17.970247Z","steps":["trace[1111017370] 'agreement among raft nodes before linearized reading' (duration: 288.359848ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:11:17.978067Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.945904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-08-28T04:11:17.978172Z","caller":"traceutil/trace.go:171","msg":"trace[558564522] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5025; }","duration":"144.116907ms","start":"2026-08-28T04:11:17.83403Z","end":"2026-08-28T04:11:17.978146Z","steps":["trace[558564522] 'agreement among raft nodes before linearized reading' (duration: 143.823691ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:11:17.981302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.139241ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-28T04:11:17.98141Z","caller":"traceutil/trace.go:171","msg":"trace[1276738680] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5025; }","duration":"118.73606ms","start":"2026-08-28T04:11:17.862651Z","end":"2026-08-28T04:11:17.981387Z","steps":["trace[1276738680] 'agreement among raft nodes before linearized reading' (duration: 115.456609ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:11:18.385816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.097618ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-28T04:11:18.386082Z","caller":"traceutil/trace.go:171","msg":"trace[1413751883] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"260.152801ms","start":"2026-08-28T04:11:18.125909Z","end":"2026-08-28T04:11:18.386062Z","steps":["trace[1413751883] 'process raft request' (duration: 133.714724ms)","trace[1413751883] 'compare' (duration: 125.620057ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:11:18.386301Z","caller":"traceutil/trace.go:171","msg":"trace[871820707] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"260.299135ms","start":"2026-08-28T04:11:18.12599Z","end":"2026-08-28T04:11:18.386289Z","steps":["trace[871820707] 'process raft request' (duration: 259.922155ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:11:18.386883Z","caller":"traceutil/trace.go:171","msg":"trace[1863952249] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"260.725005ms","start":"2026-08-28T04:11:18.126124Z","end":"2026-08-28T04:11:18.386849Z","steps":["trace[1863952249] 'process raft request' (duration: 259.865724ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:11:18.386937Z","caller":"traceutil/trace.go:171","msg":"trace[72686419] linearizableReadLoop","detail":"{readStateIndex:5232; appliedIndex:5228; }","duration":"260.713355ms","start":"2026-08-28T04:11:18.12621Z","end":"2026-08-28T04:11:18.386923Z","steps":["trace[72686419] 'read index received' (duration: 13.244392ms)","trace[72686419] 'applied index is now lower than readState.Index' (duration: 247.467683ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:11:18.387098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.878718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-08-28T04:11:18.387133Z","caller":"traceutil/trace.go:171","msg":"trace[1306074876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5039; }","duration":"260.928979ms","start":"2026-08-28T04:11:18.126195Z","end":"2026-08-28T04:11:18.387124Z","steps":["trace[1306074876] 'agreement among raft nodes before linearized reading' (duration: 260.773416ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:11:18.387265Z","caller":"traceutil/trace.go:171","msg":"trace[1242397048] transaction","detail":"{read_only:false; response_revision:5039; number_of_response:1; }","duration":"257.734653ms","start":"2026-08-28T04:11:18.129517Z","end":"2026-08-28T04:11:18.387252Z","steps":["trace[1242397048] 'process raft request' (duration: 257.236941ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:11:18.594211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.062175ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-28T04:11:18.594358Z","caller":"traceutil/trace.go:171","msg":"trace[1760128556] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5040; }","duration":"100.3043ms","start":"2026-08-28T04:11:18.494023Z","end":"2026-08-28T04:11:18.594328Z","steps":["trace[1760128556] 'range keys from in-memory index tree' (duration: 99.949982ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:11:27.182585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.285153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:13850"} {"level":"info","ts":"2026-08-28T04:11:27.182767Z","caller":"traceutil/trace.go:171","msg":"trace[1471110821] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5219; }","duration":"131.582311ms","start":"2026-08-28T04:11:27.051158Z","end":"2026-08-28T04:11:27.18274Z","steps":["trace[1471110821] 'range keys from in-memory index tree' (duration: 130.184146ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:11:27.182625Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.303793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-5e1ec516-a5dd-42d5-9e5f-70d97a64721d\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-08-28T04:11:27.182893Z","caller":"traceutil/trace.go:171","msg":"trace[1511998724] range","detail":"{range_begin:/registry/persistentvolumes/pvc-5e1ec516-a5dd-42d5-9e5f-70d97a64721d; range_end:; response_count:1; response_revision:5219; }","duration":"131.658853ms","start":"2026-08-28T04:11:27.051194Z","end":"2026-08-28T04:11:27.182852Z","steps":["trace[1511998724] 'range keys from in-memory index tree' (duration: 130.176166ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:11:27.333656Z","caller":"traceutil/trace.go:171","msg":"trace[600288482] transaction","detail":"{read_only:false; response_revision:5221; number_of_response:1; }","duration":"129.853809ms","start":"2026-08-28T04:11:27.203778Z","end":"2026-08-28T04:11:27.333632Z","steps":["trace[600288482] 'process raft request' (duration: 129.658804ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:11:32.216969Z","caller":"traceutil/trace.go:171","msg":"trace[1869948742] linearizableReadLoop","detail":"{readStateIndex:5475; appliedIndex:5474; }","duration":"106.433859ms","start":"2026-08-28T04:11:32.110513Z","end":"2026-08-28T04:11:32.216946Z","steps":["trace[1869948742] 'read index received' (duration: 14.865752ms)","trace[1869948742] 'applied index is now lower than readState.Index' (duration: 91.566867ms)"],"step_count":2} {"level":"info","ts":"2026-08-28T04:11:32.217106Z","caller":"traceutil/trace.go:171","msg":"trace[1282886734] transaction","detail":"{read_only:false; response_revision:5276; number_of_response:1; }","duration":"136.022358ms","start":"2026-08-28T04:11:32.081069Z","end":"2026-08-28T04:11:32.217091Z","steps":["trace[1282886734] 'process raft request' (duration: 44.332639ms)","trace[1282886734] 'compare' (duration: 91.347951ms)"],"step_count":2} {"level":"warn","ts":"2026-08-28T04:11:32.217609Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.928817ms","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-08-28T04:11:32.217693Z","caller":"traceutil/trace.go:171","msg":"trace[1004014138] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5277; }","duration":"134.05132ms","start":"2026-08-28T04:11:32.083622Z","end":"2026-08-28T04:11:32.217673Z","steps":["trace[1004014138] 'agreement among raft nodes before linearized reading' (duration: 133.744012ms)"],"step_count":1} {"level":"warn","ts":"2026-08-28T04:11:32.217627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.191888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-08-28T04:11:32.217802Z","caller":"traceutil/trace.go:171","msg":"trace[1675520492] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5277; }","duration":"112.404404ms","start":"2026-08-28T04:11:32.105381Z","end":"2026-08-28T04:11:32.217785Z","steps":["trace[1675520492] 'agreement among raft nodes before linearized reading' (duration: 112.112937ms)"],"step_count":1} {"level":"info","ts":"2026-08-28T04:11:34.593772Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2839} {"level":"info","ts":"2026-08-28T04:11:34.720496Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2839,"took":"124.774483ms","hash":2817541870,"current-db-size-bytes":51552256,"current-db-size":"52 MB","current-db-size-in-use-bytes":27402240,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-28T04:11:34.720605Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2817541870,"revision":2839,"compact-revision":-1} {"level":"info","ts":"2026-08-28T04:16:34.60043Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5296} {"level":"info","ts":"2026-08-28T04:16:34.669996Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5296,"took":"67.691795ms","hash":4145307718,"current-db-size-bytes":51552256,"current-db-size":"52 MB","current-db-size-in-use-bytes":18665472,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-28T04:16:34.670053Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4145307718,"revision":5296,"compact-revision":2839}