{"level":"warn","ts":"2026-08-13T00:47:36.741366Z","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-13T00:47:36.741488Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.105: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.105:2380","--initial-cluster=instance=https://199.19.213.105:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.105:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.105: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-13T00:47:36.741571Z","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-13T00:47:36.741645Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.105:2380"]} {"level":"info","ts":"2026-08-13T00:47:36.741674Z","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-13T00:47:36.742595Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"]} {"level":"info","ts":"2026-08-13T00:47:36.742915Z","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.105:2380"],"listen-peer-urls":["https://199.19.213.105:2380"],"advertise-client-urls":["https://199.19.213.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.105: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-13T00:47:36.746624Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.236041ms"} {"level":"info","ts":"2026-08-13T00:47:36.754421Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"eb6db91b2c71522f","cluster-id":"f43476f3367c14fb"} {"level":"info","ts":"2026-08-13T00:47:36.754769Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=()"} {"level":"info","ts":"2026-08-13T00:47:36.75482Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became follower at term 0"} {"level":"info","ts":"2026-08-13T00:47:36.754983Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft eb6db91b2c71522f [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-13T00:47:36.755159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became follower at term 1"} {"level":"info","ts":"2026-08-13T00:47:36.75555Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=(16964418897735406127)"} {"level":"warn","ts":"2026-08-13T00:47:36.761664Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-13T00:47:36.763302Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-13T00:47:36.764418Z","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-13T00:47:36.766358Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"eb6db91b2c71522f","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-13T00:47:36.766818Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"eb6db91b2c71522f","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-13T00:47:36.76843Z","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-13T00:47:36.768496Z","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-13T00:47:36.768507Z","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-13T00:47:36.769548Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f switched to configuration voters=(16964418897735406127)"} {"level":"info","ts":"2026-08-13T00:47:36.769755Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f43476f3367c14fb","local-member-id":"eb6db91b2c71522f","added-peer-id":"eb6db91b2c71522f","added-peer-peer-urls":["https://199.19.213.105:2380"]} {"level":"info","ts":"2026-08-13T00:47:36.77335Z","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-13T00:47:36.773481Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.105:2380"} {"level":"info","ts":"2026-08-13T00:47:36.773552Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.105:2380"} {"level":"info","ts":"2026-08-13T00:47:36.773793Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"eb6db91b2c71522f","initial-advertise-peer-urls":["https://199.19.213.105:2380"],"listen-peer-urls":["https://199.19.213.105:2380"],"advertise-client-urls":["https://199.19.213.105:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.105:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-13T00:47:36.773846Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-13T00:47:37.556585Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f is starting a new election at term 1"} {"level":"info","ts":"2026-08-13T00:47:37.556662Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became pre-candidate at term 1"} {"level":"info","ts":"2026-08-13T00:47:37.556712Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f received MsgPreVoteResp from eb6db91b2c71522f at term 1"} {"level":"info","ts":"2026-08-13T00:47:37.556735Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became candidate at term 2"} {"level":"info","ts":"2026-08-13T00:47:37.556747Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f received MsgVoteResp from eb6db91b2c71522f at term 2"} {"level":"info","ts":"2026-08-13T00:47:37.556766Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"eb6db91b2c71522f became leader at term 2"} {"level":"info","ts":"2026-08-13T00:47:37.556789Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: eb6db91b2c71522f elected leader eb6db91b2c71522f at term 2"} {"level":"info","ts":"2026-08-13T00:47:37.557893Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"eb6db91b2c71522f","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.105:2379]}","request-path":"/0/members/eb6db91b2c71522f/attributes","cluster-id":"f43476f3367c14fb","publish-timeout":"7s"} {"level":"info","ts":"2026-08-13T00:47:37.558059Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T00:47:37.558215Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-13T00:47:37.558252Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:47:37.559324Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f43476f3367c14fb","local-member-id":"eb6db91b2c71522f","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:47:37.559486Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:47:37.559523Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-13T00:47:37.560794Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.105:2379"} {"level":"info","ts":"2026-08-13T00:47:37.561437Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-13T00:47:37.568521Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-13T00:47:37.568589Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-13T00:47:39.163732Z","caller":"traceutil/trace.go:171","msg":"trace[32450484] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"146.52074ms","start":"2026-08-13T00:47:39.017185Z","end":"2026-08-13T00:47:39.163706Z","steps":["trace[32450484] 'read index received' (duration: 59.202092ms)","trace[32450484] 'applied index is now lower than readState.Index' (duration: 87.317388ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:47:39.16391Z","caller":"traceutil/trace.go:171","msg":"trace[1224436385] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"147.442386ms","start":"2026-08-13T00:47:39.016383Z","end":"2026-08-13T00:47:39.163825Z","steps":["trace[1224436385] 'process raft request' (duration: 147.172301ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:39.164337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.060469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T00:47:39.164536Z","caller":"traceutil/trace.go:171","msg":"trace[1861204196] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:0; response_revision:3; }","duration":"147.299383ms","start":"2026-08-13T00:47:39.01714Z","end":"2026-08-13T00:47:39.164439Z","steps":["trace[1861204196] 'agreement among raft nodes before linearized reading' (duration: 146.741614ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:39.174396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.557014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-08-13T00:47:39.17483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.73084ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T00:47:39.174896Z","caller":"traceutil/trace.go:171","msg":"trace[28584789] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"103.842332ms","start":"2026-08-13T00:47:39.071029Z","end":"2026-08-13T00:47:39.174871Z","steps":["trace[28584789] 'agreement among raft nodes before linearized reading' (duration: 103.767331ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:39.174922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.846063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-08-13T00:47:39.174982Z","caller":"traceutil/trace.go:171","msg":"trace[33739106] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"103.964785ms","start":"2026-08-13T00:47:39.071002Z","end":"2026-08-13T00:47:39.174967Z","steps":["trace[33739106] 'agreement among raft nodes before linearized reading' (duration: 103.879613ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:47:39.179597Z","caller":"traceutil/trace.go:171","msg":"trace[995650933] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"113.719427ms","start":"2026-08-13T00:47:39.060749Z","end":"2026-08-13T00:47:39.174469Z","steps":["trace[995650933] 'agreement among raft nodes before linearized reading' (duration: 113.515683ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:47:40.424428Z","caller":"traceutil/trace.go:171","msg":"trace[1297951244] transaction","detail":"{read_only:false; response_revision:116; number_of_response:1; }","duration":"130.867121ms","start":"2026-08-13T00:47:40.293526Z","end":"2026-08-13T00:47:40.424394Z","steps":["trace[1297951244] 'process raft request' (duration: 122.310587ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:47:44.609741Z","caller":"traceutil/trace.go:171","msg":"trace[184196298] transaction","detail":"{read_only:false; response_revision:323; number_of_response:1; }","duration":"109.975038ms","start":"2026-08-13T00:47:44.499737Z","end":"2026-08-13T00:47:44.609712Z","steps":["trace[184196298] 'process raft request' (duration: 109.818205ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:47:45.082804Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.43402ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:47:45.08295Z","caller":"traceutil/trace.go:171","msg":"trace[1917841105] transaction","detail":"{read_only:false; response_revision:324; number_of_response:1; }","duration":"434.2639ms","start":"2026-08-13T00:47:44.648651Z","end":"2026-08-13T00:47:45.082914Z","steps":["trace[1917841105] 'process raft request' (duration: 164.216078ms)","trace[1917841105] 'compare' (duration: 269.258387ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:47:45.082997Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:47:44.648627Z","time spent":"434.353431ms","remote":"127.0.0.1:59984","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":192,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-13T00:47:45.328683Z","caller":"traceutil/trace.go:171","msg":"trace[1272044309] transaction","detail":"{read_only:false; response_revision:326; number_of_response:1; }","duration":"160.496585ms","start":"2026-08-13T00:47:45.168157Z","end":"2026-08-13T00:47:45.328653Z","steps":["trace[1272044309] 'process raft request' (duration: 154.74405ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:21.047153Z","caller":"traceutil/trace.go:171","msg":"trace[84218394] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"148.942476ms","start":"2026-08-13T00:48:20.898176Z","end":"2026-08-13T00:48:21.047119Z","steps":["trace[84218394] 'process raft request' (duration: 148.238318ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:21.78278Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.903112ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:48:21.783041Z","caller":"traceutil/trace.go:171","msg":"trace[1245021934] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"353.328013ms","start":"2026-08-13T00:48:21.429686Z","end":"2026-08-13T00:48:21.783014Z","steps":["trace[1245021934] 'process raft request' (duration: 45.480446ms)","trace[1245021934] 'compare' (duration: 306.366248ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:48:21.783187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:21.42965Z","time spent":"353.464347ms","remote":"127.0.0.1:59812","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:48:21.783365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.086542ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:48:21.783468Z","caller":"traceutil/trace.go:171","msg":"trace[1758223735] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:634; }","duration":"311.228085ms","start":"2026-08-13T00:48:21.472215Z","end":"2026-08-13T00:48:21.783443Z","steps":["trace[1758223735] 'agreement among raft nodes before linearized reading' (duration: 310.936078ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:21.782961Z","caller":"traceutil/trace.go:171","msg":"trace[1824605060] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"310.695961ms","start":"2026-08-13T00:48:21.472245Z","end":"2026-08-13T00:48:21.782941Z","steps":["trace[1824605060] 'read index received' (duration: 2.873565ms)","trace[1824605060] 'applied index is now lower than readState.Index' (duration: 307.820696ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:48:21.783555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:21.472202Z","time spent":"311.341999ms","remote":"127.0.0.1:59794","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-13T00:48:22.031157Z","caller":"traceutil/trace.go:171","msg":"trace[1665712682] linearizableReadLoop","detail":"{readStateIndex:658; appliedIndex:657; }","duration":"247.487198ms","start":"2026-08-13T00:48:21.783623Z","end":"2026-08-13T00:48:22.03111Z","steps":["trace[1665712682] 'read index received' (duration: 239.999381ms)","trace[1665712682] 'applied index is now lower than readState.Index' (duration: 7.486857ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:48:22.031359Z","caller":"traceutil/trace.go:171","msg":"trace[1471836097] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"491.401208ms","start":"2026-08-13T00:48:21.539913Z","end":"2026-08-13T00:48:22.031314Z","steps":["trace[1471836097] 'process raft request' (duration: 487.158756ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:22.031504Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.027086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-13T00:48:22.031559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.972068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"warn","ts":"2026-08-13T00:48:22.031576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:21.539889Z","time spent":"491.572403ms","remote":"127.0.0.1:59868","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:48:22.031603Z","caller":"traceutil/trace.go:171","msg":"trace[801959618] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:635; }","duration":"246.027579ms","start":"2026-08-13T00:48:21.785564Z","end":"2026-08-13T00:48:22.031592Z","steps":["trace[801959618] 'agreement among raft nodes before linearized reading' (duration: 245.779173ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:22.031598Z","caller":"traceutil/trace.go:171","msg":"trace[596980788] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:635; }","duration":"359.18472ms","start":"2026-08-13T00:48:21.672395Z","end":"2026-08-13T00:48:22.03158Z","steps":["trace[596980788] 'agreement among raft nodes before linearized reading' (duration: 358.772229ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:22.031673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:21.672378Z","time spent":"359.283763ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-08-13T00:48:22.031555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.91868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-13T00:48:22.031681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.674125ms","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-08-13T00:48:22.031747Z","caller":"traceutil/trace.go:171","msg":"trace[776810676] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:635; }","duration":"365.131766ms","start":"2026-08-13T00:48:21.666593Z","end":"2026-08-13T00:48:22.031724Z","steps":["trace[776810676] 'agreement among raft nodes before linearized reading' (duration: 364.602912ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:22.031773Z","caller":"traceutil/trace.go:171","msg":"trace[1204757226] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:635; }","duration":"463.843439ms","start":"2026-08-13T00:48:21.567902Z","end":"2026-08-13T00:48:22.031745Z","steps":["trace[1204757226] 'agreement among raft nodes before linearized reading' (duration: 463.385697ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:22.031792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:21.666483Z","time spent":"365.30098ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T00:48:22.031821Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:21.567878Z","time spent":"463.935732ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-13T00:48:22.03196Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"451.17882ms","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-13T00:48:22.032639Z","caller":"traceutil/trace.go:171","msg":"trace[2099136631] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:635; }","duration":"451.867747ms","start":"2026-08-13T00:48:21.580746Z","end":"2026-08-13T00:48:22.032614Z","steps":["trace[2099136631] 'agreement among raft nodes before linearized reading' (duration: 450.477671ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:22.032705Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:48:21.580731Z","time spent":"451.95099ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-13T00:48:36.364353Z","caller":"traceutil/trace.go:171","msg":"trace[1072889924] transaction","detail":"{read_only:false; response_revision:795; number_of_response:1; }","duration":"196.304186ms","start":"2026-08-13T00:48:36.167992Z","end":"2026-08-13T00:48:36.364296Z","steps":["trace[1072889924] 'process raft request' (duration: 196.032969ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:48:36.434116Z","caller":"traceutil/trace.go:171","msg":"trace[309292214] linearizableReadLoop","detail":"{readStateIndex:822; appliedIndex:820; }","duration":"263.899303ms","start":"2026-08-13T00:48:36.170175Z","end":"2026-08-13T00:48:36.434074Z","steps":["trace[309292214] 'read index received' (duration: 193.772066ms)","trace[309292214] 'applied index is now lower than readState.Index' (duration: 70.125867ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:48:36.434287Z","caller":"traceutil/trace.go:171","msg":"trace[541360435] transaction","detail":"{read_only:false; response_revision:796; number_of_response:1; }","duration":"266.26908ms","start":"2026-08-13T00:48:36.168005Z","end":"2026-08-13T00:48:36.434274Z","steps":["trace[541360435] 'process raft request' (duration: 258.847391ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:36.434636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.422308ms","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-13T00:48:36.434764Z","caller":"traceutil/trace.go:171","msg":"trace[116842092] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:796; }","duration":"264.585943ms","start":"2026-08-13T00:48:36.170155Z","end":"2026-08-13T00:48:36.434741Z","steps":["trace[116842092] 'agreement among raft nodes before linearized reading' (duration: 264.269904ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:48:36.436458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.076989ms","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-08-13T00:48:36.436566Z","caller":"traceutil/trace.go:171","msg":"trace[2132991842] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:796; }","duration":"264.229773ms","start":"2026-08-13T00:48:36.172314Z","end":"2026-08-13T00:48:36.436544Z","steps":["trace[2132991842] 'agreement among raft nodes before linearized reading' (duration: 263.271206ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:49:57.405066Z","caller":"traceutil/trace.go:171","msg":"trace[117288037] linearizableReadLoop","detail":"{readStateIndex:1953; appliedIndex:1952; }","duration":"311.916282ms","start":"2026-08-13T00:49:57.093121Z","end":"2026-08-13T00:49:57.405037Z","steps":["trace[117288037] 'read index received' (duration: 311.769807ms)","trace[117288037] 'applied index is now lower than readState.Index' (duration: 145.175µs)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:49:57.405189Z","caller":"traceutil/trace.go:171","msg":"trace[398291224] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"372.622654ms","start":"2026-08-13T00:49:57.032528Z","end":"2026-08-13T00:49:57.405151Z","steps":["trace[398291224] 'process raft request' (duration: 372.308994ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:57.40544Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:57.032493Z","time spent":"372.7998ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:49:57.405615Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.42799ms","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-13T00:49:57.405701Z","caller":"traceutil/trace.go:171","msg":"trace[197220438] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1880; }","duration":"312.653788ms","start":"2026-08-13T00:49:57.093032Z","end":"2026-08-13T00:49:57.405686Z","steps":["trace[197220438] 'agreement among raft nodes before linearized reading' (duration: 312.160242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:57.405747Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:57.092995Z","time spent":"312.740491ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-08-13T00:49:57.415615Z","caller":"traceutil/trace.go:171","msg":"trace[651701200] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"237.322019ms","start":"2026-08-13T00:49:57.178269Z","end":"2026-08-13T00:49:57.415591Z","steps":["trace[651701200] 'process raft request' (duration: 237.05047ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:57.415776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.613739ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:49:57.415856Z","caller":"traceutil/trace.go:171","msg":"trace[287760573] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1881; }","duration":"252.752633ms","start":"2026-08-13T00:49:57.163077Z","end":"2026-08-13T00:49:57.41583Z","steps":["trace[287760573] 'agreement among raft nodes before linearized reading' (duration: 252.640549ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:57.415638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.599815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:49:57.416981Z","caller":"traceutil/trace.go:171","msg":"trace[209594325] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoypatchpolicies/; range_end:/registry/gateway.envoyproxy.io/envoypatchpolicies0; response_count:0; response_revision:1881; }","duration":"320.939689ms","start":"2026-08-13T00:49:57.095984Z","end":"2026-08-13T00:49:57.416923Z","steps":["trace[209594325] 'agreement among raft nodes before linearized reading' (duration: 319.542462ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:49:57.417167Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:49:57.095939Z","time spent":"321.139636ms","remote":"127.0.0.1:33740","response type":"/etcdserverpb.KV/Range","request count":0,"request size":108,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.envoyproxy.io/envoypatchpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoypatchpolicies0\" count_only:true "} {"level":"info","ts":"2026-08-13T00:50:10.189127Z","caller":"traceutil/trace.go:171","msg":"trace[779444991] transaction","detail":"{read_only:false; response_revision:2000; number_of_response:1; }","duration":"110.411505ms","start":"2026-08-13T00:50:10.07869Z","end":"2026-08-13T00:50:10.189101Z","steps":["trace[779444991] 'process raft request' (duration: 110.24145ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:31.14484Z","caller":"traceutil/trace.go:171","msg":"trace[675242991] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"104.433523ms","start":"2026-08-13T00:50:31.040362Z","end":"2026-08-13T00:50:31.144795Z","steps":["trace[675242991] 'process raft request' (duration: 104.213586ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:31.306647Z","caller":"traceutil/trace.go:171","msg":"trace[2142656074] transaction","detail":"{read_only:false; response_revision:2122; number_of_response:1; }","duration":"157.289057ms","start":"2026-08-13T00:50:31.149332Z","end":"2026-08-13T00:50:31.306621Z","steps":["trace[2142656074] 'process raft request' (duration: 117.042393ms)","trace[2142656074] 'compare' (duration: 40.095759ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:50:31.30703Z","caller":"traceutil/trace.go:171","msg":"trace[391578005] transaction","detail":"{read_only:false; response_revision:2123; number_of_response:1; }","duration":"156.2214ms","start":"2026-08-13T00:50:31.150792Z","end":"2026-08-13T00:50:31.307014Z","steps":["trace[391578005] 'process raft request' (duration: 155.796415ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:31.307001Z","caller":"traceutil/trace.go:171","msg":"trace[394762780] linearizableReadLoop","detail":"{readStateIndex:2203; appliedIndex:2201; }","duration":"147.082402ms","start":"2026-08-13T00:50:31.159896Z","end":"2026-08-13T00:50:31.306978Z","steps":["trace[394762780] 'read index received' (duration: 106.481785ms)","trace[394762780] 'applied index is now lower than readState.Index' (duration: 40.599337ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:50:31.307291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.290889ms","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-13T00:50:31.307344Z","caller":"traceutil/trace.go:171","msg":"trace[1831902322] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2123; }","duration":"147.460375ms","start":"2026-08-13T00:50:31.159868Z","end":"2026-08-13T00:50:31.307328Z","steps":["trace[1831902322] 'agreement among raft nodes before linearized reading' (duration: 147.184255ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:31.742006Z","caller":"traceutil/trace.go:171","msg":"trace[649118298] linearizableReadLoop","detail":"{readStateIndex:2207; appliedIndex:2206; }","duration":"139.59993ms","start":"2026-08-13T00:50:31.602364Z","end":"2026-08-13T00:50:31.741964Z","steps":["trace[649118298] 'read index received' (duration: 74.023782ms)","trace[649118298] 'applied index is now lower than readState.Index' (duration: 65.574568ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:50:31.742106Z","caller":"traceutil/trace.go:171","msg":"trace[1074337913] transaction","detail":"{read_only:false; response_revision:2126; number_of_response:1; }","duration":"171.054708ms","start":"2026-08-13T00:50:31.571017Z","end":"2026-08-13T00:50:31.742072Z","steps":["trace[1074337913] 'process raft request' (duration: 105.455819ms)","trace[1074337913] 'compare' (duration: 65.292248ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:50:31.74252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.122999ms","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-13T00:50:31.742679Z","caller":"traceutil/trace.go:171","msg":"trace[518643502] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2126; }","duration":"140.324795ms","start":"2026-08-13T00:50:31.602334Z","end":"2026-08-13T00:50:31.742659Z","steps":["trace[518643502] 'agreement among raft nodes before linearized reading' (duration: 139.795297ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:50:32.298611Z","caller":"traceutil/trace.go:171","msg":"trace[938993713] transaction","detail":"{read_only:false; response_revision:2129; number_of_response:1; }","duration":"107.811474ms","start":"2026-08-13T00:50:32.190763Z","end":"2026-08-13T00:50:32.298574Z","steps":["trace[938993713] 'process raft request' (duration: 57.59517ms)","trace[938993713] 'compare' (duration: 50.015557ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:51:35.641335Z","caller":"traceutil/trace.go:171","msg":"trace[366737104] transaction","detail":"{read_only:false; response_revision:2571; number_of_response:1; }","duration":"166.284928ms","start":"2026-08-13T00:51:35.475007Z","end":"2026-08-13T00:51:35.641292Z","steps":["trace[366737104] 'process raft request' (duration: 166.052014ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:52:02.354775Z","caller":"traceutil/trace.go:171","msg":"trace[483115876] transaction","detail":"{read_only:false; response_revision:2723; number_of_response:1; }","duration":"108.088815ms","start":"2026-08-13T00:52:02.246657Z","end":"2026-08-13T00:52:02.354746Z","steps":["trace[483115876] 'process raft request' (duration: 107.913491ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:52:06.811908Z","caller":"traceutil/trace.go:171","msg":"trace[759036830] transaction","detail":"{read_only:false; response_revision:2745; number_of_response:1; }","duration":"105.527182ms","start":"2026-08-13T00:52:06.706357Z","end":"2026-08-13T00:52:06.811884Z","steps":["trace[759036830] 'process raft request' (duration: 105.320027ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:08.401533Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.657813ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:52:08.401673Z","caller":"traceutil/trace.go:171","msg":"trace[1909782260] linearizableReadLoop","detail":"{readStateIndex:2851; appliedIndex:2850; }","duration":"555.248165ms","start":"2026-08-13T00:52:07.846401Z","end":"2026-08-13T00:52:08.401649Z","steps":["trace[1909782260] 'read index received' (duration: 323.341186ms)","trace[1909782260] 'applied index is now lower than readState.Index' (duration: 231.905519ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:52:08.402621Z","caller":"traceutil/trace.go:171","msg":"trace[1542730551] transaction","detail":"{read_only:false; response_revision:2748; number_of_response:1; }","duration":"556.553634ms","start":"2026-08-13T00:52:07.84605Z","end":"2026-08-13T00:52:08.402603Z","steps":["trace[1542730551] 'process raft request' (duration: 323.693614ms)","trace[1542730551] 'compare' (duration: 231.03329ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:52:08.402714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:07.846019Z","time spent":"556.653495ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:52:08.402802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"556.361219ms","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-13T00:52:08.40289Z","caller":"traceutil/trace.go:171","msg":"trace[2031879390] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2748; }","duration":"556.506443ms","start":"2026-08-13T00:52:07.846363Z","end":"2026-08-13T00:52:08.40287Z","steps":["trace[2031879390] 'agreement among raft nodes before linearized reading' (duration: 556.247377ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:08.402939Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:07.846347Z","time spent":"556.577054ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-13T00:52:08.424529Z","caller":"traceutil/trace.go:171","msg":"trace[7800669] transaction","detail":"{read_only:false; response_revision:2749; number_of_response:1; }","duration":"524.120828ms","start":"2026-08-13T00:52:07.900386Z","end":"2026-08-13T00:52:08.424506Z","steps":["trace[7800669] 'process raft request' (duration: 523.73836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:08.424672Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:07.900361Z","time spent":"524.2257ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:52:08.425045Z","caller":"traceutil/trace.go:171","msg":"trace[363875906] transaction","detail":"{read_only:false; response_revision:2750; number_of_response:1; }","duration":"414.460716ms","start":"2026-08-13T00:52:08.010572Z","end":"2026-08-13T00:52:08.425032Z","steps":["trace[363875906] 'process raft request' (duration: 413.781881ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:08.425118Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:08.010519Z","time spent":"414.565878ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:52:08.425488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.277633ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/grpcroutes/\" range_end:\"/registry/gateway.networking.k8s.io/grpcroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:52:08.425536Z","caller":"traceutil/trace.go:171","msg":"trace[2131581825] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/grpcroutes/; range_end:/registry/gateway.networking.k8s.io/grpcroutes0; response_count:0; response_revision:2750; }","duration":"135.353924ms","start":"2026-08-13T00:52:08.290168Z","end":"2026-08-13T00:52:08.425522Z","steps":["trace[2131581825] 'agreement among raft nodes before linearized reading' (duration: 135.284932ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:08.425724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.666773ms","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-08-13T00:52:08.42576Z","caller":"traceutil/trace.go:171","msg":"trace[561286038] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2750; }","duration":"157.728264ms","start":"2026-08-13T00:52:08.268024Z","end":"2026-08-13T00:52:08.425752Z","steps":["trace[561286038] 'agreement among raft nodes before linearized reading' (duration: 157.639682ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:08.909108Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.275541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:52:08.909194Z","caller":"traceutil/trace.go:171","msg":"trace[211449387] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:2755; }","duration":"350.416924ms","start":"2026-08-13T00:52:08.558757Z","end":"2026-08-13T00:52:08.909174Z","steps":["trace[211449387] 'count revisions from in-memory index tree' (duration: 350.210359ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:08.90924Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:52:08.558716Z","time spent":"350.513846ms","remote":"127.0.0.1:60408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true "} {"level":"warn","ts":"2026-08-13T00:52:08.909579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.286175ms","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-13T00:52:08.909633Z","caller":"traceutil/trace.go:171","msg":"trace[1012350231] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2755; }","duration":"157.379807ms","start":"2026-08-13T00:52:08.75224Z","end":"2026-08-13T00:52:08.90962Z","steps":["trace[1012350231] 'range keys from in-memory index tree' (duration: 157.08252ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:52:08.909418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.461119ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:52:08.909917Z","caller":"traceutil/trace.go:171","msg":"trace[636494066] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:2755; }","duration":"111.059553ms","start":"2026-08-13T00:52:08.798832Z","end":"2026-08-13T00:52:08.909891Z","steps":["trace[636494066] 'count revisions from in-memory index tree' (duration: 110.317556ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:52:08.989654Z","caller":"traceutil/trace.go:171","msg":"trace[1347458292] transaction","detail":"{read_only:false; response_revision:2756; number_of_response:1; }","duration":"170.826541ms","start":"2026-08-13T00:52:08.818792Z","end":"2026-08-13T00:52:08.989619Z","steps":["trace[1347458292] 'process raft request' (duration: 170.572175ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:05.752967Z","caller":"traceutil/trace.go:171","msg":"trace[1920975019] transaction","detail":"{read_only:false; response_revision:4628; number_of_response:1; }","duration":"198.921579ms","start":"2026-08-13T00:56:05.554022Z","end":"2026-08-13T00:56:05.752943Z","steps":["trace[1920975019] 'process raft request' (duration: 198.509346ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:06.081961Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.153028ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-13T00:56:06.082137Z","caller":"traceutil/trace.go:171","msg":"trace[1127763011] linearizableReadLoop","detail":"{readStateIndex:4805; appliedIndex:4803; }","duration":"300.598824ms","start":"2026-08-13T00:56:05.781521Z","end":"2026-08-13T00:56:06.08212Z","steps":["trace[1127763011] 'read index received' (duration: 58.212038ms)","trace[1127763011] 'applied index is now lower than readState.Index' (duration: 242.385506ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:56:06.082301Z","caller":"traceutil/trace.go:171","msg":"trace[2047497858] transaction","detail":"{read_only:false; response_revision:4630; number_of_response:1; }","duration":"322.345258ms","start":"2026-08-13T00:56:05.759912Z","end":"2026-08-13T00:56:06.082257Z","steps":["trace[2047497858] 'process raft request' (duration: 322.152952ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:06.082426Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.900473ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-13T00:56:06.082466Z","caller":"traceutil/trace.go:171","msg":"trace[1368417329] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4630; }","duration":"300.973385ms","start":"2026-08-13T00:56:05.781482Z","end":"2026-08-13T00:56:06.082455Z","steps":["trace[1368417329] 'agreement among raft nodes before linearized reading' (duration: 300.846181ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:06.082504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:05.781463Z","time spent":"301.032588ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-08-13T00:56:06.082553Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:05.759904Z","time spent":"322.498333ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:56:06.083021Z","caller":"traceutil/trace.go:171","msg":"trace[1066638549] transaction","detail":"{read_only:false; response_revision:4629; number_of_response:1; }","duration":"325.2232ms","start":"2026-08-13T00:56:05.757651Z","end":"2026-08-13T00:56:06.082874Z","steps":["trace[1066638549] 'process raft request' (duration: 82.066459ms)","trace[1066638549] 'compare' (duration: 241.948432ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:56:06.083102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:05.757628Z","time spent":"325.435936ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:56:06.368543Z","caller":"traceutil/trace.go:171","msg":"trace[846094360] linearizableReadLoop","detail":"{readStateIndex:4806; appliedIndex:4805; }","duration":"286.344173ms","start":"2026-08-13T00:56:06.082171Z","end":"2026-08-13T00:56:06.368515Z","steps":["trace[846094360] 'read index received' (duration: 284.150123ms)","trace[846094360] 'applied index is now lower than readState.Index' (duration: 2.19297ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:56:06.368703Z","caller":"traceutil/trace.go:171","msg":"trace[1508159878] transaction","detail":"{read_only:false; response_revision:4631; number_of_response:1; }","duration":"528.707168ms","start":"2026-08-13T00:56:05.839958Z","end":"2026-08-13T00:56:06.368665Z","steps":["trace[1508159878] 'process raft request' (duration: 526.351873ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:06.368842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"491.454209ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-66544e73-60d8-4682-9f19-7696071090b6\" ","response":"range_response_count:1 size:4067"} {"level":"info","ts":"2026-08-13T00:56:06.368919Z","caller":"traceutil/trace.go:171","msg":"trace[438395097] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-66544e73-60d8-4682-9f19-7696071090b6; range_end:; response_count:1; response_revision:4631; }","duration":"491.571553ms","start":"2026-08-13T00:56:05.877332Z","end":"2026-08-13T00:56:06.368903Z","steps":["trace[438395097] 'agreement among raft nodes before linearized reading' (duration: 491.355096ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:06.368964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:05.877317Z","time spent":"491.635945ms","remote":"127.0.0.1:59966","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4091,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-66544e73-60d8-4682-9f19-7696071090b6\" "} {"level":"warn","ts":"2026-08-13T00:56:06.368847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:05.839927Z","time spent":"528.827512ms","remote":"127.0.0.1:60064","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-13T00:56:06.386073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.669644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-97275d4d-eb99-4007-94fc-a917b6915cf5\" ","response":"range_response_count:1 size:4050"} {"level":"info","ts":"2026-08-13T00:56:06.386136Z","caller":"traceutil/trace.go:171","msg":"trace[238522252] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-97275d4d-eb99-4007-94fc-a917b6915cf5; range_end:; response_count:1; response_revision:4633; }","duration":"183.734436ms","start":"2026-08-13T00:56:06.202386Z","end":"2026-08-13T00:56:06.386121Z","steps":["trace[238522252] 'agreement among raft nodes before linearized reading' (duration: 183.586142ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:06.386393Z","caller":"traceutil/trace.go:171","msg":"trace[389184712] transaction","detail":"{read_only:false; response_revision:4632; number_of_response:1; }","duration":"299.842084ms","start":"2026-08-13T00:56:06.08653Z","end":"2026-08-13T00:56:06.386372Z","steps":["trace[389184712] 'process raft request' (duration: 299.315007ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:06.386569Z","caller":"traceutil/trace.go:171","msg":"trace[229396380] transaction","detail":"{read_only:false; response_revision:4633; number_of_response:1; }","duration":"208.245359ms","start":"2026-08-13T00:56:06.178308Z","end":"2026-08-13T00:56:06.386553Z","steps":["trace[229396380] 'process raft request' (duration: 207.617119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:06.386916Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"173.440058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-08-13T00:56:06.386974Z","caller":"traceutil/trace.go:171","msg":"trace[92965609] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4633; }","duration":"173.51835ms","start":"2026-08-13T00:56:06.213442Z","end":"2026-08-13T00:56:06.38696Z","steps":["trace[92965609] 'agreement among raft nodes before linearized reading' (duration: 173.305643ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:06.387115Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.206392ms","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-13T00:56:06.387164Z","caller":"traceutil/trace.go:171","msg":"trace[771230709] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4633; }","duration":"179.324995ms","start":"2026-08-13T00:56:06.207827Z","end":"2026-08-13T00:56:06.387152Z","steps":["trace[771230709] 'agreement among raft nodes before linearized reading' (duration: 179.197701ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:14.397547Z","caller":"traceutil/trace.go:171","msg":"trace[1834188318] transaction","detail":"{read_only:false; response_revision:4802; number_of_response:1; }","duration":"267.523392ms","start":"2026-08-13T00:56:14.129994Z","end":"2026-08-13T00:56:14.397518Z","steps":["trace[1834188318] 'process raft request' (duration: 267.330246ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:14.403944Z","caller":"traceutil/trace.go:171","msg":"trace[336224320] linearizableReadLoop","detail":"{readStateIndex:4985; appliedIndex:4983; }","duration":"242.603153ms","start":"2026-08-13T00:56:14.16132Z","end":"2026-08-13T00:56:14.403924Z","steps":["trace[336224320] 'read index received' (duration: 236.124035ms)","trace[336224320] 'applied index is now lower than readState.Index' (duration: 6.470488ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:56:14.404055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.724497ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-13T00:56:14.404082Z","caller":"traceutil/trace.go:171","msg":"trace[2034788086] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4803; }","duration":"242.770609ms","start":"2026-08-13T00:56:14.161304Z","end":"2026-08-13T00:56:14.404075Z","steps":["trace[2034788086] 'agreement among raft nodes before linearized reading' (duration: 242.696677ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:14.404281Z","caller":"traceutil/trace.go:171","msg":"trace[935330779] transaction","detail":"{read_only:false; response_revision:4803; number_of_response:1; }","duration":"272.595474ms","start":"2026-08-13T00:56:14.131666Z","end":"2026-08-13T00:56:14.404262Z","steps":["trace[935330779] 'process raft request' (duration: 272.15094ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:14.757577Z","caller":"traceutil/trace.go:171","msg":"trace[325568043] transaction","detail":"{read_only:false; response_revision:4808; number_of_response:1; }","duration":"187.800157ms","start":"2026-08-13T00:56:14.569758Z","end":"2026-08-13T00:56:14.757558Z","steps":["trace[325568043] 'process raft request' (duration: 187.683843ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:14.75794Z","caller":"traceutil/trace.go:171","msg":"trace[743260377] linearizableReadLoop","detail":"{readStateIndex:4990; appliedIndex:4990; }","duration":"165.244845ms","start":"2026-08-13T00:56:14.59266Z","end":"2026-08-13T00:56:14.757905Z","steps":["trace[743260377] 'read index received' (duration: 165.168722ms)","trace[743260377] 'applied index is now lower than readState.Index' (duration: 73.103µs)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:56:14.758097Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.41698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-56ac8ad7-a3ee-4f74-a23d-ffac1ef9dfc3\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-08-13T00:56:14.758155Z","caller":"traceutil/trace.go:171","msg":"trace[353571597] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-56ac8ad7-a3ee-4f74-a23d-ffac1ef9dfc3; range_end:; response_count:1; response_revision:4808; }","duration":"165.509673ms","start":"2026-08-13T00:56:14.592629Z","end":"2026-08-13T00:56:14.758138Z","steps":["trace[353571597] 'agreement among raft nodes before linearized reading' (duration: 165.346958ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:14.828033Z","caller":"traceutil/trace.go:171","msg":"trace[142506794] transaction","detail":"{read_only:false; response_revision:4809; number_of_response:1; }","duration":"228.599205ms","start":"2026-08-13T00:56:14.599399Z","end":"2026-08-13T00:56:14.827998Z","steps":["trace[142506794] 'process raft request' (duration: 195.289268ms)","trace[142506794] 'compare' (duration: 33.130231ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:56:28.433315Z","caller":"traceutil/trace.go:171","msg":"trace[1318328549] linearizableReadLoop","detail":"{readStateIndex:5171; appliedIndex:5170; }","duration":"323.336878ms","start":"2026-08-13T00:56:28.109948Z","end":"2026-08-13T00:56:28.433285Z","steps":["trace[1318328549] 'read index received' (duration: 257.58542ms)","trace[1318328549] 'applied index is now lower than readState.Index' (duration: 65.750288ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:56:28.433839Z","caller":"traceutil/trace.go:171","msg":"trace[919364562] transaction","detail":"{read_only:false; response_revision:4983; number_of_response:1; }","duration":"332.654839ms","start":"2026-08-13T00:56:28.101146Z","end":"2026-08-13T00:56:28.433801Z","steps":["trace[919364562] 'process raft request' (duration: 266.421955ms)","trace[919364562] 'compare' (duration: 65.403067ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:56:28.433969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"323.993369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-08-13T00:56:28.434755Z","caller":"traceutil/trace.go:171","msg":"trace[642149328] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4983; }","duration":"324.714162ms","start":"2026-08-13T00:56:28.10993Z","end":"2026-08-13T00:56:28.434644Z","steps":["trace[642149328] 'agreement among raft nodes before linearized reading' (duration: 323.872285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:28.434833Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:28.109919Z","time spent":"324.897819ms","remote":"127.0.0.1:60288","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9661,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"warn","ts":"2026-08-13T00:56:28.435113Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-13T00:56:28.101133Z","time spent":"333.786315ms","remote":"127.0.0.1:59852","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":725,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-13T00:56:28.648735Z","caller":"traceutil/trace.go:171","msg":"trace[391828938] transaction","detail":"{read_only:false; response_revision:4988; number_of_response:1; }","duration":"112.062711ms","start":"2026-08-13T00:56:28.536632Z","end":"2026-08-13T00:56:28.648694Z","steps":["trace[391828938] 'process raft request' (duration: 111.993529ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:28.648991Z","caller":"traceutil/trace.go:171","msg":"trace[24737206] transaction","detail":"{read_only:false; response_revision:4987; number_of_response:1; }","duration":"136.25351ms","start":"2026-08-13T00:56:28.512724Z","end":"2026-08-13T00:56:28.648977Z","steps":["trace[24737206] 'process raft request' (duration: 45.476645ms)","trace[24737206] 'compare' (duration: 90.229337ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:56:28.649166Z","caller":"traceutil/trace.go:171","msg":"trace[448154646] linearizableReadLoop","detail":"{readStateIndex:5175; appliedIndex:5174; }","duration":"116.733811ms","start":"2026-08-13T00:56:28.532399Z","end":"2026-08-13T00:56:28.649133Z","steps":["trace[448154646] 'read index received' (duration: 25.77292ms)","trace[448154646] 'applied index is now lower than readState.Index' (duration: 90.958981ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:56:28.6495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.412151ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-08-13T00:56:28.64962Z","caller":"traceutil/trace.go:171","msg":"trace[744232833] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4988; }","duration":"121.552706ms","start":"2026-08-13T00:56:28.528044Z","end":"2026-08-13T00:56:28.649597Z","steps":["trace[744232833] 'agreement among raft nodes before linearized reading' (duration: 121.177604ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:28.851054Z","caller":"traceutil/trace.go:171","msg":"trace[875545989] transaction","detail":"{read_only:false; response_revision:4989; number_of_response:1; }","duration":"197.344839ms","start":"2026-08-13T00:56:28.653679Z","end":"2026-08-13T00:56:28.851024Z","steps":["trace[875545989] 'process raft request' (duration: 98.161033ms)","trace[875545989] 'compare' (duration: 98.735211ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:56:28.851172Z","caller":"traceutil/trace.go:171","msg":"trace[1315148879] linearizableReadLoop","detail":"{readStateIndex:5178; appliedIndex:5176; }","duration":"124.373787ms","start":"2026-08-13T00:56:28.726775Z","end":"2026-08-13T00:56:28.851149Z","steps":["trace[1315148879] 'read index received' (duration: 25.046607ms)","trace[1315148879] 'applied index is now lower than readState.Index' (duration: 99.32592ms)"],"step_count":2} {"level":"warn","ts":"2026-08-13T00:56:28.851409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.617165ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10395"} {"level":"info","ts":"2026-08-13T00:56:28.851449Z","caller":"traceutil/trace.go:171","msg":"trace[1637265535] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4990; }","duration":"124.697408ms","start":"2026-08-13T00:56:28.72674Z","end":"2026-08-13T00:56:28.851438Z","steps":["trace[1637265535] 'agreement among raft nodes before linearized reading' (duration: 124.544693ms)"],"step_count":1} {"level":"warn","ts":"2026-08-13T00:56:28.851559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.328639ms","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-13T00:56:28.851766Z","caller":"traceutil/trace.go:171","msg":"trace[1229638271] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4990; }","duration":"103.605908ms","start":"2026-08-13T00:56:28.748143Z","end":"2026-08-13T00:56:28.851749Z","steps":["trace[1229638271] 'agreement among raft nodes before linearized reading' (duration: 103.238126ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:28.851304Z","caller":"traceutil/trace.go:171","msg":"trace[1358387122] transaction","detail":"{read_only:false; response_revision:4990; number_of_response:1; }","duration":"197.482473ms","start":"2026-08-13T00:56:28.653802Z","end":"2026-08-13T00:56:28.851284Z","steps":["trace[1358387122] 'process raft request' (duration: 197.114521ms)"],"step_count":1} {"level":"info","ts":"2026-08-13T00:56:29.086714Z","caller":"traceutil/trace.go:171","msg":"trace[1418348166] transaction","detail":"{read_only:false; response_revision:4991; number_of_response:1; }","duration":"224.703571ms","start":"2026-08-13T00:56:28.861987Z","end":"2026-08-13T00:56:29.086691Z","steps":["trace[1418348166] 'process raft request' (duration: 187.167971ms)","trace[1418348166] 'compare' (duration: 37.378655ms)"],"step_count":2} {"level":"info","ts":"2026-08-13T00:57:37.758967Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2905} {"level":"info","ts":"2026-08-13T00:57:38.270066Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2905,"took":"509.157135ms","hash":1134969097,"current-db-size-bytes":53440512,"current-db-size":"53 MB","current-db-size-in-use-bytes":29245440,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-08-13T00:57:38.270151Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1134969097,"revision":2905,"compact-revision":-1} {"level":"info","ts":"2026-08-13T01:02:37.764652Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5578} {"level":"info","ts":"2026-08-13T01:02:37.845462Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5578,"took":"78.940618ms","hash":1393639415,"current-db-size-bytes":53440512,"current-db-size":"53 MB","current-db-size-in-use-bytes":18579456,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-13T01:02:37.845526Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1393639415,"revision":5578,"compact-revision":2905}