{"level":"warn","ts":"2026-07-08T21:31:04.242685Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T21:31:04.242844Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.138: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.138:2380","--initial-cluster=instance=https://199.19.213.138:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.138:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.138: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-07-08T21:31:04.242975Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-08T21:31:04.242999Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.138:2380"]} {"level":"info","ts":"2026-07-08T21:31:04.243038Z","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-07-08T21:31:04.244321Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"]} {"level":"info","ts":"2026-07-08T21:31:04.244431Z","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.138:2380"],"listen-peer-urls":["https://199.19.213.138:2380"],"advertise-client-urls":["https://199.19.213.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.138: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-07-08T21:31:04.249119Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.381197ms"} {"level":"info","ts":"2026-07-08T21:31:04.260253Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"2062be27cfb43adb","cluster-id":"f353072e75ed4d57"} {"level":"info","ts":"2026-07-08T21:31:04.260357Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=()"} {"level":"info","ts":"2026-07-08T21:31:04.260387Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became follower at term 0"} {"level":"info","ts":"2026-07-08T21:31:04.260408Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 2062be27cfb43adb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-08T21:31:04.260418Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became follower at term 1"} {"level":"info","ts":"2026-07-08T21:31:04.26047Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=(2333636635129035483)"} {"level":"warn","ts":"2026-07-08T21:31:04.266853Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-08T21:31:04.268202Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-08T21:31:04.269782Z","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-07-08T21:31:04.272219Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"2062be27cfb43adb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-08T21:31:04.272454Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"2062be27cfb43adb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-08T21:31:04.272613Z","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-07-08T21:31:04.272706Z","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-07-08T21:31:04.272727Z","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-07-08T21:31:04.273709Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb switched to configuration voters=(2333636635129035483)"} {"level":"info","ts":"2026-07-08T21:31:04.275242Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f353072e75ed4d57","local-member-id":"2062be27cfb43adb","added-peer-id":"2062be27cfb43adb","added-peer-peer-urls":["https://199.19.213.138:2380"]} {"level":"info","ts":"2026-07-08T21:31:04.277504Z","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-07-08T21:31:04.27756Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.138:2380"} {"level":"info","ts":"2026-07-08T21:31:04.277609Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.138:2380"} {"level":"info","ts":"2026-07-08T21:31:04.277827Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"2062be27cfb43adb","initial-advertise-peer-urls":["https://199.19.213.138:2380"],"listen-peer-urls":["https://199.19.213.138:2380"],"advertise-client-urls":["https://199.19.213.138:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.138:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-08T21:31:04.277882Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-08T21:31:04.561112Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb is starting a new election at term 1"} {"level":"info","ts":"2026-07-08T21:31:04.561168Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became pre-candidate at term 1"} {"level":"info","ts":"2026-07-08T21:31:04.561194Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb received MsgPreVoteResp from 2062be27cfb43adb at term 1"} {"level":"info","ts":"2026-07-08T21:31:04.561206Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became candidate at term 2"} {"level":"info","ts":"2026-07-08T21:31:04.561212Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb received MsgVoteResp from 2062be27cfb43adb at term 2"} {"level":"info","ts":"2026-07-08T21:31:04.561221Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"2062be27cfb43adb became leader at term 2"} {"level":"info","ts":"2026-07-08T21:31:04.561254Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 2062be27cfb43adb elected leader 2062be27cfb43adb at term 2"} {"level":"info","ts":"2026-07-08T21:31:04.562656Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T21:31:04.564118Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T21:31:04.564118Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"2062be27cfb43adb","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.138:2379]}","request-path":"/0/members/2062be27cfb43adb/attributes","cluster-id":"f353072e75ed4d57","publish-timeout":"7s"} {"level":"info","ts":"2026-07-08T21:31:04.564257Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-08T21:31:04.564727Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-08T21:31:04.564819Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-08T21:31:04.564837Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f353072e75ed4d57","local-member-id":"2062be27cfb43adb","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T21:31:04.564956Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T21:31:04.564998Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-08T21:31:04.56853Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.138:2379"} {"level":"info","ts":"2026-07-08T21:31:04.570557Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"warn","ts":"2026-07-08T21:31:08.987353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"348.103322ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:31:08.987585Z","caller":"traceutil/trace.go:171","msg":"trace[2085529441] linearizableReadLoop","detail":"{readStateIndex:232; appliedIndex:230; }","duration":"543.55921ms","start":"2026-07-08T21:31:08.44401Z","end":"2026-07-08T21:31:08.987569Z","steps":["trace[2085529441] 'read index received' (duration: 194.64458ms)","trace[2085529441] 'applied index is now lower than readState.Index' (duration: 348.91376ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:31:08.987692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.672671ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3492"} {"level":"info","ts":"2026-07-08T21:31:08.987716Z","caller":"traceutil/trace.go:171","msg":"trace[1108343795] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:226; }","duration":"543.728342ms","start":"2026-07-08T21:31:08.443982Z","end":"2026-07-08T21:31:08.98771Z","steps":["trace[1108343795] 'agreement among raft nodes before linearized reading' (duration: 543.639001ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:31:08.987699Z","caller":"traceutil/trace.go:171","msg":"trace[1724862465] transaction","detail":"{read_only:false; response_revision:225; number_of_response:1; }","duration":"562.890386ms","start":"2026-07-08T21:31:08.424775Z","end":"2026-07-08T21:31:08.987666Z","steps":["trace[1724862465] 'process raft request' (duration: 213.888325ms)","trace[1724862465] 'compare' (duration: 347.930291ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:31:08.987794Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:31:08.443968Z","time spent":"543.778003ms","remote":"127.0.0.1:55410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3515,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-08T21:31:08.987823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:31:08.424757Z","time spent":"563.005797ms","remote":"127.0.0.1:55446","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":203,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T21:31:08.987876Z","caller":"traceutil/trace.go:171","msg":"trace[1903468703] transaction","detail":"{read_only:false; response_revision:226; number_of_response:1; }","duration":"561.34178ms","start":"2026-07-08T21:31:08.426436Z","end":"2026-07-08T21:31:08.987778Z","steps":["trace[1903468703] 'process raft request' (duration: 561.067328ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:31:08.988591Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:31:08.426416Z","time spent":"561.668133ms","remote":"127.0.0.1:55328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:31:09.447518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.90207ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:31:09.447784Z","caller":"traceutil/trace.go:171","msg":"trace[1766553929] linearizableReadLoop","detail":"{readStateIndex:233; appliedIndex:232; }","duration":"455.178331ms","start":"2026-07-08T21:31:08.992585Z","end":"2026-07-08T21:31:09.447763Z","steps":["trace[1766553929] 'read index received' (duration: 318.860896ms)","trace[1766553929] 'applied index is now lower than readState.Index' (duration: 136.315625ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:31:09.447867Z","caller":"traceutil/trace.go:171","msg":"trace[750229929] transaction","detail":"{read_only:false; response_revision:227; number_of_response:1; }","duration":"648.655759ms","start":"2026-07-08T21:31:08.799201Z","end":"2026-07-08T21:31:09.447857Z","steps":["trace[750229929] 'process raft request' (duration: 512.307784ms)","trace[750229929] 'compare' (duration: 135.640908ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:31:09.447999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:31:08.799169Z","time spent":"648.8017ms","remote":"127.0.0.1:55424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5498,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T21:31:09.447782Z","caller":"traceutil/trace.go:171","msg":"trace[1263000908] transaction","detail":"{read_only:false; response_revision:228; number_of_response:1; }","duration":"452.15066ms","start":"2026-07-08T21:31:08.99561Z","end":"2026-07-08T21:31:09.447761Z","steps":["trace[1263000908] 'process raft request' (duration: 452.058619ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:31:09.448549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.890379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T21:31:09.448639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:31:08.995556Z","time spent":"452.547595ms","remote":"127.0.0.1:55410","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3695,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:31:09.448697Z","caller":"traceutil/trace.go:171","msg":"trace[1221016414] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/disruption-controller; range_end:; response_count:0; response_revision:228; }","duration":"456.131261ms","start":"2026-07-08T21:31:08.992519Z","end":"2026-07-08T21:31:09.44865Z","steps":["trace[1221016414] 'agreement among raft nodes before linearized reading' (duration: 455.531666ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:31:09.448804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:31:08.992496Z","time spent":"456.246552ms","remote":"127.0.0.1:55446","response type":"/etcdserverpb.KV/Range","request count":0,"request size":61,"response count":0,"response size":28,"request content":"key:\"/registry/serviceaccounts/kube-system/disruption-controller\" "} {"level":"warn","ts":"2026-07-08T21:31:09.449255Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"456.01853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/default/instance.18c06de0799e6d77\" ","response":"range_response_count:1 size:653"} {"level":"info","ts":"2026-07-08T21:31:09.449379Z","caller":"traceutil/trace.go:171","msg":"trace[1220194476] range","detail":"{range_begin:/registry/events/default/instance.18c06de0799e6d77; range_end:; response_count:1; response_revision:228; }","duration":"456.196502ms","start":"2026-07-08T21:31:08.993149Z","end":"2026-07-08T21:31:09.449345Z","steps":["trace[1220194476] 'agreement among raft nodes before linearized reading' (duration: 454.97373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:31:09.449429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:31:08.993126Z","time spent":"456.292493ms","remote":"127.0.0.1:55328","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":1,"response size":676,"request content":"key:\"/registry/events/default/instance.18c06de0799e6d77\" "} {"level":"warn","ts":"2026-07-08T21:31:40.954896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.137184ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:31:40.95533Z","caller":"traceutil/trace.go:171","msg":"trace[279694946] transaction","detail":"{read_only:false; response_revision:562; number_of_response:1; }","duration":"324.366944ms","start":"2026-07-08T21:31:40.630921Z","end":"2026-07-08T21:31:40.955288Z","steps":["trace[279694946] 'process raft request' (duration: 147.720866ms)","trace[279694946] 'compare' (duration: 175.842991ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:31:40.955543Z","caller":"traceutil/trace.go:171","msg":"trace[454721502] transaction","detail":"{read_only:false; response_revision:564; number_of_response:1; }","duration":"238.096292ms","start":"2026-07-08T21:31:40.717428Z","end":"2026-07-08T21:31:40.955524Z","steps":["trace[454721502] 'process raft request' (duration: 237.80386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:31:40.955546Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:31:40.630901Z","time spent":"324.525875ms","remote":"127.0.0.1:55294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":31121,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:31:40.955603Z","caller":"traceutil/trace.go:171","msg":"trace[973413599] transaction","detail":"{read_only:false; response_revision:563; number_of_response:1; }","duration":"318.052654ms","start":"2026-07-08T21:31:40.637513Z","end":"2026-07-08T21:31:40.955566Z","steps":["trace[973413599] 'process raft request' (duration: 317.552991ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:31:40.955774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:31:40.637502Z","time spent":"318.187065ms","remote":"127.0.0.1:55294","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:31:45.520978Z","caller":"traceutil/trace.go:171","msg":"trace[1054176471] transaction","detail":"{read_only:false; response_revision:620; number_of_response:1; }","duration":"105.222717ms","start":"2026-07-08T21:31:45.415729Z","end":"2026-07-08T21:31:45.520952Z","steps":["trace[1054176471] 'process raft request' (duration: 95.946653ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:31:47.009246Z","caller":"traceutil/trace.go:171","msg":"trace[849849560] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"110.552315ms","start":"2026-07-08T21:31:46.898651Z","end":"2026-07-08T21:31:47.009204Z","steps":["trace[849849560] 'process raft request' (duration: 108.454578ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:31:47.313887Z","caller":"traceutil/trace.go:171","msg":"trace[202407552] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"245.482663ms","start":"2026-07-08T21:31:47.06837Z","end":"2026-07-08T21:31:47.313852Z","steps":["trace[202407552] 'process raft request' (duration: 245.22531ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:31:48.059087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"531.276309ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:31:48.059223Z","caller":"traceutil/trace.go:171","msg":"trace[1222359275] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:635; }","duration":"531.45852ms","start":"2026-07-08T21:31:47.527741Z","end":"2026-07-08T21:31:48.0592Z","steps":["trace[1222359275] 'range keys from in-memory index tree' (duration: 531.188677ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:31:48.059263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:31:47.527721Z","time spent":"531.53549ms","remote":"127.0.0.1:55260","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-07-08T21:31:48.059911Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.682226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-5hknh\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-08T21:31:48.060133Z","caller":"traceutil/trace.go:171","msg":"trace[826069732] range","detail":"{range_begin:/registry/pods/kube-system/cilium-5hknh; range_end:; response_count:1; response_revision:635; }","duration":"539.179009ms","start":"2026-07-08T21:31:47.520924Z","end":"2026-07-08T21:31:48.060103Z","steps":["trace[826069732] 'range keys from in-memory index tree' (duration: 538.445224ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:31:48.06022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:31:47.520908Z","time spent":"539.29329ms","remote":"127.0.0.1:55424","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-5hknh\" "} {"level":"info","ts":"2026-07-08T21:31:48.06071Z","caller":"traceutil/trace.go:171","msg":"trace[809861095] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"356.883469ms","start":"2026-07-08T21:31:47.703804Z","end":"2026-07-08T21:31:48.060687Z","steps":["trace[809861095] 'process raft request' (duration: 351.642768ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:31:48.060841Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:31:47.703771Z","time spent":"356.98381ms","remote":"127.0.0.1:55352","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036309,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:31:48.060858Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.956984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-08T21:31:48.060937Z","caller":"traceutil/trace.go:171","msg":"trace[346761969] range","detail":"{range_begin:/registry/masterleases/199.19.213.138; range_end:; response_count:1; response_revision:636; }","duration":"301.044194ms","start":"2026-07-08T21:31:47.759873Z","end":"2026-07-08T21:31:48.060918Z","steps":["trace[346761969] 'agreement among raft nodes before linearized reading' (duration: 300.887153ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:31:48.060731Z","caller":"traceutil/trace.go:171","msg":"trace[165396919] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:656; }","duration":"300.756182ms","start":"2026-07-08T21:31:47.759906Z","end":"2026-07-08T21:31:48.060662Z","steps":["trace[165396919] 'read index received' (duration: 295.323449ms)","trace[165396919] 'applied index is now lower than readState.Index' (duration: 5.431243ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:31:48.060965Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:31:47.759817Z","time spent":"301.143305ms","remote":"127.0.0.1:55290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.138\" "} {"level":"info","ts":"2026-07-08T21:32:03.981868Z","caller":"traceutil/trace.go:171","msg":"trace[666644905] transaction","detail":"{read_only:false; response_revision:801; number_of_response:1; }","duration":"350.807367ms","start":"2026-07-08T21:32:03.631033Z","end":"2026-07-08T21:32:03.98184Z","steps":["trace[666644905] 'process raft request' (duration: 350.63122ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:32:03.98203Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:32:03.631013Z","time spent":"350.93339ms","remote":"127.0.0.1:55424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4025,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:32:49.99136Z","caller":"traceutil/trace.go:171","msg":"trace[982544668] transaction","detail":"{read_only:false; response_revision:1340; number_of_response:1; }","duration":"117.534005ms","start":"2026-07-08T21:32:49.873799Z","end":"2026-07-08T21:32:49.991333Z","steps":["trace[982544668] 'process raft request' (duration: 117.346641ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:33:24.377776Z","caller":"traceutil/trace.go:171","msg":"trace[1378688859] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1960; }","duration":"338.126968ms","start":"2026-07-08T21:33:24.039626Z","end":"2026-07-08T21:33:24.377753Z","steps":["trace[1378688859] 'read index received' (duration: 338.042636ms)","trace[1378688859] 'applied index is now lower than readState.Index' (duration: 83.392µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:33:24.378106Z","caller":"traceutil/trace.go:171","msg":"trace[684958993] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"428.151809ms","start":"2026-07-08T21:33:23.949922Z","end":"2026-07-08T21:33:24.378074Z","steps":["trace[684958993] 'process raft request' (duration: 427.662941ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:33:24.378188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.551625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-805bb36e-6471-4236-926b-66493522c101\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-08T21:33:24.378229Z","caller":"traceutil/trace.go:171","msg":"trace[667595056] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-805bb36e-6471-4236-926b-66493522c101; range_end:; response_count:1; response_revision:1893; }","duration":"338.639907ms","start":"2026-07-08T21:33:24.039578Z","end":"2026-07-08T21:33:24.378218Z","steps":["trace[667595056] 'agreement among raft nodes before linearized reading' (duration: 338.501035ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:33:24.378272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:33:24.03956Z","time spent":"338.703898ms","remote":"127.0.0.1:55424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3650,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-805bb36e-6471-4236-926b-66493522c101\" "} {"level":"warn","ts":"2026-07-08T21:33:24.37831Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:33:23.949896Z","time spent":"428.300313ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:33:24.378216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.026196ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-805bb36e-6471-4236-926b-66493522c101\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-08T21:33:24.378538Z","caller":"traceutil/trace.go:171","msg":"trace[1138325709] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-805bb36e-6471-4236-926b-66493522c101; range_end:; response_count:1; response_revision:1893; }","duration":"281.393702ms","start":"2026-07-08T21:33:24.097128Z","end":"2026-07-08T21:33:24.378521Z","steps":["trace[1138325709] 'agreement among raft nodes before linearized reading' (duration: 280.909854ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:33:24.542467Z","caller":"traceutil/trace.go:171","msg":"trace[551622751] transaction","detail":"{read_only:false; response_revision:1894; number_of_response:1; }","duration":"155.240545ms","start":"2026-07-08T21:33:24.387204Z","end":"2026-07-08T21:33:24.542444Z","steps":["trace[551622751] 'process raft request' (duration: 149.308838ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:33:24.549215Z","caller":"traceutil/trace.go:171","msg":"trace[553566823] transaction","detail":"{read_only:false; response_revision:1895; number_of_response:1; }","duration":"124.885765ms","start":"2026-07-08T21:33:24.424304Z","end":"2026-07-08T21:33:24.54919Z","steps":["trace[553566823] 'process raft request' (duration: 124.740512ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:33:24.968709Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.241495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-08T21:33:24.968784Z","caller":"traceutil/trace.go:171","msg":"trace[1731947960] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1895; }","duration":"278.373218ms","start":"2026-07-08T21:33:24.69039Z","end":"2026-07-08T21:33:24.968763Z","steps":["trace[1731947960] 'range keys from in-memory index tree' (duration: 278.037483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:33:24.968907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.618257ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-07-08T21:33:24.969025Z","caller":"traceutil/trace.go:171","msg":"trace[1994489690] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1895; }","duration":"147.75725ms","start":"2026-07-08T21:33:24.82124Z","end":"2026-07-08T21:33:24.968997Z","steps":["trace[1994489690] 'range keys from in-memory index tree' (duration: 147.363823ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:33:24.969199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.431083ms","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-07-08T21:33:24.969245Z","caller":"traceutil/trace.go:171","msg":"trace[1336952904] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1895; }","duration":"157.475564ms","start":"2026-07-08T21:33:24.811756Z","end":"2026-07-08T21:33:24.969231Z","steps":["trace[1336952904] 'range keys from in-memory index tree' (duration: 157.17505ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:33:24.969395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.333742ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-08T21:33:24.969454Z","caller":"traceutil/trace.go:171","msg":"trace[1585024684] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1895; }","duration":"152.462034ms","start":"2026-07-08T21:33:24.816978Z","end":"2026-07-08T21:33:24.96944Z","steps":["trace[1585024684] 'range keys from in-memory index tree' (duration: 152.063137ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:33:35.912865Z","caller":"traceutil/trace.go:171","msg":"trace[1813426992] transaction","detail":"{read_only:false; response_revision:1992; number_of_response:1; }","duration":"158.448021ms","start":"2026-07-08T21:33:35.754385Z","end":"2026-07-08T21:33:35.912833Z","steps":["trace[1813426992] 'process raft request' (duration: 158.169526ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:33:36.091505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.604461ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:33:36.09177Z","caller":"traceutil/trace.go:171","msg":"trace[108412937] transaction","detail":"{read_only:false; response_revision:1993; number_of_response:1; }","duration":"174.439254ms","start":"2026-07-08T21:33:35.917304Z","end":"2026-07-08T21:33:36.091744Z","steps":["trace[108412937] 'process raft request' (duration: 20.508778ms)","trace[108412937] 'compare' (duration: 153.504829ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:33:36.528226Z","caller":"traceutil/trace.go:171","msg":"trace[476428332] transaction","detail":"{read_only:false; response_revision:1994; number_of_response:1; }","duration":"126.745592ms","start":"2026-07-08T21:33:36.401452Z","end":"2026-07-08T21:33:36.528198Z","steps":["trace[476428332] 'process raft request' (duration: 126.569829ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:33:36.549081Z","caller":"traceutil/trace.go:171","msg":"trace[1576570318] transaction","detail":"{read_only:false; response_revision:1995; number_of_response:1; }","duration":"124.966573ms","start":"2026-07-08T21:33:36.42409Z","end":"2026-07-08T21:33:36.549056Z","steps":["trace[1576570318] 'process raft request' (duration: 124.428234ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:34:02.969335Z","caller":"traceutil/trace.go:171","msg":"trace[1272135801] transaction","detail":"{read_only:false; response_revision:2153; number_of_response:1; }","duration":"258.587001ms","start":"2026-07-08T21:34:02.710719Z","end":"2026-07-08T21:34:02.969306Z","steps":["trace[1272135801] 'process raft request' (duration: 258.398549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:03.453979Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.846128ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-07-08T21:34:03.454394Z","caller":"traceutil/trace.go:171","msg":"trace[223400739] transaction","detail":"{read_only:false; response_revision:2154; number_of_response:1; }","duration":"292.588268ms","start":"2026-07-08T21:34:03.161785Z","end":"2026-07-08T21:34:03.454374Z","steps":["trace[223400739] 'process raft request' (duration: 292.440296ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:34:03.454612Z","caller":"traceutil/trace.go:171","msg":"trace[1799947884] linearizableReadLoop","detail":"{readStateIndex:2229; appliedIndex:2228; }","duration":"411.216768ms","start":"2026-07-08T21:34:03.043304Z","end":"2026-07-08T21:34:03.454521Z","steps":["trace[1799947884] 'read index received' (duration: 38.798872ms)","trace[1799947884] 'applied index is now lower than readState.Index' (duration: 372.414316ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:34:03.454843Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.505832ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T21:34:03.454941Z","caller":"traceutil/trace.go:171","msg":"trace[1382338201] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:2154; }","duration":"411.671116ms","start":"2026-07-08T21:34:03.043251Z","end":"2026-07-08T21:34:03.454922Z","steps":["trace[1382338201] 'agreement among raft nodes before linearized reading' (duration: 411.479972ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:03.454991Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:34:03.043227Z","time spent":"411.753377ms","remote":"127.0.0.1:55424","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":22,"response size":30,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-07-08T21:34:03.455321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.342235ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"warn","ts":"2026-07-08T21:34:03.455343Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.141389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"warn","ts":"2026-07-08T21:34:03.455433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.079071ms","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-07-08T21:34:03.45548Z","caller":"traceutil/trace.go:171","msg":"trace[1482414660] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2154; }","duration":"290.141793ms","start":"2026-07-08T21:34:03.165327Z","end":"2026-07-08T21:34:03.455469Z","steps":["trace[1482414660] 'agreement among raft nodes before linearized reading' (duration: 289.98015ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:03.455365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"289.95662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-08T21:34:03.455587Z","caller":"traceutil/trace.go:171","msg":"trace[1729310466] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2154; }","duration":"290.199143ms","start":"2026-07-08T21:34:03.16537Z","end":"2026-07-08T21:34:03.455569Z","steps":["trace[1729310466] 'agreement among raft nodes before linearized reading' (duration: 289.858288ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:34:03.45539Z","caller":"traceutil/trace.go:171","msg":"trace[2091846571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2154; }","duration":"290.468857ms","start":"2026-07-08T21:34:03.164905Z","end":"2026-07-08T21:34:03.455373Z","steps":["trace[2091846571] 'agreement among raft nodes before linearized reading' (duration: 290.022811ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:34:03.455505Z","caller":"traceutil/trace.go:171","msg":"trace[793866090] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2154; }","duration":"180.269711ms","start":"2026-07-08T21:34:03.275143Z","end":"2026-07-08T21:34:03.455413Z","steps":["trace[793866090] 'agreement among raft nodes before linearized reading' (duration: 179.903536ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:03.455864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.338032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-08T21:34:03.455976Z","caller":"traceutil/trace.go:171","msg":"trace[729456621] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2154; }","duration":"285.484184ms","start":"2026-07-08T21:34:03.170472Z","end":"2026-07-08T21:34:03.455957Z","steps":["trace[729456621] 'agreement among raft nodes before linearized reading' (duration: 284.481039ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:03.674702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.328841ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:34:03.674948Z","caller":"traceutil/trace.go:171","msg":"trace[198519716] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"215.305368ms","start":"2026-07-08T21:34:03.45962Z","end":"2026-07-08T21:34:03.674925Z","steps":["trace[198519716] 'process raft request' (duration: 34.614352ms)","trace[198519716] 'compare' (duration: 179.919075ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:34:03.679748Z","caller":"traceutil/trace.go:171","msg":"trace[1800935837] transaction","detail":"{read_only:false; response_revision:2157; number_of_response:1; }","duration":"219.082693ms","start":"2026-07-08T21:34:03.460625Z","end":"2026-07-08T21:34:03.679707Z","steps":["trace[1800935837] 'process raft request' (duration: 218.963131ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:34:03.679743Z","caller":"traceutil/trace.go:171","msg":"trace[1818530956] transaction","detail":"{read_only:false; response_revision:2156; number_of_response:1; }","duration":"219.315288ms","start":"2026-07-08T21:34:03.460384Z","end":"2026-07-08T21:34:03.6797Z","steps":["trace[1818530956] 'process raft request' (duration: 219.000053ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:34:03.68004Z","caller":"traceutil/trace.go:171","msg":"trace[147336018] linearizableReadLoop","detail":"{readStateIndex:2234; appliedIndex:2231; }","duration":"150.458651ms","start":"2026-07-08T21:34:03.529414Z","end":"2026-07-08T21:34:03.679873Z","steps":["trace[147336018] 'read index received' (duration: 149.977674ms)","trace[147336018] 'applied index is now lower than readState.Index' (duration: 479.457µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:34:03.680059Z","caller":"traceutil/trace.go:171","msg":"trace[543669026] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"219.079953ms","start":"2026-07-08T21:34:03.460899Z","end":"2026-07-08T21:34:03.679979Z","steps":["trace[543669026] 'process raft request' (duration: 218.749828ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:03.680322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.797146ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:34:03.680367Z","caller":"traceutil/trace.go:171","msg":"trace[790732061] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2158; }","duration":"150.981268ms","start":"2026-07-08T21:34:03.529372Z","end":"2026-07-08T21:34:03.680353Z","steps":["trace[790732061] 'agreement among raft nodes before linearized reading' (duration: 150.721384ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:04.223842Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.809853ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:34:04.224291Z","caller":"traceutil/trace.go:171","msg":"trace[715301451] linearizableReadLoop","detail":"{readStateIndex:2235; appliedIndex:2234; }","duration":"543.066011ms","start":"2026-07-08T21:34:03.681194Z","end":"2026-07-08T21:34:04.22426Z","steps":["trace[715301451] 'read index received' (duration: 428.761331ms)","trace[715301451] 'applied index is now lower than readState.Index' (duration: 114.30102ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:34:04.224623Z","caller":"traceutil/trace.go:171","msg":"trace[182236365] transaction","detail":"{read_only:false; response_revision:2160; number_of_response:1; }","duration":"217.576198ms","start":"2026-07-08T21:34:04.006612Z","end":"2026-07-08T21:34:04.224188Z","steps":["trace[182236365] 'process raft request' (duration: 217.387365ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:34:04.224938Z","caller":"traceutil/trace.go:171","msg":"trace[755696686] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"686.193974ms","start":"2026-07-08T21:34:03.538016Z","end":"2026-07-08T21:34:04.22421Z","steps":["trace[755696686] 'process raft request' (duration: 571.915684ms)","trace[755696686] 'compare' (duration: 113.387367ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:34:04.22448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"543.272904ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T21:34:04.225079Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:34:03.537988Z","time spent":"687.005135ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:34:04.225019Z","caller":"traceutil/trace.go:171","msg":"trace[1973329113] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2160; }","duration":"543.830472ms","start":"2026-07-08T21:34:03.681167Z","end":"2026-07-08T21:34:04.224998Z","steps":["trace[1973329113] 'agreement among raft nodes before linearized reading' (duration: 543.252614ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:04.225481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:34:03.681158Z","time spent":"544.183458ms","remote":"127.0.0.1:55254","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-07-08T21:34:05.181773Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.659079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:34:05.18201Z","caller":"traceutil/trace.go:171","msg":"trace[1311306405] transaction","detail":"{read_only:false; response_revision:2163; number_of_response:1; }","duration":"206.988361ms","start":"2026-07-08T21:34:04.974985Z","end":"2026-07-08T21:34:05.181973Z","steps":["trace[1311306405] 'process raft request' (duration: 78.986507ms)","trace[1311306405] 'compare' (duration: 127.469726ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:34:05.62539Z","caller":"traceutil/trace.go:171","msg":"trace[619870307] linearizableReadLoop","detail":"{readStateIndex:2240; appliedIndex:2239; }","duration":"151.151902ms","start":"2026-07-08T21:34:05.474203Z","end":"2026-07-08T21:34:05.625355Z","steps":["trace[619870307] 'read index received' (duration: 150.803467ms)","trace[619870307] 'applied index is now lower than readState.Index' (duration: 346.795µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:34:05.625479Z","caller":"traceutil/trace.go:171","msg":"trace[1462827143] transaction","detail":"{read_only:false; response_revision:2164; number_of_response:1; }","duration":"163.852458ms","start":"2026-07-08T21:34:05.46158Z","end":"2026-07-08T21:34:05.625432Z","steps":["trace[1462827143] 'process raft request' (duration: 163.528294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:05.625664Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.439816ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-08T21:34:05.625755Z","caller":"traceutil/trace.go:171","msg":"trace[707273627] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2164; }","duration":"151.567149ms","start":"2026-07-08T21:34:05.474167Z","end":"2026-07-08T21:34:05.625734Z","steps":["trace[707273627] 'agreement among raft nodes before linearized reading' (duration: 151.373766ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:34:06.064994Z","caller":"traceutil/trace.go:171","msg":"trace[1436203590] linearizableReadLoop","detail":"{readStateIndex:2241; appliedIndex:2240; }","duration":"380.860481ms","start":"2026-07-08T21:34:05.684094Z","end":"2026-07-08T21:34:06.064955Z","steps":["trace[1436203590] 'read index received' (duration: 308.038608ms)","trace[1436203590] 'applied index is now lower than readState.Index' (duration: 72.820833ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:34:06.065257Z","caller":"traceutil/trace.go:171","msg":"trace[1775329068] transaction","detail":"{read_only:false; response_revision:2165; number_of_response:1; }","duration":"381.52111ms","start":"2026-07-08T21:34:05.683705Z","end":"2026-07-08T21:34:06.065226Z","steps":["trace[1775329068] 'process raft request' (duration: 308.427143ms)","trace[1775329068] 'compare' (duration: 72.60488ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:34:06.065413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:34:05.683674Z","time spent":"381.654973ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:34:06.065439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.314258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-07-08T21:34:06.065501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.322808ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:431"} {"level":"info","ts":"2026-07-08T21:34:06.06551Z","caller":"traceutil/trace.go:171","msg":"trace[81233608] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2165; }","duration":"381.423439ms","start":"2026-07-08T21:34:05.684069Z","end":"2026-07-08T21:34:06.065492Z","steps":["trace[81233608] 'agreement among raft nodes before linearized reading' (duration: 381.168295ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:34:06.065543Z","caller":"traceutil/trace.go:171","msg":"trace[2060658606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2165; }","duration":"381.392268ms","start":"2026-07-08T21:34:05.684139Z","end":"2026-07-08T21:34:06.065531Z","steps":["trace[2060658606] 'agreement among raft nodes before linearized reading' (duration: 381.219146ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:06.06556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:34:05.683969Z","time spent":"381.576952ms","remote":"127.0.0.1:55496","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-07-08T21:34:06.065576Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:34:05.68413Z","time spent":"381.438399ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T21:34:06.065782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"381.565662ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-08T21:34:06.065821Z","caller":"traceutil/trace.go:171","msg":"trace[1919341535] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2165; }","duration":"381.602962ms","start":"2026-07-08T21:34:05.684206Z","end":"2026-07-08T21:34:06.065809Z","steps":["trace[1919341535] 'agreement among raft nodes before linearized reading' (duration: 381.48096ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:06.065849Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:34:05.684184Z","time spent":"381.658414ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T21:34:45.98308Z","caller":"traceutil/trace.go:171","msg":"trace[439200822] transaction","detail":"{read_only:false; response_revision:2434; number_of_response:1; }","duration":"203.695742ms","start":"2026-07-08T21:34:45.779359Z","end":"2026-07-08T21:34:45.983055Z","steps":["trace[439200822] 'process raft request' (duration: 203.5252ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:54.815557Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":4241158586966399156,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-08T21:34:54.954141Z","caller":"traceutil/trace.go:171","msg":"trace[1418974772] transaction","detail":"{read_only:false; response_revision:2533; number_of_response:1; }","duration":"645.978783ms","start":"2026-07-08T21:34:54.308126Z","end":"2026-07-08T21:34:54.954104Z","steps":["trace[1418974772] 'process raft request' (duration: 645.72794ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:34:54.954373Z","caller":"traceutil/trace.go:171","msg":"trace[652490834] transaction","detail":"{read_only:false; response_revision:2534; number_of_response:1; }","duration":"558.016134ms","start":"2026-07-08T21:34:54.396341Z","end":"2026-07-08T21:34:54.954357Z","steps":["trace[652490834] 'process raft request' (duration: 557.901483ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:54.954406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:34:54.308114Z","time spent":"646.129415ms","remote":"127.0.0.1:55496","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-07-08T21:34:54.954491Z","caller":"traceutil/trace.go:171","msg":"trace[1767006831] linearizableReadLoop","detail":"{readStateIndex:2622; appliedIndex:2620; }","duration":"639.440451ms","start":"2026-07-08T21:34:54.315036Z","end":"2026-07-08T21:34:54.954476Z","steps":["trace[1767006831] 'read index received' (duration: 638.739642ms)","trace[1767006831] 'applied index is now lower than readState.Index' (duration: 699.979µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:34:54.954511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:34:54.396323Z","time spent":"558.104915ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:34:54.954654Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.614293ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"info","ts":"2026-07-08T21:34:54.954671Z","caller":"traceutil/trace.go:171","msg":"trace[681665593] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2535; }","duration":"639.647143ms","start":"2026-07-08T21:34:54.315019Z","end":"2026-07-08T21:34:54.954666Z","steps":["trace[681665593] 'agreement among raft nodes before linearized reading' (duration: 639.572202ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:54.954673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"639.593972ms","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-07-08T21:34:54.954706Z","caller":"traceutil/trace.go:171","msg":"trace[997833693] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2535; }","duration":"639.637134ms","start":"2026-07-08T21:34:54.315059Z","end":"2026-07-08T21:34:54.954696Z","steps":["trace[997833693] 'agreement among raft nodes before linearized reading' (duration: 639.544232ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:54.954735Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:34:54.315055Z","time spent":"639.674154ms","remote":"127.0.0.1:55496","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-07-08T21:34:54.954752Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.661916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-07-08T21:34:54.954766Z","caller":"traceutil/trace.go:171","msg":"trace[2146897078] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2535; }","duration":"391.688586ms","start":"2026-07-08T21:34:54.563073Z","end":"2026-07-08T21:34:54.954762Z","steps":["trace[2146897078] 'agreement among raft nodes before linearized reading' (duration: 391.647196ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:54.954791Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:34:54.563067Z","time spent":"391.721198ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T21:34:54.95482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"425.147479ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:34:54.954843Z","caller":"traceutil/trace.go:171","msg":"trace[292782714] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2535; }","duration":"425.189929ms","start":"2026-07-08T21:34:54.529645Z","end":"2026-07-08T21:34:54.954835Z","steps":["trace[292782714] 'agreement among raft nodes before linearized reading' (duration: 425.155549ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:54.954865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"636.539045ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-07-08T21:34:54.954685Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:34:54.315011Z","time spent":"639.671063ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-07-08T21:34:54.954877Z","caller":"traceutil/trace.go:171","msg":"trace[1770491185] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2535; }","duration":"636.572945ms","start":"2026-07-08T21:34:54.318299Z","end":"2026-07-08T21:34:54.954872Z","steps":["trace[1770491185] 'agreement among raft nodes before linearized reading' (duration: 636.531784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:34:54.954865Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:34:54.529629Z","time spent":"425.23146ms","remote":"127.0.0.1:55260","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-07-08T21:34:54.954895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:34:54.318289Z","time spent":"636.603606ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-08T21:35:27.526064Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.564839ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:35:27.52626Z","caller":"traceutil/trace.go:171","msg":"trace[664209163] transaction","detail":"{read_only:false; response_revision:2714; number_of_response:1; }","duration":"408.101456ms","start":"2026-07-08T21:35:27.118135Z","end":"2026-07-08T21:35:27.526237Z","steps":["trace[664209163] 'process raft request' (duration: 237.274233ms)","trace[664209163] 'compare' (duration: 170.431377ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:35:27.526323Z","caller":"traceutil/trace.go:171","msg":"trace[780620373] linearizableReadLoop","detail":"{readStateIndex:2809; appliedIndex:2808; }","duration":"405.839839ms","start":"2026-07-08T21:35:27.120466Z","end":"2026-07-08T21:35:27.526306Z","steps":["trace[780620373] 'read index received' (duration: 234.877274ms)","trace[780620373] 'applied index is now lower than readState.Index' (duration: 170.960465ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:35:27.526381Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:35:27.118113Z","time spent":"408.225838ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":417,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:35:27.526482Z","caller":"traceutil/trace.go:171","msg":"trace[457713553] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"399.13127ms","start":"2026-07-08T21:35:27.127342Z","end":"2026-07-08T21:35:27.526474Z","steps":["trace[457713553] 'process raft request' (duration: 398.875127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:35:27.526501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.025392ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-07-08T21:35:27.526532Z","caller":"traceutil/trace.go:171","msg":"trace[1419152761] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2716; }","duration":"406.076402ms","start":"2026-07-08T21:35:27.120445Z","end":"2026-07-08T21:35:27.526522Z","steps":["trace[1419152761] 'agreement among raft nodes before linearized reading' (duration: 405.943711ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:35:27.526537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:35:27.12733Z","time spent":"399.179111ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:35:27.526638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:35:27.120437Z","time spent":"406.127293ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-07-08T21:35:27.526798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.267454ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-08T21:35:27.526824Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"405.619126ms","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-07-08T21:35:27.52683Z","caller":"traceutil/trace.go:171","msg":"trace[1944855866] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2716; }","duration":"406.314325ms","start":"2026-07-08T21:35:27.120507Z","end":"2026-07-08T21:35:27.526821Z","steps":["trace[1944855866] 'agreement among raft nodes before linearized reading' (duration: 406.231734ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:35:27.526856Z","caller":"traceutil/trace.go:171","msg":"trace[1123781997] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2716; }","duration":"405.671417ms","start":"2026-07-08T21:35:27.121175Z","end":"2026-07-08T21:35:27.526846Z","steps":["trace[1123781997] 'agreement among raft nodes before linearized reading' (duration: 405.581856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:35:27.526862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:35:27.120497Z","time spent":"406.356615ms","remote":"127.0.0.1:55496","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-07-08T21:35:27.526893Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:35:27.121165Z","time spent":"405.721717ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T21:35:28.400604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"769.116806ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-08T21:35:28.400701Z","caller":"traceutil/trace.go:171","msg":"trace[1813822355] linearizableReadLoop","detail":"{readStateIndex:2815; appliedIndex:2814; }","duration":"690.620971ms","start":"2026-07-08T21:35:27.710064Z","end":"2026-07-08T21:35:28.400685Z","steps":["trace[1813822355] 'read index received' (duration: 50.39µs)","trace[1813822355] 'applied index is now lower than readState.Index' (duration: 690.569391ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:35:28.400877Z","caller":"traceutil/trace.go:171","msg":"trace[485169600] transaction","detail":"{read_only:false; response_revision:2720; number_of_response:1; }","duration":"823.32806ms","start":"2026-07-08T21:35:27.577537Z","end":"2026-07-08T21:35:28.400865Z","steps":["trace[485169600] 'process raft request' (duration: 53.875911ms)","trace[485169600] 'compare' (duration: 768.852723ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:35:28.400933Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:35:27.57752Z","time spent":"823.384311ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:35:28.401178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"691.121798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-07-08T21:35:28.401212Z","caller":"traceutil/trace.go:171","msg":"trace[1974826060] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2720; }","duration":"691.190998ms","start":"2026-07-08T21:35:27.710009Z","end":"2026-07-08T21:35:28.4012Z","steps":["trace[1974826060] 'agreement among raft nodes before linearized reading' (duration: 691.085497ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:35:28.401239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:35:27.709988Z","time spent":"691.244659ms","remote":"127.0.0.1:55434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":1392,"request content":"key:\"/registry/services/specs/auth-system/keycloak-headless\" "} {"level":"info","ts":"2026-07-08T21:35:28.42008Z","caller":"traceutil/trace.go:171","msg":"trace[1723494424] transaction","detail":"{read_only:false; response_revision:2721; number_of_response:1; }","duration":"270.637017ms","start":"2026-07-08T21:35:28.149416Z","end":"2026-07-08T21:35:28.420053Z","steps":["trace[1723494424] 'process raft request' (duration: 270.404014ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:35:28.420463Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"389.209385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T21:35:28.42063Z","caller":"traceutil/trace.go:171","msg":"trace[474265372] range","detail":"{range_begin:/registry/mutatingwebhookconfigurations/; range_end:/registry/mutatingwebhookconfigurations0; response_count:0; response_revision:2721; }","duration":"389.374987ms","start":"2026-07-08T21:35:28.031192Z","end":"2026-07-08T21:35:28.420567Z","steps":["trace[474265372] 'agreement among raft nodes before linearized reading' (duration: 388.865621ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:35:28.421013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.038289ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.138\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-08T21:35:28.421052Z","caller":"traceutil/trace.go:171","msg":"trace[1565674650] range","detail":"{range_begin:/registry/masterleases/199.19.213.138; range_end:; response_count:1; response_revision:2721; }","duration":"650.113979ms","start":"2026-07-08T21:35:27.770929Z","end":"2026-07-08T21:35:28.421043Z","steps":["trace[1565674650] 'agreement among raft nodes before linearized reading' (duration: 649.953587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:35:28.421074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:35:27.770913Z","time spent":"650.155409ms","remote":"127.0.0.1:55290","response type":"/etcdserverpb.KV/Range","request count":0,"request size":39,"response count":1,"response size":158,"request content":"key:\"/registry/masterleases/199.19.213.138\" "} {"level":"warn","ts":"2026-07-08T21:35:28.420942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:35:28.031171Z","time spent":"389.61148ms","remote":"127.0.0.1:55720","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":2,"response size":30,"request content":"key:\"/registry/mutatingwebhookconfigurations/\" range_end:\"/registry/mutatingwebhookconfigurations0\" count_only:true "} {"level":"info","ts":"2026-07-08T21:35:28.859102Z","caller":"traceutil/trace.go:171","msg":"trace[1485219792] linearizableReadLoop","detail":"{readStateIndex:2817; appliedIndex:2816; }","duration":"434.402361ms","start":"2026-07-08T21:35:28.424648Z","end":"2026-07-08T21:35:28.85905Z","steps":["trace[1485219792] 'read index received' (duration: 424.398133ms)","trace[1485219792] 'applied index is now lower than readState.Index' (duration: 10.002738ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:35:28.859247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:35:28.423944Z","time spent":"435.299193ms","remote":"127.0.0.1:55290","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-08T21:35:28.859555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"434.889068ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak\" ","response":"range_response_count:1 size:1319"} {"level":"info","ts":"2026-07-08T21:35:28.859601Z","caller":"traceutil/trace.go:171","msg":"trace[1522950527] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak; range_end:; response_count:1; response_revision:2721; }","duration":"434.96546ms","start":"2026-07-08T21:35:28.42462Z","end":"2026-07-08T21:35:28.859585Z","steps":["trace[1522950527] 'agreement among raft nodes before linearized reading' (duration: 434.813087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:35:28.859641Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:35:28.424606Z","time spent":"435.02415ms","remote":"127.0.0.1:55434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":1342,"request content":"key:\"/registry/services/specs/auth-system/keycloak\" "} {"level":"warn","ts":"2026-07-08T21:35:28.859641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.458452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:35:28.859748Z","caller":"traceutil/trace.go:171","msg":"trace[1610324002] range","detail":"{range_begin:/registry/rabbitmq.com/shovels/; range_end:/registry/rabbitmq.com/shovels0; response_count:0; response_revision:2721; }","duration":"391.529993ms","start":"2026-07-08T21:35:28.468156Z","end":"2026-07-08T21:35:28.859686Z","steps":["trace[1610324002] 'agreement among raft nodes before linearized reading' (duration: 391.386971ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:35:28.859853Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:35:28.468108Z","time spent":"391.724004ms","remote":"127.0.0.1:56334","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/shovels/\" range_end:\"/registry/rabbitmq.com/shovels0\" count_only:true "} {"level":"warn","ts":"2026-07-08T21:35:28.859817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.635636ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csidrivers/\" range_end:\"/registry/csidrivers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:35:28.860123Z","caller":"traceutil/trace.go:171","msg":"trace[1223283897] range","detail":"{range_begin:/registry/csidrivers/; range_end:/registry/csidrivers0; response_count:0; response_revision:2721; }","duration":"291.986381ms","start":"2026-07-08T21:35:28.568116Z","end":"2026-07-08T21:35:28.860102Z","steps":["trace[1223283897] 'agreement among raft nodes before linearized reading' (duration: 291.511785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:35:28.860003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.021226ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-08T21:35:28.860401Z","caller":"traceutil/trace.go:171","msg":"trace[1643138802] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:2721; }","duration":"226.754435ms","start":"2026-07-08T21:35:28.633626Z","end":"2026-07-08T21:35:28.86038Z","steps":["trace[1643138802] 'agreement among raft nodes before linearized reading' (duration: 225.973356ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:08.00466Z","caller":"traceutil/trace.go:171","msg":"trace[393409329] transaction","detail":"{read_only:false; response_revision:4366; number_of_response:1; }","duration":"151.077891ms","start":"2026-07-08T21:39:07.853555Z","end":"2026-07-08T21:39:08.004632Z","steps":["trace[393409329] 'process raft request' (duration: 54.195154ms)","trace[393409329] 'compare' (duration: 96.468939ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:39:08.004842Z","caller":"traceutil/trace.go:171","msg":"trace[275726814] transaction","detail":"{read_only:false; response_revision:4367; number_of_response:1; }","duration":"119.883584ms","start":"2026-07-08T21:39:07.88488Z","end":"2026-07-08T21:39:08.004764Z","steps":["trace[275726814] 'process raft request' (duration: 119.687121ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:08.0049Z","caller":"traceutil/trace.go:171","msg":"trace[2084828139] linearizableReadLoop","detail":"{readStateIndex:4532; appliedIndex:4530; }","duration":"100.336089ms","start":"2026-07-08T21:39:07.904527Z","end":"2026-07-08T21:39:08.004863Z","steps":["trace[2084828139] 'read index received' (duration: 3.235678ms)","trace[2084828139] 'applied index is now lower than readState.Index' (duration: 97.096921ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:39:08.005123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.587444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:39:08.005185Z","caller":"traceutil/trace.go:171","msg":"trace[747071601] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls; range_end:; response_count:0; response_revision:4367; }","duration":"100.683935ms","start":"2026-07-08T21:39:07.904472Z","end":"2026-07-08T21:39:08.005156Z","steps":["trace[747071601] 'agreement among raft nodes before linearized reading' (duration: 100.510002ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:08.200498Z","caller":"traceutil/trace.go:171","msg":"trace[623793234] transaction","detail":"{read_only:false; response_revision:4372; number_of_response:1; }","duration":"166.640016ms","start":"2026-07-08T21:39:08.033825Z","end":"2026-07-08T21:39:08.200465Z","steps":["trace[623793234] 'process raft request' (duration: 101.766202ms)","trace[623793234] 'compare' (duration: 64.675411ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:39:08.202022Z","caller":"traceutil/trace.go:171","msg":"trace[1615442800] transaction","detail":"{read_only:false; response_revision:4373; number_of_response:1; }","duration":"133.64662ms","start":"2026-07-08T21:39:08.068343Z","end":"2026-07-08T21:39:08.20199Z","steps":["trace[1615442800] 'process raft request' (duration: 133.037059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:08.37694Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.68385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-tls\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:39:08.377046Z","caller":"traceutil/trace.go:171","msg":"trace[1726490393] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-tls; range_end:; response_count:0; response_revision:4379; }","duration":"123.847443ms","start":"2026-07-08T21:39:08.253177Z","end":"2026-07-08T21:39:08.377024Z","steps":["trace[1726490393] 'agreement among raft nodes before linearized reading' (duration: 123.260142ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:13.408793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.757948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1821"} {"level":"info","ts":"2026-07-08T21:39:13.408926Z","caller":"traceutil/trace.go:171","msg":"trace[343978685] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4522; }","duration":"142.919251ms","start":"2026-07-08T21:39:13.265966Z","end":"2026-07-08T21:39:13.408885Z","steps":["trace[343978685] 'range keys from in-memory index tree' (duration: 142.570735ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:13.409175Z","caller":"traceutil/trace.go:171","msg":"trace[1422792745] transaction","detail":"{read_only:false; response_revision:4523; number_of_response:1; }","duration":"136.16954ms","start":"2026-07-08T21:39:13.272979Z","end":"2026-07-08T21:39:13.409148Z","steps":["trace[1422792745] 'process raft request' (duration: 136.009107ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:16.013674Z","caller":"traceutil/trace.go:171","msg":"trace[826694958] transaction","detail":"{read_only:false; response_revision:4552; number_of_response:1; }","duration":"138.354189ms","start":"2026-07-08T21:39:15.875296Z","end":"2026-07-08T21:39:16.01365Z","steps":["trace[826694958] 'process raft request' (duration: 138.216507ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:16.015596Z","caller":"traceutil/trace.go:171","msg":"trace[135374834] transaction","detail":"{read_only:false; response_revision:4553; number_of_response:1; }","duration":"131.903105ms","start":"2026-07-08T21:39:15.883677Z","end":"2026-07-08T21:39:16.01558Z","steps":["trace[135374834] 'process raft request' (duration: 131.826043ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:17.648111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.844271ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-08T21:39:17.648178Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.030537ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-07-08T21:39:17.648208Z","caller":"traceutil/trace.go:171","msg":"trace[1749350102] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4613; }","duration":"119.977464ms","start":"2026-07-08T21:39:17.528208Z","end":"2026-07-08T21:39:17.648186Z","steps":["trace[1749350102] 'range keys from in-memory index tree' (duration: 119.78233ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:17.648235Z","caller":"traceutil/trace.go:171","msg":"trace[889796236] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4613; }","duration":"227.121068ms","start":"2026-07-08T21:39:17.421096Z","end":"2026-07-08T21:39:17.648217Z","steps":["trace[889796236] 'range keys from in-memory index tree' (duration: 226.842824ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:17.649147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.9647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:1229"} {"level":"info","ts":"2026-07-08T21:39:17.649811Z","caller":"traceutil/trace.go:171","msg":"trace[1962306338] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4613; }","duration":"126.633312ms","start":"2026-07-08T21:39:17.523108Z","end":"2026-07-08T21:39:17.649742Z","steps":["trace[1962306338] 'range keys from in-memory index tree' (duration: 125.786027ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:22.957751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.71323ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-grafana-7f6d94df77-xttq9\" ","response":"range_response_count:1 size:11257"} {"level":"warn","ts":"2026-07-08T21:39:22.957774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.111352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-12315e10-3e99-462d-acee-5ce0df804a80\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-08T21:39:22.957834Z","caller":"traceutil/trace.go:171","msg":"trace[949215533] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-12315e10-3e99-462d-acee-5ce0df804a80; range_end:; response_count:1; response_revision:4713; }","duration":"255.221104ms","start":"2026-07-08T21:39:22.702598Z","end":"2026-07-08T21:39:22.957819Z","steps":["trace[949215533] 'range keys from in-memory index tree' (duration: 254.96895ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:22.957831Z","caller":"traceutil/trace.go:171","msg":"trace[525913031] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-grafana-7f6d94df77-xttq9; range_end:; response_count:1; response_revision:4713; }","duration":"206.843502ms","start":"2026-07-08T21:39:22.750967Z","end":"2026-07-08T21:39:22.957811Z","steps":["trace[525913031] 'range keys from in-memory index tree' (duration: 206.558818ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:22.957765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"275.215707ms","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-07-08T21:39:22.957911Z","caller":"traceutil/trace.go:171","msg":"trace[733398798] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4713; }","duration":"275.43972ms","start":"2026-07-08T21:39:22.682448Z","end":"2026-07-08T21:39:22.957887Z","steps":["trace[733398798] 'range keys from in-memory index tree' (duration: 274.974532ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:23.122931Z","caller":"traceutil/trace.go:171","msg":"trace[1946727095] linearizableReadLoop","detail":"{readStateIndex:4888; appliedIndex:4887; }","duration":"359.66936ms","start":"2026-07-08T21:39:22.76324Z","end":"2026-07-08T21:39:23.122909Z","steps":["trace[1946727095] 'read index received' (duration: 359.575648ms)","trace[1946727095] 'applied index is now lower than readState.Index' (duration: 92.422µs)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:39:23.122999Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:22.751832Z","time spent":"371.159511ms","remote":"127.0.0.1:55328","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-08T21:39:23.12319Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.925194ms","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-07-08T21:39:23.123282Z","caller":"traceutil/trace.go:171","msg":"trace[1420051258] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4713; }","duration":"360.053106ms","start":"2026-07-08T21:39:22.763205Z","end":"2026-07-08T21:39:23.123258Z","steps":["trace[1420051258] 'agreement among raft nodes before linearized reading' (duration: 359.787871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:23.123348Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:22.763188Z","time spent":"360.148388ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-07-08T21:39:23.431933Z","caller":"traceutil/trace.go:171","msg":"trace[1359956750] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"470.525282ms","start":"2026-07-08T21:39:22.961379Z","end":"2026-07-08T21:39:23.431904Z","steps":["trace[1359956750] 'process raft request' (duration: 470.269868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:23.432076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:22.961335Z","time spent":"470.668085ms","remote":"127.0.0.1:55496","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-07-08T21:39:23.432171Z","caller":"traceutil/trace.go:171","msg":"trace[1018175092] transaction","detail":"{read_only:false; response_revision:4718; number_of_response:1; }","duration":"244.511887ms","start":"2026-07-08T21:39:23.187638Z","end":"2026-07-08T21:39:23.43215Z","steps":["trace[1018175092] 'process raft request' (duration: 244.457526ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:23.43229Z","caller":"traceutil/trace.go:171","msg":"trace[161955459] transaction","detail":"{read_only:false; response_revision:4716; number_of_response:1; }","duration":"307.980913ms","start":"2026-07-08T21:39:23.124284Z","end":"2026-07-08T21:39:23.432265Z","steps":["trace[161955459] 'process raft request' (duration: 307.672087ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:23.432344Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:23.124259Z","time spent":"308.060245ms","remote":"127.0.0.1:55328","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":883,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-08T21:39:23.432389Z","caller":"traceutil/trace.go:171","msg":"trace[1514430949] transaction","detail":"{read_only:false; response_revision:4715; number_of_response:1; }","duration":"463.467439ms","start":"2026-07-08T21:39:22.9689Z","end":"2026-07-08T21:39:23.432368Z","steps":["trace[1514430949] 'process raft request' (duration: 462.905178ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:23.432479Z","caller":"traceutil/trace.go:171","msg":"trace[614867789] linearizableReadLoop","detail":"{readStateIndex:4891; appliedIndex:4888; }","duration":"309.4163ms","start":"2026-07-08T21:39:23.123053Z","end":"2026-07-08T21:39:23.432469Z","steps":["trace[614867789] 'read index received' (duration: 285.158193ms)","trace[614867789] 'applied index is now lower than readState.Index' (duration: 24.256787ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:39:23.43254Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:22.968882Z","time spent":"463.56321ms","remote":"127.0.0.1:55424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11833,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:39:23.432603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"562.38039ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-07-08T21:39:23.432631Z","caller":"traceutil/trace.go:171","msg":"trace[1930160252] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4718; }","duration":"562.449921ms","start":"2026-07-08T21:39:22.870172Z","end":"2026-07-08T21:39:23.432622Z","steps":["trace[1930160252] 'agreement among raft nodes before linearized reading' (duration: 562.336059ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:23.432636Z","caller":"traceutil/trace.go:171","msg":"trace[1467162495] transaction","detail":"{read_only:false; response_revision:4717; number_of_response:1; }","duration":"305.021261ms","start":"2026-07-08T21:39:23.127595Z","end":"2026-07-08T21:39:23.432616Z","steps":["trace[1467162495] 'process raft request' (duration: 304.44358ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:23.432662Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:22.870149Z","time spent":"562.507912ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-08T21:39:23.432763Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:23.127574Z","time spent":"305.122844ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-08T21:39:23.432802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"547.731782ms","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-07-08T21:39:23.432904Z","caller":"traceutil/trace.go:171","msg":"trace[932788387] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4718; }","duration":"547.877395ms","start":"2026-07-08T21:39:22.885004Z","end":"2026-07-08T21:39:23.432881Z","steps":["trace[932788387] 'agreement among raft nodes before linearized reading' (duration: 547.56894ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:23.432966Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:22.884978Z","time spent":"547.975836ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-08T21:39:23.548186Z","caller":"traceutil/trace.go:171","msg":"trace[617210126] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"104.243642ms","start":"2026-07-08T21:39:23.443922Z","end":"2026-07-08T21:39:23.548166Z","steps":["trace[617210126] 'process raft request' (duration: 104.198271ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:23.548494Z","caller":"traceutil/trace.go:171","msg":"trace[500022670] transaction","detail":"{read_only:false; response_revision:4720; number_of_response:1; }","duration":"109.105888ms","start":"2026-07-08T21:39:23.439378Z","end":"2026-07-08T21:39:23.548484Z","steps":["trace[500022670] 'process raft request' (duration: 108.567469ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:23.548585Z","caller":"traceutil/trace.go:171","msg":"trace[847612875] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"108.427846ms","start":"2026-07-08T21:39:23.440151Z","end":"2026-07-08T21:39:23.548578Z","steps":["trace[847612875] 'process raft request' (duration: 107.890727ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:23.54873Z","caller":"traceutil/trace.go:171","msg":"trace[1208389281] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"107.469599ms","start":"2026-07-08T21:39:23.441254Z","end":"2026-07-08T21:39:23.548723Z","steps":["trace[1208389281] 'process raft request' (duration: 106.823548ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:23.939766Z","caller":"traceutil/trace.go:171","msg":"trace[584786480] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"178.369056ms","start":"2026-07-08T21:39:23.761365Z","end":"2026-07-08T21:39:23.939734Z","steps":["trace[584786480] 'process raft request' (duration: 95.229654ms)","trace[584786480] 'compare' (duration: 82.663903ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:39:26.965105Z","caller":"traceutil/trace.go:171","msg":"trace[346246212] transaction","detail":"{read_only:false; response_revision:4768; number_of_response:1; }","duration":"173.972011ms","start":"2026-07-08T21:39:26.791106Z","end":"2026-07-08T21:39:26.965078Z","steps":["trace[346246212] 'process raft request' (duration: 173.811968ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:32.228513Z","caller":"traceutil/trace.go:171","msg":"trace[485701581] transaction","detail":"{read_only:false; response_revision:4863; number_of_response:1; }","duration":"336.664175ms","start":"2026-07-08T21:39:31.891826Z","end":"2026-07-08T21:39:32.22849Z","steps":["trace[485701581] 'process raft request' (duration: 336.536002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:32.22864Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:31.891814Z","time spent":"336.765577ms","remote":"127.0.0.1:55424","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13834,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:39:32.230243Z","caller":"traceutil/trace.go:171","msg":"trace[585789207] transaction","detail":"{read_only:false; response_revision:4864; number_of_response:1; }","duration":"121.717644ms","start":"2026-07-08T21:39:32.108505Z","end":"2026-07-08T21:39:32.230222Z","steps":["trace[585789207] 'process raft request' (duration: 121.577732ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:36.804695Z","caller":"traceutil/trace.go:171","msg":"trace[1101629326] linearizableReadLoop","detail":"{readStateIndex:5082; appliedIndex:5081; }","duration":"428.618919ms","start":"2026-07-08T21:39:36.376044Z","end":"2026-07-08T21:39:36.804663Z","steps":["trace[1101629326] 'read index received' (duration: 428.464746ms)","trace[1101629326] 'applied index is now lower than readState.Index' (duration: 152.503µs)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:39:36.804903Z","caller":"traceutil/trace.go:171","msg":"trace[758687188] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"562.673361ms","start":"2026-07-08T21:39:36.242217Z","end":"2026-07-08T21:39:36.80489Z","steps":["trace[758687188] 'process raft request' (duration: 562.230983ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:36.804993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"428.932274ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:39:36.805143Z","caller":"traceutil/trace.go:171","msg":"trace[1851652326] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:4901; }","duration":"429.214888ms","start":"2026-07-08T21:39:36.375899Z","end":"2026-07-08T21:39:36.805114Z","steps":["trace[1851652326] 'agreement among raft nodes before linearized reading' (duration: 429.025965ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:36.805218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:36.375877Z","time spent":"429.319751ms","remote":"127.0.0.1:59460","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"warn","ts":"2026-07-08T21:39:36.805217Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:36.24219Z","time spent":"562.776112ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:39:36.917209Z","caller":"traceutil/trace.go:171","msg":"trace[301063130] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"435.761171ms","start":"2026-07-08T21:39:36.481421Z","end":"2026-07-08T21:39:36.917182Z","steps":["trace[301063130] 'process raft request' (duration: 431.345506ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:36.917352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"251.538999ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"warn","ts":"2026-07-08T21:39:36.91733Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"388.5657ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:39:36.9174Z","caller":"traceutil/trace.go:171","msg":"trace[296751857] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4903; }","duration":"251.617069ms","start":"2026-07-08T21:39:36.66577Z","end":"2026-07-08T21:39:36.917387Z","steps":["trace[296751857] 'agreement among raft nodes before linearized reading' (duration: 251.461147ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:36.917404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.14683ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3316"} {"level":"warn","ts":"2026-07-08T21:39:36.91746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"459.676712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-07-08T21:39:36.917434Z","caller":"traceutil/trace.go:171","msg":"trace[671728649] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4903; }","duration":"388.718503ms","start":"2026-07-08T21:39:36.5287Z","end":"2026-07-08T21:39:36.917419Z","steps":["trace[671728649] 'agreement among raft nodes before linearized reading' (duration: 388.56726ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:36.917464Z","caller":"traceutil/trace.go:171","msg":"trace[1275212759] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4903; }","duration":"495.231671ms","start":"2026-07-08T21:39:36.422213Z","end":"2026-07-08T21:39:36.917445Z","steps":["trace[1275212759] 'agreement among raft nodes before linearized reading' (duration: 495.050738ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:36.917497Z","caller":"traceutil/trace.go:171","msg":"trace[1782855153] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4903; }","duration":"459.729083ms","start":"2026-07-08T21:39:36.457757Z","end":"2026-07-08T21:39:36.917486Z","steps":["trace[1782855153] 'agreement among raft nodes before linearized reading' (duration: 459.626011ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:36.917509Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:36.528687Z","time spent":"388.810754ms","remote":"127.0.0.1:55260","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-07-08T21:39:36.917514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:36.422199Z","time spent":"495.301622ms","remote":"127.0.0.1:56292","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3339,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-07-08T21:39:36.917526Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:36.457746Z","time spent":"459.773794ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-08T21:39:36.917353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:36.481393Z","time spent":"435.886063ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:39:36.917542Z","caller":"traceutil/trace.go:171","msg":"trace[1404054618] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"385.936285ms","start":"2026-07-08T21:39:36.531582Z","end":"2026-07-08T21:39:36.917519Z","steps":["trace[1404054618] 'process raft request' (duration: 385.526398ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:36.917673Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-08T21:39:36.531562Z","time spent":"386.037417ms","remote":"127.0.0.1:55496","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-08T21:39:36.917201Z","caller":"traceutil/trace.go:171","msg":"trace[443702001] linearizableReadLoop","detail":"{readStateIndex:5084; appliedIndex:5082; }","duration":"112.322078ms","start":"2026-07-08T21:39:36.804853Z","end":"2026-07-08T21:39:36.917175Z","steps":["trace[443702001] 'read index received' (duration: 107.874582ms)","trace[443702001] 'applied index is now lower than readState.Index' (duration: 4.445916ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:39:37.049965Z","caller":"traceutil/trace.go:171","msg":"trace[2018979914] linearizableReadLoop","detail":"{readStateIndex:5085; appliedIndex:5084; }","duration":"125.468522ms","start":"2026-07-08T21:39:36.924474Z","end":"2026-07-08T21:39:37.049943Z","steps":["trace[2018979914] 'read index received' (duration: 100.575246ms)","trace[2018979914] 'applied index is now lower than readState.Index' (duration: 24.891786ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:39:37.050048Z","caller":"traceutil/trace.go:171","msg":"trace[1784901105] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"128.001696ms","start":"2026-07-08T21:39:36.922011Z","end":"2026-07-08T21:39:37.050012Z","steps":["trace[1784901105] 'process raft request' (duration: 103.027448ms)","trace[1784901105] 'compare' (duration: 24.765045ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:39:37.05037Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.87645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-keystone-default-user\" ","response":"range_response_count:1 size:1321"} {"level":"info","ts":"2026-07-08T21:39:37.050499Z","caller":"traceutil/trace.go:171","msg":"trace[323509210] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-keystone-default-user; range_end:; response_count:1; response_revision:4904; }","duration":"126.055272ms","start":"2026-07-08T21:39:36.924425Z","end":"2026-07-08T21:39:37.05048Z","steps":["trace[323509210] 'agreement among raft nodes before linearized reading' (duration: 125.704176ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:37.069782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.7968ms","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-07-08T21:39:37.070127Z","caller":"traceutil/trace.go:171","msg":"trace[99839410] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:4905; }","duration":"104.175736ms","start":"2026-07-08T21:39:36.965938Z","end":"2026-07-08T21:39:37.070114Z","steps":["trace[99839410] 'agreement among raft nodes before linearized reading' (duration: 103.740229ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:37.070052Z","caller":"traceutil/trace.go:171","msg":"trace[632902381] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"140.411068ms","start":"2026-07-08T21:39:36.929617Z","end":"2026-07-08T21:39:37.070028Z","steps":["trace[632902381] 'process raft request' (duration: 139.812208ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:37.682614Z","caller":"traceutil/trace.go:171","msg":"trace[989522170] linearizableReadLoop","detail":"{readStateIndex:5095; appliedIndex:5094; }","duration":"181.701854ms","start":"2026-07-08T21:39:37.500869Z","end":"2026-07-08T21:39:37.68257Z","steps":["trace[989522170] 'read index received' (duration: 129.748804ms)","trace[989522170] 'applied index is now lower than readState.Index' (duration: 51.95173ms)"],"step_count":2} {"level":"info","ts":"2026-07-08T21:39:37.682703Z","caller":"traceutil/trace.go:171","msg":"trace[1020348219] transaction","detail":"{read_only:false; response_revision:4914; number_of_response:1; }","duration":"200.068278ms","start":"2026-07-08T21:39:37.482596Z","end":"2026-07-08T21:39:37.682664Z","steps":["trace[1020348219] 'process raft request' (duration: 148.040147ms)","trace[1020348219] 'compare' (duration: 51.786177ms)"],"step_count":2} {"level":"warn","ts":"2026-07-08T21:39:37.683074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.527132ms","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-07-08T21:39:37.683131Z","caller":"traceutil/trace.go:171","msg":"trace[1394341505] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4914; }","duration":"167.633113ms","start":"2026-07-08T21:39:37.515483Z","end":"2026-07-08T21:39:37.683117Z","steps":["trace[1394341505] 'agreement among raft nodes before linearized reading' (duration: 167.47724ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:37.683301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.975929ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-07-08T21:39:37.68331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.584458ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:39:37.683371Z","caller":"traceutil/trace.go:171","msg":"trace[1824660697] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4914; }","duration":"123.675439ms","start":"2026-07-08T21:39:37.55968Z","end":"2026-07-08T21:39:37.683355Z","steps":["trace[1824660697] 'agreement among raft nodes before linearized reading' (duration: 123.581607ms)"],"step_count":1} {"level":"warn","ts":"2026-07-08T21:39:37.683369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.88572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-08T21:39:37.683553Z","caller":"traceutil/trace.go:171","msg":"trace[664617080] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4914; }","duration":"153.136955ms","start":"2026-07-08T21:39:37.530398Z","end":"2026-07-08T21:39:37.683535Z","steps":["trace[664617080] 'agreement among raft nodes before linearized reading' (duration: 152.942142ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:37.683369Z","caller":"traceutil/trace.go:171","msg":"trace[171551446] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4914; }","duration":"182.535908ms","start":"2026-07-08T21:39:37.500816Z","end":"2026-07-08T21:39:37.683352Z","steps":["trace[171551446] 'agreement among raft nodes before linearized reading' (duration: 181.923857ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:39:38.805855Z","caller":"traceutil/trace.go:171","msg":"trace[488714353] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"237.071555ms","start":"2026-07-08T21:39:38.568756Z","end":"2026-07-08T21:39:38.805828Z","steps":["trace[488714353] 'process raft request' (duration: 236.918303ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:41:00.282127Z","caller":"traceutil/trace.go:171","msg":"trace[1694977555] transaction","detail":"{read_only:false; response_revision:5497; number_of_response:1; }","duration":"104.285418ms","start":"2026-07-08T21:41:00.177808Z","end":"2026-07-08T21:41:00.282094Z","steps":["trace[1694977555] 'process raft request' (duration: 103.980993ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:41:00.282368Z","caller":"traceutil/trace.go:171","msg":"trace[345707013] transaction","detail":"{read_only:false; response_revision:5498; number_of_response:1; }","duration":"104.302159ms","start":"2026-07-08T21:41:00.17804Z","end":"2026-07-08T21:41:00.282342Z","steps":["trace[345707013] 'process raft request' (duration: 103.894343ms)"],"step_count":1} {"level":"info","ts":"2026-07-08T21:41:04.666957Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2911} {"level":"info","ts":"2026-07-08T21:41:04.796157Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2911,"took":"127.890682ms","hash":3411030060,"current-db-size-bytes":53932032,"current-db-size":"54 MB","current-db-size-in-use-bytes":28717056,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-08T21:41:04.796203Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3411030060,"revision":2911,"compact-revision":-1} {"level":"info","ts":"2026-07-08T21:46:04.67484Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5528} {"level":"info","ts":"2026-07-08T21:46:04.775801Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5528,"took":"99.532224ms","hash":608833109,"current-db-size-bytes":53932032,"current-db-size":"54 MB","current-db-size-in-use-bytes":18309120,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-08T21:46:04.775868Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":608833109,"revision":5528,"compact-revision":2911}