{"level":"warn","ts":"2026-10-27T21:00:07.633954Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-27T21:00:07.634089Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.137:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.137:2380","--initial-cluster=instance=https://199.19.213.137:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.137:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.137: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-10-27T21:00:07.634197Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-10-27T21:00:07.634209Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.137:2380"]} {"level":"info","ts":"2026-10-27T21:00:07.634226Z","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-10-27T21:00:07.634885Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.137:2379"]} {"level":"info","ts":"2026-10-27T21:00:07.634973Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.137:2380"],"listen-peer-urls":["https://199.19.213.137:2380"],"advertise-client-urls":["https://199.19.213.137:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.137:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.137: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-10-27T21:00:07.640624Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.426502ms"} {"level":"info","ts":"2026-10-27T21:00:07.648277Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2ba8e3c2b0588654","cluster-id":"97694c3f9947e4ca"} {"level":"info","ts":"2026-10-27T21:00:07.648376Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 switched to configuration voters=()"} {"level":"info","ts":"2026-10-27T21:00:07.6484Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became follower at term 0"} {"level":"info","ts":"2026-10-27T21:00:07.648411Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2ba8e3c2b0588654 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-10-27T21:00:07.648422Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became follower at term 1"} {"level":"info","ts":"2026-10-27T21:00:07.648463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 switched to configuration voters=(3146014765040043604)"} {"level":"warn","ts":"2026-10-27T21:00:07.657467Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-10-27T21:00:07.659309Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-10-27T21:00:07.660684Z","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-10-27T21:00:07.663031Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2ba8e3c2b0588654","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-10-27T21:00:07.663435Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2ba8e3c2b0588654","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-10-27T21:00:07.663425Z","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-10-27T21:00:07.663561Z","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-10-27T21:00:07.663618Z","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-10-27T21:00:07.665823Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 switched to configuration voters=(3146014765040043604)"} {"level":"info","ts":"2026-10-27T21:00:07.665893Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"97694c3f9947e4ca","local-member-id":"2ba8e3c2b0588654","added-peer-id":"2ba8e3c2b0588654","added-peer-peer-urls":["https://199.19.213.137:2380"]} {"level":"info","ts":"2026-10-27T21:00:07.666487Z","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-10-27T21:00:07.666688Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.137:2380"} {"level":"info","ts":"2026-10-27T21:00:07.666723Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.137:2380"} {"level":"info","ts":"2026-10-27T21:00:07.667033Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2ba8e3c2b0588654","initial-advertise-peer-urls":["https://199.19.213.137:2380"],"listen-peer-urls":["https://199.19.213.137:2380"],"advertise-client-urls":["https://199.19.213.137:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.137:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-10-27T21:00:07.667118Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-10-27T21:00:07.749319Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 is starting a new election at term 1"} {"level":"info","ts":"2026-10-27T21:00:07.749442Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became pre-candidate at term 1"} {"level":"info","ts":"2026-10-27T21:00:07.749478Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 received MsgPreVoteResp from 2ba8e3c2b0588654 at term 1"} {"level":"info","ts":"2026-10-27T21:00:07.749571Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became candidate at term 2"} {"level":"info","ts":"2026-10-27T21:00:07.749642Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 received MsgVoteResp from 2ba8e3c2b0588654 at term 2"} {"level":"info","ts":"2026-10-27T21:00:07.74968Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2ba8e3c2b0588654 became leader at term 2"} {"level":"info","ts":"2026-10-27T21:00:07.74974Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2ba8e3c2b0588654 elected leader 2ba8e3c2b0588654 at term 2"} {"level":"info","ts":"2026-10-27T21:00:07.750804Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T21:00:07.751944Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-27T21:00:07.751943Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2ba8e3c2b0588654","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.137:2379]}","request-path":"/0/members/2ba8e3c2b0588654/attributes","cluster-id":"97694c3f9947e4ca","publish-timeout":"7s"} {"level":"info","ts":"2026-10-27T21:00:07.752187Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-10-27T21:00:07.752445Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-10-27T21:00:07.752605Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"97694c3f9947e4ca","local-member-id":"2ba8e3c2b0588654","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T21:00:07.752866Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T21:00:07.752931Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-10-27T21:00:07.752516Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-10-27T21:00:07.755147Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-10-27T21:00:07.755165Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.137:2379"} {"level":"warn","ts":"2026-10-27T21:00:11.072061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.178477ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-10-27T21:00:11.072181Z","caller":"traceutil/trace.go:171","msg":"trace[223202099] transaction","detail":"{read_only:false; response_revision:155; number_of_response:1; }","duration":"402.458001ms","start":"2026-10-27T21:00:10.669704Z","end":"2026-10-27T21:00:11.072162Z","steps":["trace[223202099] 'process raft request' (duration: 112.656736ms)","trace[223202099] 'compare' (duration: 289.017285ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:00:11.072245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:00:10.669678Z","time spent":"402.543702ms","remote":"127.0.0.1:46262","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":710,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-10-27T21:00:11.483017Z","caller":"traceutil/trace.go:171","msg":"trace[1066139717] linearizableReadLoop","detail":"{readStateIndex:161; appliedIndex:160; }","duration":"407.623875ms","start":"2026-10-27T21:00:11.075365Z","end":"2026-10-27T21:00:11.482989Z","steps":["trace[1066139717] 'read index received' (duration: 388.459587ms)","trace[1066139717] 'applied index is now lower than readState.Index' (duration: 19.163448ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:00:11.483157Z","caller":"traceutil/trace.go:171","msg":"trace[362411644] transaction","detail":"{read_only:false; response_revision:156; number_of_response:1; }","duration":"567.410029ms","start":"2026-10-27T21:00:10.915736Z","end":"2026-10-27T21:00:11.483146Z","steps":["trace[362411644] 'process raft request' (duration: 548.024288ms)","trace[362411644] 'compare' (duration: 19.084288ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:00:11.483285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:00:10.915705Z","time spent":"567.480311ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2905,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-27T21:00:11.483542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"408.114443ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/system:controller:node-controller\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:00:11.48368Z","caller":"traceutil/trace.go:171","msg":"trace[387146233] range","detail":"{range_begin:/registry/clusterroles/system:controller:node-controller; range_end:; response_count:0; response_revision:156; }","duration":"408.330375ms","start":"2026-10-27T21:00:11.075317Z","end":"2026-10-27T21:00:11.483647Z","steps":["trace[387146233] 'agreement among raft nodes before linearized reading' (duration: 407.923ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:00:11.483807Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:00:11.075299Z","time spent":"408.487007ms","remote":"127.0.0.1:46262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":58,"response count":0,"response size":28,"request content":"key:\"/registry/clusterroles/system:controller:node-controller\" "} {"level":"info","ts":"2026-10-27T21:01:03.723226Z","caller":"traceutil/trace.go:171","msg":"trace[608467656] transaction","detail":"{read_only:false; response_revision:665; number_of_response:1; }","duration":"130.109091ms","start":"2026-10-27T21:01:03.593085Z","end":"2026-10-27T21:01:03.723194Z","steps":["trace[608467656] 'process raft request' (duration: 52.142598ms)","trace[608467656] 'compare' (duration: 77.64859ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:01:04.338194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.022939ms","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-10-27T21:01:04.338316Z","caller":"traceutil/trace.go:171","msg":"trace[1228391804] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:665; }","duration":"256.183792ms","start":"2026-10-27T21:01:04.082102Z","end":"2026-10-27T21:01:04.338285Z","steps":["trace[1228391804] 'range keys from in-memory index tree' (duration: 255.921538ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:01:04.387986Z","caller":"traceutil/trace.go:171","msg":"trace[459953649] linearizableReadLoop","detail":"{readStateIndex:694; appliedIndex:693; }","duration":"239.068539ms","start":"2026-10-27T21:01:04.148894Z","end":"2026-10-27T21:01:04.387962Z","steps":["trace[459953649] 'read index received' (duration: 238.085989ms)","trace[459953649] 'applied index is now lower than readState.Index' (duration: 981.55µs)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:01:04.388133Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.212651ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-10-27T21:01:04.388022Z","caller":"traceutil/trace.go:171","msg":"trace[863812462] transaction","detail":"{read_only:false; response_revision:666; number_of_response:1; }","duration":"258.915111ms","start":"2026-10-27T21:01:04.129091Z","end":"2026-10-27T21:01:04.388006Z","steps":["trace[863812462] 'process raft request' (duration: 258.164473ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:01:04.38816Z","caller":"traceutil/trace.go:171","msg":"trace[43659254] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:666; }","duration":"239.293841ms","start":"2026-10-27T21:01:04.148859Z","end":"2026-10-27T21:01:04.388153Z","steps":["trace[43659254] 'agreement among raft nodes before linearized reading' (duration: 239.20555ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:01:05.803068Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.31072083s","expected-duration":"1s"} {"level":"warn","ts":"2026-10-27T21:01:05.876852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.384195842s","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T21:01:05.877078Z","caller":"traceutil/trace.go:171","msg":"trace[595950712] transaction","detail":"{read_only:false; response_revision:667; number_of_response:1; }","duration":"1.53319614s","start":"2026-10-27T21:01:04.343849Z","end":"2026-10-27T21:01:05.877045Z","steps":["trace[595950712] 'process raft request' (duration: 148.478643ms)","trace[595950712] 'compare' (duration: 1.383946549s)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:01:05.877184Z","caller":"traceutil/trace.go:171","msg":"trace[808153820] linearizableReadLoop","detail":"{readStateIndex:695; appliedIndex:694; }","duration":"1.489032719s","start":"2026-10-27T21:01:04.388126Z","end":"2026-10-27T21:01:05.877159Z","steps":["trace[808153820] 'read index received' (duration: 104.09139ms)","trace[808153820] 'applied index is now lower than readState.Index' (duration: 1.384938779s)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:01:05.877273Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:01:04.343823Z","time spent":"1.533331531s","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:01:05.877322Z","caller":"traceutil/trace.go:171","msg":"trace[1045348107] transaction","detail":"{read_only:false; response_revision:668; number_of_response:1; }","duration":"1.484139027s","start":"2026-10-27T21:01:04.393174Z","end":"2026-10-27T21:01:05.877313Z","steps":["trace[1045348107] 'process raft request' (duration: 1.483936115s)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:01:05.877325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.66260201s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bcsv7\" ","response":"range_response_count:1 size:16965"} {"level":"warn","ts":"2026-10-27T21:01:05.877378Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:01:04.393147Z","time spent":"1.484198148s","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:01:05.877396Z","caller":"traceutil/trace.go:171","msg":"trace[708858456] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bcsv7; range_end:; response_count:1; response_revision:668; }","duration":"1.662712171s","start":"2026-10-27T21:01:04.214657Z","end":"2026-10-27T21:01:05.877369Z","steps":["trace[708858456] 'agreement among raft nodes before linearized reading' (duration: 1.662502938s)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:01:05.877438Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:01:04.214635Z","time spent":"1.662793192s","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16988,"request content":"key:\"/registry/pods/kube-system/cilium-bcsv7\" "} {"level":"info","ts":"2026-10-27T21:01:05.877861Z","caller":"traceutil/trace.go:171","msg":"trace[751587408] transaction","detail":"{read_only:false; response_revision:670; number_of_response:1; }","duration":"786.445333ms","start":"2026-10-27T21:01:05.0914Z","end":"2026-10-27T21:01:05.877845Z","steps":["trace[751587408] 'process raft request' (duration: 786.278091ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:01:05.877826Z","caller":"traceutil/trace.go:171","msg":"trace[1206517926] transaction","detail":"{read_only:false; response_revision:669; number_of_response:1; }","duration":"1.438022805s","start":"2026-10-27T21:01:04.439784Z","end":"2026-10-27T21:01:05.877807Z","steps":["trace[1206517926] 'process raft request' (duration: 1.437390659s)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:01:05.877913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:01:05.091361Z","time spent":"786.527083ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:01:05.877942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:01:04.439761Z","time spent":"1.438136007s","remote":"127.0.0.1:46458","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-10-27T21:01:05.877987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.431245523s","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-10-27T21:01:05.878078Z","caller":"traceutil/trace.go:171","msg":"trace[463352379] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:670; }","duration":"1.431350885s","start":"2026-10-27T21:01:04.446704Z","end":"2026-10-27T21:01:05.878055Z","steps":["trace[463352379] 'agreement among raft nodes before linearized reading' (duration: 1.431132563s)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:01:05.878095Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.725229937s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:01:05.878124Z","caller":"traceutil/trace.go:171","msg":"trace[1042499095] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:670; }","duration":"1.725293549s","start":"2026-10-27T21:01:04.152821Z","end":"2026-10-27T21:01:05.878114Z","steps":["trace[1042499095] 'agreement among raft nodes before linearized reading' (duration: 1.72445791s)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:01:05.878131Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:01:04.44669Z","time spent":"1.431429326s","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-10-27T21:01:05.878147Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:01:04.152799Z","time spent":"1.725343949s","remote":"127.0.0.1:45920","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-10-27T21:01:05.878367Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.31181042s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-10-27T21:01:05.878423Z","caller":"traceutil/trace.go:171","msg":"trace[2023428091] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:670; }","duration":"1.312103804s","start":"2026-10-27T21:01:04.566306Z","end":"2026-10-27T21:01:05.87841Z","steps":["trace[2023428091] 'agreement among raft nodes before linearized reading' (duration: 1.311945402s)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:01:05.878464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:01:04.56628Z","time spent":"1.312174464s","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-10-27T21:01:07.857285Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.647282ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:01:07.857411Z","caller":"traceutil/trace.go:171","msg":"trace[672716845] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumclusterwidenetworkpolicies.cilium.io; range_end:; response_count:0; response_revision:693; }","duration":"269.893066ms","start":"2026-10-27T21:01:07.587491Z","end":"2026-10-27T21:01:07.857384Z","steps":["trace[672716845] 'range keys from in-memory index tree' (duration: 269.532951ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:01:33.166854Z","caller":"traceutil/trace.go:171","msg":"trace[2013545969] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"131.722429ms","start":"2026-10-27T21:01:33.035105Z","end":"2026-10-27T21:01:33.166828Z","steps":["trace[2013545969] 'process raft request' (duration: 130.381885ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:01:33.446571Z","caller":"traceutil/trace.go:171","msg":"trace[718935175] transaction","detail":"{read_only:false; response_revision:981; number_of_response:1; }","duration":"120.112877ms","start":"2026-10-27T21:01:33.326433Z","end":"2026-10-27T21:01:33.446546Z","steps":["trace[718935175] 'process raft request' (duration: 116.329687ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:01:35.968466Z","caller":"traceutil/trace.go:171","msg":"trace[1185558788] transaction","detail":"{read_only:false; response_revision:997; number_of_response:1; }","duration":"153.86216ms","start":"2026-10-27T21:01:35.814579Z","end":"2026-10-27T21:01:35.968441Z","steps":["trace[1185558788] 'process raft request' (duration: 153.685682ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:01:36.199572Z","caller":"traceutil/trace.go:171","msg":"trace[798251552] transaction","detail":"{read_only:false; response_revision:999; number_of_response:1; }","duration":"222.581553ms","start":"2026-10-27T21:01:35.97697Z","end":"2026-10-27T21:01:36.199552Z","steps":["trace[798251552] 'process raft request' (duration: 222.379733ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:01:36.199725Z","caller":"traceutil/trace.go:171","msg":"trace[1842196241] linearizableReadLoop","detail":"{readStateIndex:1036; appliedIndex:1034; }","duration":"141.959262ms","start":"2026-10-27T21:01:36.057737Z","end":"2026-10-27T21:01:36.199696Z","steps":["trace[1842196241] 'read index received' (duration: 141.546053ms)","trace[1842196241] 'applied index is now lower than readState.Index' (duration: 411.479µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:01:36.199868Z","caller":"traceutil/trace.go:171","msg":"trace[2086323162] transaction","detail":"{read_only:false; response_revision:1000; number_of_response:1; }","duration":"222.30388ms","start":"2026-10-27T21:01:35.97755Z","end":"2026-10-27T21:01:36.199854Z","steps":["trace[2086323162] 'process raft request' (duration: 221.952983ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:01:36.200098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.32895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:01:36.200193Z","caller":"traceutil/trace.go:171","msg":"trace[254326199] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1000; }","duration":"142.483647ms","start":"2026-10-27T21:01:36.057684Z","end":"2026-10-27T21:01:36.200168Z","steps":["trace[254326199] 'agreement among raft nodes before linearized reading' (duration: 142.302249ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:01:55.301292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.694093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success: > failure: >>","response":"size:18"} {"level":"info","ts":"2026-10-27T21:01:55.301394Z","caller":"traceutil/trace.go:171","msg":"trace[1174321097] linearizableReadLoop","detail":"{readStateIndex:1298; appliedIndex:1297; }","duration":"148.655216ms","start":"2026-10-27T21:01:55.152722Z","end":"2026-10-27T21:01:55.301378Z","steps":["trace[1174321097] 'read index received' (duration: 34.136059ms)","trace[1174321097] 'applied index is now lower than readState.Index' (duration: 114.518067ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:01:55.301407Z","caller":"traceutil/trace.go:171","msg":"trace[982871706] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1257; }","duration":"213.752849ms","start":"2026-10-27T21:01:55.08764Z","end":"2026-10-27T21:01:55.301392Z","steps":["trace[982871706] 'process raft request' (duration: 99.359787ms)","trace[982871706] 'compare' (duration: 113.368859ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:01:55.30145Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.733719ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:01:55.301478Z","caller":"traceutil/trace.go:171","msg":"trace[709151469] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1257; }","duration":"148.813233ms","start":"2026-10-27T21:01:55.152657Z","end":"2026-10-27T21:01:55.30147Z","steps":["trace[709151469] 'agreement among raft nodes before linearized reading' (duration: 148.764551ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:02:13.676865Z","caller":"traceutil/trace.go:171","msg":"trace[1147879395] transaction","detail":"{read_only:false; response_revision:1399; number_of_response:1; }","duration":"106.058998ms","start":"2026-10-27T21:02:13.570777Z","end":"2026-10-27T21:02:13.676836Z","steps":["trace[1147879395] 'process raft request' (duration: 105.86862ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:02:39.232794Z","caller":"traceutil/trace.go:171","msg":"trace[1285190] transaction","detail":"{read_only:false; response_revision:1823; number_of_response:1; }","duration":"139.596188ms","start":"2026-10-27T21:02:39.09313Z","end":"2026-10-27T21:02:39.232726Z","steps":["trace[1285190] 'process raft request' (duration: 138.934195ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:02:51.026066Z","caller":"traceutil/trace.go:171","msg":"trace[1387292855] linearizableReadLoop","detail":"{readStateIndex:2035; appliedIndex:2032; }","duration":"232.963619ms","start":"2026-10-27T21:02:50.793074Z","end":"2026-10-27T21:02:51.026038Z","steps":["trace[1387292855] 'read index received' (duration: 17.19126ms)","trace[1387292855] 'applied index is now lower than readState.Index' (duration: 215.771539ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:02:51.026142Z","caller":"traceutil/trace.go:171","msg":"trace[1081658285] transaction","detail":"{read_only:false; response_revision:1954; number_of_response:1; }","duration":"244.328423ms","start":"2026-10-27T21:02:50.781748Z","end":"2026-10-27T21:02:51.026076Z","steps":["trace[1081658285] 'process raft request' (duration: 146.712879ms)","trace[1081658285] 'compare' (duration: 97.119808ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:02:51.026498Z","caller":"traceutil/trace.go:171","msg":"trace[468596272] transaction","detail":"{read_only:false; response_revision:1955; number_of_response:1; }","duration":"240.611294ms","start":"2026-10-27T21:02:50.785684Z","end":"2026-10-27T21:02:51.026295Z","steps":["trace[468596272] 'process raft request' (duration: 240.230072ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:02:51.026596Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.495837ms","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-10-27T21:02:51.026648Z","caller":"traceutil/trace.go:171","msg":"trace[190300948] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1955; }","duration":"233.62722ms","start":"2026-10-27T21:02:50.793008Z","end":"2026-10-27T21:02:51.026635Z","steps":["trace[190300948] 'agreement among raft nodes before linearized reading' (duration: 233.418873ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:02:51.026966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.80104ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-10-27T21:02:51.027039Z","caller":"traceutil/trace.go:171","msg":"trace[428664841] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1955; }","duration":"205.909163ms","start":"2026-10-27T21:02:50.821112Z","end":"2026-10-27T21:02:51.027021Z","steps":["trace[428664841] 'agreement among raft nodes before linearized reading' (duration: 205.534481ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:02:51.349571Z","caller":"traceutil/trace.go:171","msg":"trace[1915319500] transaction","detail":"{read_only:false; response_revision:1956; number_of_response:1; }","duration":"314.471296ms","start":"2026-10-27T21:02:51.035064Z","end":"2026-10-27T21:02:51.349536Z","steps":["trace[1915319500] 'process raft request' (duration: 221.298095ms)","trace[1915319500] 'compare' (duration: 93.033967ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:02:51.350458Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:02:51.035022Z","time spent":"315.306344ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:02:51.387719Z","caller":"traceutil/trace.go:171","msg":"trace[1546142626] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"352.340215ms","start":"2026-10-27T21:02:51.035338Z","end":"2026-10-27T21:02:51.387678Z","steps":["trace[1546142626] 'process raft request' (duration: 352.063756ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:02:51.38781Z","caller":"traceutil/trace.go:171","msg":"trace[582684037] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"352.408806ms","start":"2026-10-27T21:02:51.035382Z","end":"2026-10-27T21:02:51.387791Z","steps":["trace[582684037] 'process raft request' (duration: 352.274182ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:02:51.387929Z","caller":"traceutil/trace.go:171","msg":"trace[710668618] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"352.474809ms","start":"2026-10-27T21:02:51.03537Z","end":"2026-10-27T21:02:51.387845Z","steps":["trace[710668618] 'process raft request' (duration: 352.217401ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:02:51.388019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:02:51.035368Z","time spent":"352.552141ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:02:51.388026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:02:51.035313Z","time spent":"352.5209ms","remote":"127.0.0.1:46184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1471,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:02:51.388182Z","caller":"traceutil/trace.go:171","msg":"trace[1486378768] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"351.629462ms","start":"2026-10-27T21:02:51.036532Z","end":"2026-10-27T21:02:51.388162Z","steps":["trace[1486378768] 'process raft request' (duration: 351.188478ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:02:51.388289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:02:51.035363Z","time spent":"352.675235ms","remote":"127.0.0.1:46066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":893,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:02:51.388285Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:02:51.036519Z","time spent":"351.707484ms","remote":"127.0.0.1:46184","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1529,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:02:51.388725Z","caller":"traceutil/trace.go:171","msg":"trace[734218347] transaction","detail":"{read_only:false; response_revision:1961; number_of_response:1; }","duration":"352.154728ms","start":"2026-10-27T21:02:51.036552Z","end":"2026-10-27T21:02:51.388706Z","steps":["trace[734218347] 'process raft request' (duration: 351.531588ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:02:51.388789Z","caller":"traceutil/trace.go:171","msg":"trace[1642960733] linearizableReadLoop","detail":"{readStateIndex:2042; appliedIndex:2035; }","duration":"300.917455ms","start":"2026-10-27T21:02:51.087846Z","end":"2026-10-27T21:02:51.388764Z","steps":["trace[1642960733] 'read index received' (duration: 168.535144ms)","trace[1642960733] 'applied index is now lower than readState.Index' (duration: 132.381081ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:02:51.388814Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:02:51.036543Z","time spent":"352.22151ms","remote":"127.0.0.1:46066","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":977,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:02:51.388838Z","caller":"traceutil/trace.go:171","msg":"trace[1669284791] transaction","detail":"{read_only:false; response_revision:1962; number_of_response:1; }","duration":"348.402269ms","start":"2026-10-27T21:02:51.04042Z","end":"2026-10-27T21:02:51.388822Z","steps":["trace[1669284791] 'process raft request' (duration: 348.164771ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:02:51.38895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:02:51.040408Z","time spent":"348.498872ms","remote":"127.0.0.1:46086","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3729,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:02:51.389302Z","caller":"traceutil/trace.go:171","msg":"trace[1316432335] transaction","detail":"{read_only:false; response_revision:1963; number_of_response:1; }","duration":"176.663073ms","start":"2026-10-27T21:02:51.212558Z","end":"2026-10-27T21:02:51.389221Z","steps":["trace[1316432335] 'process raft request' (duration: 176.138826ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:02:51.389319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.291302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-10-27T21:02:51.389415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.562995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5732"} {"level":"info","ts":"2026-10-27T21:02:51.389422Z","caller":"traceutil/trace.go:171","msg":"trace[1670400630] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1963; }","duration":"235.504759ms","start":"2026-10-27T21:02:51.153901Z","end":"2026-10-27T21:02:51.389406Z","steps":["trace[1670400630] 'agreement among raft nodes before linearized reading' (duration: 235.295112ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:02:51.38948Z","caller":"traceutil/trace.go:171","msg":"trace[1699753455] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1963; }","duration":"301.655168ms","start":"2026-10-27T21:02:51.087796Z","end":"2026-10-27T21:02:51.389451Z","steps":["trace[1699753455] 'agreement among raft nodes before linearized reading' (duration: 301.424671ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:02:51.389519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:02:51.087771Z","time spent":"301.737281ms","remote":"127.0.0.1:43052","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5755,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-10-27T21:03:01.971309Z","caller":"traceutil/trace.go:171","msg":"trace[1015271671] transaction","detail":"{read_only:false; response_revision:2050; number_of_response:1; }","duration":"105.860107ms","start":"2026-10-27T21:03:01.865411Z","end":"2026-10-27T21:03:01.971271Z","steps":["trace[1015271671] 'process raft request' (duration: 36.455942ms)","trace[1015271671] 'get key's previous created_revision and leaseID' {req_type:put; key:/registry/pods/openstack/percona-xtradb-pxc-0; req_size:12318; } (duration: 69.22491ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:03:04.480131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.346573ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-10-27T21:03:04.480206Z","caller":"traceutil/trace.go:171","msg":"trace[899409694] linearizableReadLoop","detail":"{readStateIndex:2161; appliedIndex:2160; }","duration":"327.993886ms","start":"2026-10-27T21:03:04.1522Z","end":"2026-10-27T21:03:04.480194Z","steps":["trace[899409694] 'read index received' (duration: 47.496408ms)","trace[899409694] 'applied index is now lower than readState.Index' (duration: 280.496448ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:03:04.480386Z","caller":"traceutil/trace.go:171","msg":"trace[1876804975] transaction","detail":"{read_only:false; response_revision:2077; number_of_response:1; }","duration":"456.77779ms","start":"2026-10-27T21:03:04.023596Z","end":"2026-10-27T21:03:04.480374Z","steps":["trace[1876804975] 'process raft request' (duration: 176.119398ms)","trace[1876804975] 'compare' (duration: 279.58798ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:03:04.480427Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:03:04.023568Z","time spent":"456.836442ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:03:04.480629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.414619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:03:04.480721Z","caller":"traceutil/trace.go:171","msg":"trace[1468699450] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2077; }","duration":"328.558933ms","start":"2026-10-27T21:03:04.152141Z","end":"2026-10-27T21:03:04.4807Z","steps":["trace[1468699450] 'agreement among raft nodes before linearized reading' (duration: 328.413638ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:03:04.480781Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:03:04.152117Z","time spent":"328.640106ms","remote":"127.0.0.1:45920","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-10-27T21:03:08.47317Z","caller":"traceutil/trace.go:171","msg":"trace[934876035] transaction","detail":"{read_only:false; response_revision:2097; number_of_response:1; }","duration":"180.820916ms","start":"2026-10-27T21:03:08.292324Z","end":"2026-10-27T21:03:08.473145Z","steps":["trace[934876035] 'process raft request' (duration: 180.676182ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:03:15.137834Z","caller":"traceutil/trace.go:171","msg":"trace[1192426858] transaction","detail":"{read_only:false; response_revision:2139; number_of_response:1; }","duration":"113.550568ms","start":"2026-10-27T21:03:15.024243Z","end":"2026-10-27T21:03:15.137794Z","steps":["trace[1192426858] 'process raft request' (duration: 38.281939ms)","trace[1192426858] 'compare' (duration: 75.075514ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:03:31.687768Z","caller":"traceutil/trace.go:171","msg":"trace[1880024809] linearizableReadLoop","detail":"{readStateIndex:2313; appliedIndex:2312; }","duration":"342.074512ms","start":"2026-10-27T21:03:31.345663Z","end":"2026-10-27T21:03:31.687738Z","steps":["trace[1880024809] 'read index received' (duration: 341.855806ms)","trace[1880024809] 'applied index is now lower than readState.Index' (duration: 217.026µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:03:31.687831Z","caller":"traceutil/trace.go:171","msg":"trace[1224388703] transaction","detail":"{read_only:false; response_revision:2223; number_of_response:1; }","duration":"468.129409ms","start":"2026-10-27T21:03:31.219642Z","end":"2026-10-27T21:03:31.687772Z","steps":["trace[1224388703] 'process raft request' (duration: 467.903073ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:03:31.688108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"342.390691ms","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-10-27T21:03:31.688156Z","caller":"traceutil/trace.go:171","msg":"trace[2022396575] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2223; }","duration":"342.535995ms","start":"2026-10-27T21:03:31.345604Z","end":"2026-10-27T21:03:31.68814Z","steps":["trace[2022396575] 'agreement among raft nodes before linearized reading' (duration: 342.301038ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:03:31.688167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:03:31.219625Z","time spent":"468.339155ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-10-27T21:03:31.688196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:03:31.345574Z","time spent":"342.610026ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-10-27T21:03:31.710604Z","caller":"traceutil/trace.go:171","msg":"trace[670135744] transaction","detail":"{read_only:false; response_revision:2224; number_of_response:1; }","duration":"110.804618ms","start":"2026-10-27T21:03:31.599745Z","end":"2026-10-27T21:03:31.71055Z","steps":["trace[670135744] 'process raft request' (duration: 110.553801ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:03:31.99498Z","caller":"traceutil/trace.go:171","msg":"trace[563770838] transaction","detail":"{read_only:false; response_revision:2226; number_of_response:1; }","duration":"277.819164ms","start":"2026-10-27T21:03:31.717125Z","end":"2026-10-27T21:03:31.994944Z","steps":["trace[563770838] 'process raft request' (duration: 277.736302ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:03:31.99501Z","caller":"traceutil/trace.go:171","msg":"trace[701655081] linearizableReadLoop","detail":"{readStateIndex:2317; appliedIndex:2314; }","duration":"258.812584ms","start":"2026-10-27T21:03:31.736179Z","end":"2026-10-27T21:03:31.994991Z","steps":["trace[701655081] 'read index received' (duration: 217.405022ms)","trace[701655081] 'applied index is now lower than readState.Index' (duration: 41.406582ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:03:31.995239Z","caller":"traceutil/trace.go:171","msg":"trace[1888158398] transaction","detail":"{read_only:false; response_revision:2227; number_of_response:1; }","duration":"278.06349ms","start":"2026-10-27T21:03:31.717156Z","end":"2026-10-27T21:03:31.99522Z","steps":["trace[1888158398] 'process raft request' (duration: 277.747452ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:03:31.995279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.062732ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-10-27T21:03:31.995323Z","caller":"traceutil/trace.go:171","msg":"trace[1771975629] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2227; }","duration":"259.195405ms","start":"2026-10-27T21:03:31.736112Z","end":"2026-10-27T21:03:31.995307Z","steps":["trace[1771975629] 'agreement among raft nodes before linearized reading' (duration: 258.977749ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:03:31.996425Z","caller":"traceutil/trace.go:171","msg":"trace[967001999] transaction","detail":"{read_only:false; response_revision:2225; number_of_response:1; }","duration":"302.236001ms","start":"2026-10-27T21:03:31.693887Z","end":"2026-10-27T21:03:31.996123Z","steps":["trace[967001999] 'process raft request' (duration: 259.736549ms)","trace[967001999] 'compare' (duration: 41.070913ms)"],"step_count":2} {"level":"warn","ts":"2026-10-27T21:03:31.996671Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:03:31.693842Z","time spent":"302.682292ms","remote":"127.0.0.1:46174","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-10-27T21:03:32.216092Z","caller":"traceutil/trace.go:171","msg":"trace[2015333877] transaction","detail":"{read_only:false; response_revision:2229; number_of_response:1; }","duration":"214.621025ms","start":"2026-10-27T21:03:32.001417Z","end":"2026-10-27T21:03:32.216038Z","steps":["trace[2015333877] 'process raft request' (duration: 208.09953ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:03:32.278535Z","caller":"traceutil/trace.go:171","msg":"trace[427523906] linearizableReadLoop","detail":"{readStateIndex:2320; appliedIndex:2318; }","duration":"123.972318ms","start":"2026-10-27T21:03:32.154533Z","end":"2026-10-27T21:03:32.278505Z","steps":["trace[427523906] 'read index received' (duration: 55.017152ms)","trace[427523906] 'applied index is now lower than readState.Index' (duration: 68.954306ms)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:03:32.278664Z","caller":"traceutil/trace.go:171","msg":"trace[1495317154] transaction","detail":"{read_only:false; response_revision:2230; number_of_response:1; }","duration":"141.495728ms","start":"2026-10-27T21:03:32.137119Z","end":"2026-10-27T21:03:32.278615Z","steps":["trace[1495317154] 'process raft request' (duration: 141.20666ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:03:32.278817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.248696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:03:32.278977Z","caller":"traceutil/trace.go:171","msg":"trace[1151041976] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2230; }","duration":"124.366748ms","start":"2026-10-27T21:03:32.154494Z","end":"2026-10-27T21:03:32.278861Z","steps":["trace[1151041976] 'agreement among raft nodes before linearized reading' (duration: 124.124222ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:04:33.0601Z","caller":"traceutil/trace.go:171","msg":"trace[2119579673] transaction","detail":"{read_only:false; response_revision:2660; number_of_response:1; }","duration":"202.04542ms","start":"2026-10-27T21:04:32.858021Z","end":"2026-10-27T21:04:33.060067Z","steps":["trace[2119579673] 'process raft request' (duration: 201.814055ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:04:33.073595Z","caller":"traceutil/trace.go:171","msg":"trace[945329917] transaction","detail":"{read_only:false; response_revision:2661; number_of_response:1; }","duration":"206.951805ms","start":"2026-10-27T21:04:32.866589Z","end":"2026-10-27T21:04:33.073541Z","steps":["trace[945329917] 'process raft request' (duration: 206.748521ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:05:04.196883Z","caller":"traceutil/trace.go:171","msg":"trace[879833660] transaction","detail":"{read_only:false; response_revision:2830; number_of_response:1; }","duration":"204.70741ms","start":"2026-10-27T21:05:03.992141Z","end":"2026-10-27T21:05:04.196848Z","steps":["trace[879833660] 'process raft request' (duration: 204.306152ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:09:24.860929Z","caller":"traceutil/trace.go:171","msg":"trace[1491795604] transaction","detail":"{read_only:false; response_revision:4805; number_of_response:1; }","duration":"125.014135ms","start":"2026-10-27T21:09:24.735861Z","end":"2026-10-27T21:09:24.860875Z","steps":["trace[1491795604] 'process raft request' (duration: 124.715261ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:09:31.020635Z","caller":"traceutil/trace.go:171","msg":"trace[1425077281] linearizableReadLoop","detail":"{readStateIndex:5116; appliedIndex:5115; }","duration":"102.30938ms","start":"2026-10-27T21:09:30.918308Z","end":"2026-10-27T21:09:31.020617Z","steps":["trace[1425077281] 'read index received' (duration: 102.178379ms)","trace[1425077281] 'applied index is now lower than readState.Index' (duration: 129.741µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:09:31.020771Z","caller":"traceutil/trace.go:171","msg":"trace[1200958113] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"105.443325ms","start":"2026-10-27T21:09:30.915294Z","end":"2026-10-27T21:09:31.020737Z","steps":["trace[1200958113] 'process raft request' (duration: 105.193842ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:09:31.02147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.129872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-10-27T21:09:31.021552Z","caller":"traceutil/trace.go:171","msg":"trace[2056223274] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4918; }","duration":"103.234613ms","start":"2026-10-27T21:09:30.918294Z","end":"2026-10-27T21:09:31.021529Z","steps":["trace[2056223274] 'agreement among raft nodes before linearized reading' (duration: 102.99987ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:09:34.961852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.18569ms","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":"info","ts":"2026-10-27T21:09:34.962004Z","caller":"traceutil/trace.go:171","msg":"trace[888140906] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5005; }","duration":"305.386543ms","start":"2026-10-27T21:09:34.656571Z","end":"2026-10-27T21:09:34.961957Z","steps":["trace[888140906] 'count revisions from in-memory index tree' (duration: 305.064828ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:09:34.962049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.153836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc7a64f0-6bbc-41aa-8c51-92b540e2e682\" ","response":"range_response_count:1 size:3646"} {"level":"warn","ts":"2026-10-27T21:09:34.962069Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-10-27T21:09:34.656534Z","time spent":"305.515155ms","remote":"127.0.0.1:43028","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-10-27T21:09:34.962087Z","caller":"traceutil/trace.go:171","msg":"trace[1138633748] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bc7a64f0-6bbc-41aa-8c51-92b540e2e682; range_end:; response_count:1; response_revision:5005; }","duration":"186.234228ms","start":"2026-10-27T21:09:34.775841Z","end":"2026-10-27T21:09:34.962076Z","steps":["trace[1138633748] 'range keys from in-memory index tree' (duration: 185.889523ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:09:34.962296Z","caller":"traceutil/trace.go:171","msg":"trace[1823250737] transaction","detail":"{read_only:false; response_revision:5006; number_of_response:1; }","duration":"169.779337ms","start":"2026-10-27T21:09:34.79248Z","end":"2026-10-27T21:09:34.962259Z","steps":["trace[1823250737] 'process raft request' (duration: 168.876834ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:09:34.962562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.579694ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bc7a64f0-6bbc-41aa-8c51-92b540e2e682\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-10-27T21:09:34.962591Z","caller":"traceutil/trace.go:171","msg":"trace[2013674397] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bc7a64f0-6bbc-41aa-8c51-92b540e2e682; range_end:; response_count:1; response_revision:5006; }","duration":"169.660805ms","start":"2026-10-27T21:09:34.792923Z","end":"2026-10-27T21:09:34.962584Z","steps":["trace[2013674397] 'agreement among raft nodes before linearized reading' (duration: 169.586054ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:09:34.9622Z","caller":"traceutil/trace.go:171","msg":"trace[710599255] linearizableReadLoop","detail":"{readStateIndex:5205; appliedIndex:5204; }","duration":"169.185958ms","start":"2026-10-27T21:09:34.792994Z","end":"2026-10-27T21:09:34.96218Z","steps":["trace[710599255] 'read index received' (duration: 168.418758ms)","trace[710599255] 'applied index is now lower than readState.Index' (duration: 765.83µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:09:38.624119Z","caller":"traceutil/trace.go:171","msg":"trace[213133147] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"110.278157ms","start":"2026-10-27T21:09:38.513817Z","end":"2026-10-27T21:09:38.624096Z","steps":["trace[213133147] 'process raft request' (duration: 100.353548ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:09:46.62619Z","caller":"traceutil/trace.go:171","msg":"trace[115168616] linearizableReadLoop","detail":"{readStateIndex:5330; appliedIndex:5329; }","duration":"105.490825ms","start":"2026-10-27T21:09:46.520675Z","end":"2026-10-27T21:09:46.626166Z","steps":["trace[115168616] 'read index received' (duration: 105.322633ms)","trace[115168616] 'applied index is now lower than readState.Index' (duration: 167.002µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:09:46.626287Z","caller":"traceutil/trace.go:171","msg":"trace[777948810] transaction","detail":"{read_only:false; response_revision:5128; number_of_response:1; }","duration":"134.138126ms","start":"2026-10-27T21:09:46.492116Z","end":"2026-10-27T21:09:46.626254Z","steps":["trace[777948810] 'process raft request' (duration: 133.874743ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:09:46.626402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.712318ms","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-10-27T21:09:46.626439Z","caller":"traceutil/trace.go:171","msg":"trace[1904013835] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5128; }","duration":"105.78979ms","start":"2026-10-27T21:09:46.520639Z","end":"2026-10-27T21:09:46.626429Z","steps":["trace[1904013835] 'agreement among raft nodes before linearized reading' (duration: 105.648608ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:10:08.182749Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2851} {"level":"info","ts":"2026-10-27T21:10:08.258367Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2851,"took":"74.295179ms","hash":847773359,"current-db-size-bytes":52125696,"current-db-size":"52 MB","current-db-size-in-use-bytes":27783168,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-10-27T21:10:08.258482Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":847773359,"revision":2851,"compact-revision":-1} {"level":"info","ts":"2026-10-27T21:10:16.222416Z","caller":"traceutil/trace.go:171","msg":"trace[223810658] linearizableReadLoop","detail":"{readStateIndex:5681; appliedIndex:5680; }","duration":"160.298146ms","start":"2026-10-27T21:10:16.062088Z","end":"2026-10-27T21:10:16.222386Z","steps":["trace[223810658] 'read index received' (duration: 159.957193ms)","trace[223810658] 'applied index is now lower than readState.Index' (duration: 335.273µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:10:16.222509Z","caller":"traceutil/trace.go:171","msg":"trace[1791679270] transaction","detail":"{read_only:false; response_revision:5469; number_of_response:1; }","duration":"219.453388ms","start":"2026-10-27T21:10:16.003007Z","end":"2026-10-27T21:10:16.222461Z","steps":["trace[1791679270] 'process raft request' (duration: 219.163926ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:10:16.222791Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.674032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keystone-credential-setup-jx26z\" ","response":"range_response_count:1 size:7557"} {"level":"info","ts":"2026-10-27T21:10:16.222844Z","caller":"traceutil/trace.go:171","msg":"trace[1599243239] range","detail":"{range_begin:/registry/pods/openstack/keystone-credential-setup-jx26z; range_end:; response_count:1; response_revision:5469; }","duration":"160.795486ms","start":"2026-10-27T21:10:16.062034Z","end":"2026-10-27T21:10:16.222829Z","steps":["trace[1599243239] 'agreement among raft nodes before linearized reading' (duration: 160.620469ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:10:23.147114Z","caller":"traceutil/trace.go:171","msg":"trace[738447388] linearizableReadLoop","detail":"{readStateIndex:5725; appliedIndex:5724; }","duration":"170.960761ms","start":"2026-10-27T21:10:22.976129Z","end":"2026-10-27T21:10:23.14709Z","steps":["trace[738447388] 'read index received' (duration: 170.728401ms)","trace[738447388] 'applied index is now lower than readState.Index' (duration: 231.4µs)"],"step_count":2} {"level":"info","ts":"2026-10-27T21:10:23.147242Z","caller":"traceutil/trace.go:171","msg":"trace[1760537513] transaction","detail":"{read_only:false; response_revision:5511; number_of_response:1; }","duration":"180.135946ms","start":"2026-10-27T21:10:22.967057Z","end":"2026-10-27T21:10:23.147193Z","steps":["trace[1760537513] 'process raft request' (duration: 179.888306ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:10:23.147356Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.20418ms","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-10-27T21:10:23.147395Z","caller":"traceutil/trace.go:171","msg":"trace[1087503578] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5511; }","duration":"171.291073ms","start":"2026-10-27T21:10:22.976093Z","end":"2026-10-27T21:10:23.147384Z","steps":["trace[1087503578] 'agreement among raft nodes before linearized reading' (duration: 171.123857ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:10:23.147536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.242396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/federations/\" range_end:\"/registry/rabbitmq.com/federations0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-10-27T21:10:23.14762Z","caller":"traceutil/trace.go:171","msg":"trace[1544677364] range","detail":"{range_begin:/registry/rabbitmq.com/federations/; range_end:/registry/rabbitmq.com/federations0; response_count:0; response_revision:5511; }","duration":"143.399862ms","start":"2026-10-27T21:10:23.004197Z","end":"2026-10-27T21:10:23.147596Z","steps":["trace[1544677364] 'agreement among raft nodes before linearized reading' (duration: 143.260336ms)"],"step_count":1} {"level":"warn","ts":"2026-10-27T21:10:23.147668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.303514ms","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-10-27T21:10:23.147829Z","caller":"traceutil/trace.go:171","msg":"trace[1738160417] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5511; }","duration":"171.514653ms","start":"2026-10-27T21:10:22.976283Z","end":"2026-10-27T21:10:23.147798Z","steps":["trace[1738160417] 'agreement among raft nodes before linearized reading' (duration: 171.035604ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:10:40.340367Z","caller":"traceutil/trace.go:171","msg":"trace[1447516598] transaction","detail":"{read_only:false; response_revision:5630; number_of_response:1; }","duration":"109.244099ms","start":"2026-10-27T21:10:40.231102Z","end":"2026-10-27T21:10:40.340346Z","steps":["trace[1447516598] 'process raft request' (duration: 109.124484ms)"],"step_count":1} {"level":"info","ts":"2026-10-27T21:15:08.194495Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5337} {"level":"info","ts":"2026-10-27T21:15:08.276317Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5337,"took":"80.737974ms","hash":1770656189,"current-db-size-bytes":52125696,"current-db-size":"52 MB","current-db-size-in-use-bytes":18804736,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-10-27T21:15:08.276487Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1770656189,"revision":5337,"compact-revision":2851}