{"level":"warn","ts":"2026-07-14T16:51:37.915238Z","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-14T16:51:37.915312Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.201: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.201:2380","--initial-cluster=instance=https://199.19.213.201:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.201:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.201: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-14T16:51:37.915375Z","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-14T16:51:37.915389Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-07-14T16:51:37.915454Z","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-14T16:51:37.916182Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"]} {"level":"info","ts":"2026-07-14T16:51:37.916329Z","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.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.201: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-14T16:51:37.920424Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.807326ms"} {"level":"info","ts":"2026-07-14T16:51:37.931944Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"61bc451777564e43","cluster-id":"b1814cb801e33ddc"} {"level":"info","ts":"2026-07-14T16:51:37.932349Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=()"} {"level":"info","ts":"2026-07-14T16:51:37.932395Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 0"} {"level":"info","ts":"2026-07-14T16:51:37.932409Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 61bc451777564e43 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-14T16:51:37.932419Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became follower at term 1"} {"level":"info","ts":"2026-07-14T16:51:37.932614Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"warn","ts":"2026-07-14T16:51:37.942891Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-14T16:51:37.947757Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-14T16:51:37.95068Z","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-14T16:51:37.954645Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"61bc451777564e43","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-14T16:51:37.954771Z","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-14T16:51:37.95487Z","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-14T16:51:37.954883Z","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-14T16:51:37.955069Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"61bc451777564e43","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-14T16:51:37.958263Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 switched to configuration voters=(7042579884389322307)"} {"level":"info","ts":"2026-07-14T16:51:37.958388Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","added-peer-id":"61bc451777564e43","added-peer-peer-urls":["https://199.19.213.201:2380"]} {"level":"info","ts":"2026-07-14T16:51:37.959881Z","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-14T16:51:37.960079Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"61bc451777564e43","initial-advertise-peer-urls":["https://199.19.213.201:2380"],"listen-peer-urls":["https://199.19.213.201:2380"],"advertise-client-urls":["https://199.19.213.201:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.201:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-14T16:51:37.960121Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-14T16:51:37.960232Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-07-14T16:51:37.96025Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.201:2380"} {"level":"info","ts":"2026-07-14T16:51:38.133068Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 is starting a new election at term 1"} {"level":"info","ts":"2026-07-14T16:51:38.133159Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-14T16:51:38.1332Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgPreVoteResp from 61bc451777564e43 at term 1"} {"level":"info","ts":"2026-07-14T16:51:38.13324Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became candidate at term 2"} {"level":"info","ts":"2026-07-14T16:51:38.133264Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 received MsgVoteResp from 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-07-14T16:51:38.133282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"61bc451777564e43 became leader at term 2"} {"level":"info","ts":"2026-07-14T16:51:38.1333Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 61bc451777564e43 elected leader 61bc451777564e43 at term 2"} {"level":"info","ts":"2026-07-14T16:51:38.134287Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"61bc451777564e43","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.201:2379]}","request-path":"/0/members/61bc451777564e43/attributes","cluster-id":"b1814cb801e33ddc","publish-timeout":"7s"} {"level":"info","ts":"2026-07-14T16:51:38.134454Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T16:51:38.13453Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-14T16:51:38.134678Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:51:38.137461Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-14T16:51:38.13822Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.201:2379"} {"level":"info","ts":"2026-07-14T16:51:38.138404Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"b1814cb801e33ddc","local-member-id":"61bc451777564e43","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:51:38.138499Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:51:38.138534Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-14T16:51:38.141943Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-14T16:51:38.141962Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-14T16:51:43.608192Z","caller":"traceutil/trace.go:171","msg":"trace[40043533] transaction","detail":"{read_only:false; response_revision:281; number_of_response:1; }","duration":"161.051956ms","start":"2026-07-14T16:51:43.447101Z","end":"2026-07-14T16:51:43.608153Z","steps":["trace[40043533] 'process raft request' (duration: 160.786505ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:51:52.115347Z","caller":"traceutil/trace.go:171","msg":"trace[647271383] linearizableReadLoop","detail":"{readStateIndex:420; appliedIndex:419; }","duration":"127.080809ms","start":"2026-07-14T16:51:51.988243Z","end":"2026-07-14T16:51:52.115324Z","steps":["trace[647271383] 'read index received' (duration: 126.893202ms)","trace[647271383] 'applied index is now lower than readState.Index' (duration: 186.747µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:51:52.115912Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.605007ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:51:52.115749Z","caller":"traceutil/trace.go:171","msg":"trace[1644883650] transaction","detail":"{read_only:false; response_revision:406; number_of_response:1; }","duration":"181.998571ms","start":"2026-07-14T16:51:51.933703Z","end":"2026-07-14T16:51:52.115701Z","steps":["trace[1644883650] 'process raft request' (duration: 181.503433ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:51:52.11607Z","caller":"traceutil/trace.go:171","msg":"trace[1577431319] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:406; }","duration":"127.810306ms","start":"2026-07-14T16:51:51.988198Z","end":"2026-07-14T16:51:52.116008Z","steps":["trace[1577431319] 'agreement among raft nodes before linearized reading' (duration: 127.543576ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:51:52.116215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.485066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T16:51:52.116306Z","caller":"traceutil/trace.go:171","msg":"trace[1166010930] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:406; }","duration":"126.617011ms","start":"2026-07-14T16:51:51.989673Z","end":"2026-07-14T16:51:52.11629Z","steps":["trace[1166010930] 'agreement among raft nodes before linearized reading' (duration: 126.467135ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:51:52.788074Z","caller":"traceutil/trace.go:171","msg":"trace[1035942225] transaction","detail":"{read_only:false; response_revision:407; number_of_response:1; }","duration":"667.908131ms","start":"2026-07-14T16:51:52.120139Z","end":"2026-07-14T16:51:52.788047Z","steps":["trace[1035942225] 'process raft request' (duration: 667.768706ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:51:52.788667Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:51:52.120122Z","time spent":"668.026676ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:51:53.401199Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.397329ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-vip-instance\" ","response":"range_response_count:1 size:4795"} {"level":"warn","ts":"2026-07-14T16:51:53.401257Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.93605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:51:53.401295Z","caller":"traceutil/trace.go:171","msg":"trace[1309222111] range","detail":"{range_begin:/registry/pods/kube-system/kube-vip-instance; range_end:; response_count:1; response_revision:407; }","duration":"165.544904ms","start":"2026-07-14T16:51:53.235726Z","end":"2026-07-14T16:51:53.401271Z","steps":["trace[1309222111] 'range keys from in-memory index tree' (duration: 165.248973ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:51:53.401346Z","caller":"traceutil/trace.go:171","msg":"trace[2104194541] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:407; }","duration":"412.082325ms","start":"2026-07-14T16:51:52.989244Z","end":"2026-07-14T16:51:53.401327Z","steps":["trace[2104194541] 'range keys from in-memory index tree' (duration: 411.840207ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:51:53.401392Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:51:52.989225Z","time spent":"412.158918ms","remote":"127.0.0.1:47410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T16:52:02.842852Z","caller":"traceutil/trace.go:171","msg":"trace[1425552365] transaction","detail":"{read_only:false; response_revision:437; number_of_response:1; }","duration":"139.560456ms","start":"2026-07-14T16:52:02.703267Z","end":"2026-07-14T16:52:02.842827Z","steps":["trace[1425552365] 'process raft request' (duration: 139.36848ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:52:16.979069Z","caller":"traceutil/trace.go:171","msg":"trace[971315134] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"106.666761ms","start":"2026-07-14T16:52:16.872378Z","end":"2026-07-14T16:52:16.979045Z","steps":["trace[971315134] 'process raft request' (duration: 106.527037ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:52:16.98592Z","caller":"traceutil/trace.go:171","msg":"trace[631305153] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"102.887224ms","start":"2026-07-14T16:52:16.883018Z","end":"2026-07-14T16:52:16.985905Z","steps":["trace[631305153] 'process raft request' (duration: 102.603986ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:52:16.98613Z","caller":"traceutil/trace.go:171","msg":"trace[1505598875] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"113.471966ms","start":"2026-07-14T16:52:16.872648Z","end":"2026-07-14T16:52:16.98612Z","steps":["trace[1505598875] 'process raft request' (duration: 112.756125ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:52:16.986218Z","caller":"traceutil/trace.go:171","msg":"trace[1443532405] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"108.764571ms","start":"2026-07-14T16:52:16.877447Z","end":"2026-07-14T16:52:16.986212Z","steps":["trace[1443532405] 'process raft request' (duration: 108.089732ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:52:16.986286Z","caller":"traceutil/trace.go:171","msg":"trace[976854801] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"108.682479ms","start":"2026-07-14T16:52:16.877597Z","end":"2026-07-14T16:52:16.98628Z","steps":["trace[976854801] 'process raft request' (duration: 107.980579ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:52:23.195627Z","caller":"traceutil/trace.go:171","msg":"trace[843986952] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"150.855184ms","start":"2026-07-14T16:52:23.044739Z","end":"2026-07-14T16:52:23.195594Z","steps":["trace[843986952] 'process raft request' (duration: 150.68413ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:52:23.855351Z","caller":"traceutil/trace.go:171","msg":"trace[143245194] linearizableReadLoop","detail":"{readStateIndex:655; appliedIndex:654; }","duration":"508.999107ms","start":"2026-07-14T16:52:23.34632Z","end":"2026-07-14T16:52:23.855319Z","steps":["trace[143245194] 'read index received' (duration: 491.993233ms)","trace[143245194] 'applied index is now lower than readState.Index' (duration: 17.004664ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:52:23.85536Z","caller":"traceutil/trace.go:171","msg":"trace[731427706] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"580.597157ms","start":"2026-07-14T16:52:23.274727Z","end":"2026-07-14T16:52:23.855324Z","steps":["trace[731427706] 'process raft request' (duration: 564.502878ms)","trace[731427706] 'compare' (duration: 15.652727ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:52:23.855568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"509.226243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-bwvb7\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-14T16:52:23.855644Z","caller":"traceutil/trace.go:171","msg":"trace[755870209] range","detail":"{range_begin:/registry/pods/kube-system/cilium-bwvb7; range_end:; response_count:1; response_revision:630; }","duration":"509.340446ms","start":"2026-07-14T16:52:23.346285Z","end":"2026-07-14T16:52:23.855626Z","steps":["trace[755870209] 'agreement among raft nodes before linearized reading' (duration: 509.1377ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:52:23.855689Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:52:23.346267Z","time spent":"509.413148ms","remote":"127.0.0.1:47584","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-bwvb7\" "} {"level":"warn","ts":"2026-07-14T16:52:23.855703Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:52:23.274687Z","time spent":"580.858134ms","remote":"127.0.0.1:47486","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036109,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:52:25.162877Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.616258ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:52:25.163175Z","caller":"traceutil/trace.go:171","msg":"trace[79476250] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:655; }","duration":"1.217062749s","start":"2026-07-14T16:52:23.946094Z","end":"2026-07-14T16:52:25.163157Z","steps":["trace[79476250] 'read index received' (duration: 440.821304ms)","trace[79476250] 'applied index is now lower than readState.Index' (duration: 776.239845ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:52:25.163557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.20325215s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-14T16:52:25.163557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.217449349s","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-14T16:52:25.163591Z","caller":"traceutil/trace.go:171","msg":"trace[705969761] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:633; }","duration":"1.203303082s","start":"2026-07-14T16:52:23.960276Z","end":"2026-07-14T16:52:25.163579Z","steps":["trace[705969761] 'agreement among raft nodes before linearized reading' (duration: 1.203169619s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:52:25.163587Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"858.092158ms","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-14T16:52:25.163583Z","caller":"traceutil/trace.go:171","msg":"trace[389607044] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.292643176s","start":"2026-07-14T16:52:23.870904Z","end":"2026-07-14T16:52:25.163548Z","steps":["trace[389607044] 'process raft request' (duration: 516.078842ms)","trace[389607044] 'compare' (duration: 775.422434ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:52:25.163631Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:52:23.960266Z","time spent":"1.203357794s","remote":"127.0.0.1:47670","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-14T16:52:25.163643Z","caller":"traceutil/trace.go:171","msg":"trace[2030411219] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:633; }","duration":"858.187771ms","start":"2026-07-14T16:52:24.305437Z","end":"2026-07-14T16:52:25.163625Z","steps":["trace[2030411219] 'agreement among raft nodes before linearized reading' (duration: 858.039717ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:52:25.163673Z","caller":"traceutil/trace.go:171","msg":"trace[873090173] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"1.049715533s","start":"2026-07-14T16:52:24.113948Z","end":"2026-07-14T16:52:25.163664Z","steps":["trace[873090173] 'process raft request' (duration: 1.049154988s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:52:25.163683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:52:24.305348Z","time spent":"858.327634ms","remote":"127.0.0.1:47670","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-14T16:52:25.16372Z","caller":"traceutil/trace.go:171","msg":"trace[1448987884] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"1.24671328s","start":"2026-07-14T16:52:23.916975Z","end":"2026-07-14T16:52:25.163688Z","steps":["trace[1448987884] 'process raft request' (duration: 1.246014352s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:52:25.163758Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:52:24.113922Z","time spent":"1.049781254s","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:52:25.163757Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:52:23.870881Z","time spent":"1.292780488s","remote":"127.0.0.1:47584","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:52:25.163862Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:52:23.91695Z","time spent":"1.246860723s","remote":"127.0.0.1:47612","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":818,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-14T16:52:25.163628Z","caller":"traceutil/trace.go:171","msg":"trace[261279234] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:633; }","duration":"1.217566422s","start":"2026-07-14T16:52:23.946044Z","end":"2026-07-14T16:52:25.16361Z","steps":["trace[261279234] 'agreement among raft nodes before linearized reading' (duration: 1.217336046s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:52:25.163891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.174890424s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T16:52:25.163917Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:52:23.946021Z","time spent":"1.217886091s","remote":"127.0.0.1:47670","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-14T16:52:25.16393Z","caller":"traceutil/trace.go:171","msg":"trace[973157077] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:633; }","duration":"1.174945975s","start":"2026-07-14T16:52:23.988975Z","end":"2026-07-14T16:52:25.163921Z","steps":["trace[973157077] 'agreement among raft nodes before linearized reading' (duration: 1.174881354s)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:52:25.163958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:52:23.988967Z","time spent":"1.174985396s","remote":"127.0.0.1:47410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-07-14T16:52:26.775115Z","caller":"traceutil/trace.go:171","msg":"trace[1010461443] linearizableReadLoop","detail":"{readStateIndex:690; appliedIndex:689; }","duration":"171.263173ms","start":"2026-07-14T16:52:26.603821Z","end":"2026-07-14T16:52:26.775084Z","steps":["trace[1010461443] 'read index received' (duration: 170.35185ms)","trace[1010461443] 'applied index is now lower than readState.Index' (duration: 909.753µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:52:26.775322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.472018ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:52:26.775359Z","caller":"traceutil/trace.go:171","msg":"trace[1104026369] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/ciliumnodes.cilium.io; range_end:; response_count:0; response_revision:664; }","duration":"171.592412ms","start":"2026-07-14T16:52:26.603753Z","end":"2026-07-14T16:52:26.775346Z","steps":["trace[1104026369] 'agreement among raft nodes before linearized reading' (duration: 171.465329ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:52:26.775603Z","caller":"traceutil/trace.go:171","msg":"trace[1780463715] transaction","detail":"{read_only:false; response_revision:664; number_of_response:1; }","duration":"219.354651ms","start":"2026-07-14T16:52:26.556209Z","end":"2026-07-14T16:52:26.775563Z","steps":["trace[1780463715] 'process raft request' (duration: 218.281303ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:52:39.176698Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.131503ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:52:39.176789Z","caller":"traceutil/trace.go:171","msg":"trace[67701906] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:798; }","duration":"186.271896ms","start":"2026-07-14T16:52:38.990498Z","end":"2026-07-14T16:52:39.176769Z","steps":["trace[67701906] 'range keys from in-memory index tree' (duration: 186.063571ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:23.671618Z","caller":"traceutil/trace.go:171","msg":"trace[497059325] transaction","detail":"{read_only:false; response_revision:1341; number_of_response:1; }","duration":"189.78627ms","start":"2026-07-14T16:53:23.481797Z","end":"2026-07-14T16:53:23.671583Z","steps":["trace[497059325] 'process raft request' (duration: 188.437187ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:41.189527Z","caller":"traceutil/trace.go:171","msg":"trace[1840075112] linearizableReadLoop","detail":"{readStateIndex:1691; appliedIndex:1685; }","duration":"214.317697ms","start":"2026-07-14T16:53:40.97519Z","end":"2026-07-14T16:53:41.189508Z","steps":["trace[1840075112] 'read index received' (duration: 132.182453ms)","trace[1840075112] 'applied index is now lower than readState.Index' (duration: 82.133504ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:41.189638Z","caller":"traceutil/trace.go:171","msg":"trace[1312087704] transaction","detail":"{read_only:false; response_revision:1624; number_of_response:1; }","duration":"250.908388ms","start":"2026-07-14T16:53:40.938685Z","end":"2026-07-14T16:53:41.189593Z","steps":["trace[1312087704] 'process raft request' (duration: 250.736655ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:41.189837Z","caller":"traceutil/trace.go:171","msg":"trace[174428131] transaction","detail":"{read_only:false; number_of_response:1; response_revision:1625; }","duration":"246.873804ms","start":"2026-07-14T16:53:40.942932Z","end":"2026-07-14T16:53:41.189805Z","steps":["trace[174428131] 'process raft request' (duration: 246.551049ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:41.189903Z","caller":"traceutil/trace.go:171","msg":"trace[1221385125] transaction","detail":"{read_only:false; response_revision:1621; number_of_response:1; }","duration":"254.520464ms","start":"2026-07-14T16:53:40.93536Z","end":"2026-07-14T16:53:41.189881Z","steps":["trace[1221385125] 'process raft request' (duration: 172.019494ms)","trace[1221385125] 'compare' (duration: 81.84294ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:41.19002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.816095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/rabbitmq-messaging-topology-operator\" ","response":"range_response_count:1 size:5755"} {"level":"info","ts":"2026-07-14T16:53:41.190069Z","caller":"traceutil/trace.go:171","msg":"trace[510119177] range","detail":"{range_begin:/registry/deployments/openstack/rabbitmq-messaging-topology-operator; range_end:; response_count:1; response_revision:1625; }","duration":"248.901416ms","start":"2026-07-14T16:53:40.941156Z","end":"2026-07-14T16:53:41.190058Z","steps":["trace[510119177] 'agreement among raft nodes before linearized reading' (duration: 248.716643ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:41.190117Z","caller":"traceutil/trace.go:171","msg":"trace[580889402] transaction","detail":"{read_only:false; response_revision:1625; number_of_response:1; }","duration":"250.973699ms","start":"2026-07-14T16:53:40.939133Z","end":"2026-07-14T16:53:41.190106Z","steps":["trace[580889402] 'process raft request' (duration: 250.314958ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:41.189502Z","caller":"traceutil/trace.go:171","msg":"trace[230908633] transaction","detail":"{read_only:false; response_revision:1622; number_of_response:1; }","duration":"251.008279ms","start":"2026-07-14T16:53:40.93846Z","end":"2026-07-14T16:53:41.189468Z","steps":["trace[230908633] 'process raft request' (duration: 250.900397ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:41.190332Z","caller":"traceutil/trace.go:171","msg":"trace[24249431] transaction","detail":"{read_only:false; response_revision:1623; number_of_response:1; }","duration":"251.683099ms","start":"2026-07-14T16:53:40.938639Z","end":"2026-07-14T16:53:41.190322Z","steps":["trace[24249431] 'process raft request' (duration: 250.756875ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:41.19861Z","caller":"traceutil/trace.go:171","msg":"trace[556802130] transaction","detail":"{read_only:false; response_revision:1627; number_of_response:1; }","duration":"212.052961ms","start":"2026-07-14T16:53:40.986529Z","end":"2026-07-14T16:53:41.198582Z","steps":["trace[556802130] 'process raft request' (duration: 212.00458ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:41.198651Z","caller":"traceutil/trace.go:171","msg":"trace[857186958] transaction","detail":"{read_only:false; response_revision:1626; number_of_response:1; }","duration":"216.295727ms","start":"2026-07-14T16:53:40.982336Z","end":"2026-07-14T16:53:41.198632Z","steps":["trace[857186958] 'process raft request' (duration: 216.105554ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:41.19897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.204707ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:53:41.199041Z","caller":"traceutil/trace.go:171","msg":"trace[1492351569] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1627; }","duration":"209.318758ms","start":"2026-07-14T16:53:40.989707Z","end":"2026-07-14T16:53:41.199025Z","steps":["trace[1492351569] 'agreement among raft nodes before linearized reading' (duration: 209.195426ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:41.502337Z","caller":"traceutil/trace.go:171","msg":"trace[173409490] transaction","detail":"{read_only:false; response_revision:1646; number_of_response:1; }","duration":"174.73028ms","start":"2026-07-14T16:53:41.327587Z","end":"2026-07-14T16:53:41.502318Z","steps":["trace[173409490] 'process raft request' (duration: 165.408965ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:41.502594Z","caller":"traceutil/trace.go:171","msg":"trace[1864300536] linearizableReadLoop","detail":"{readStateIndex:1714; appliedIndex:1711; }","duration":"172.539376ms","start":"2026-07-14T16:53:41.330022Z","end":"2026-07-14T16:53:41.502561Z","steps":["trace[1864300536] 'read index received' (duration: 74.037778ms)","trace[1864300536] 'applied index is now lower than readState.Index' (duration: 98.500528ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:41.502798Z","caller":"traceutil/trace.go:171","msg":"trace[832616502] transaction","detail":"{read_only:false; response_revision:1647; number_of_response:1; }","duration":"173.706264ms","start":"2026-07-14T16:53:41.329078Z","end":"2026-07-14T16:53:41.502784Z","steps":["trace[832616502] 'process raft request' (duration: 173.195216ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:41.503009Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.947472ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-07-14T16:53:41.503105Z","caller":"traceutil/trace.go:171","msg":"trace[236993154] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:1647; }","duration":"173.083205ms","start":"2026-07-14T16:53:41.329997Z","end":"2026-07-14T16:53:41.503081Z","steps":["trace[236993154] 'agreement among raft nodes before linearized reading' (duration: 172.79313ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:41.50362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.294296ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:2 size:133118"} {"level":"info","ts":"2026-07-14T16:53:41.503713Z","caller":"traceutil/trace.go:171","msg":"trace[263080211] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:2; response_revision:1649; }","duration":"120.378637ms","start":"2026-07-14T16:53:41.383279Z","end":"2026-07-14T16:53:41.503658Z","steps":["trace[263080211] 'agreement among raft nodes before linearized reading' (duration: 120.066172ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:56.688819Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.660095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c21c7251-c7a7-4efb-9272-fdf162921cba\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-07-14T16:53:56.688915Z","caller":"traceutil/trace.go:171","msg":"trace[858027480] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c21c7251-c7a7-4efb-9272-fdf162921cba; range_end:; response_count:1; response_revision:1881; }","duration":"107.800997ms","start":"2026-07-14T16:53:56.581092Z","end":"2026-07-14T16:53:56.688893Z","steps":["trace[858027480] 'range keys from in-memory index tree' (duration: 107.480612ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:57.126811Z","caller":"traceutil/trace.go:171","msg":"trace[1004413622] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"245.709645ms","start":"2026-07-14T16:53:56.881071Z","end":"2026-07-14T16:53:57.126781Z","steps":["trace[1004413622] 'process raft request' (duration: 245.494722ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:57.798528Z","caller":"traceutil/trace.go:171","msg":"trace[2110125916] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1959; }","duration":"794.598227ms","start":"2026-07-14T16:53:57.003905Z","end":"2026-07-14T16:53:57.798503Z","steps":["trace[2110125916] 'read index received' (duration: 122.712937ms)","trace[2110125916] 'applied index is now lower than readState.Index' (duration: 671.88419ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:57.798511Z","caller":"traceutil/trace.go:171","msg":"trace[1857229464] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"845.620728ms","start":"2026-07-14T16:53:56.952857Z","end":"2026-07-14T16:53:57.798477Z","steps":["trace[1857229464] 'process raft request' (duration: 798.155171ms)","trace[1857229464] 'compare' (duration: 46.644365ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:53:57.798927Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:56.952838Z","time spent":"845.834102ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:53:57.799107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.63805ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-c21c7251-c7a7-4efb-9272-fdf162921cba\" ","response":"range_response_count:1 size:3747"} {"level":"warn","ts":"2026-07-14T16:53:57.799135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.703672ms","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-14T16:53:57.799183Z","caller":"traceutil/trace.go:171","msg":"trace[425290317] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-c21c7251-c7a7-4efb-9272-fdf162921cba; range_end:; response_count:1; response_revision:1885; }","duration":"105.747101ms","start":"2026-07-14T16:53:57.693419Z","end":"2026-07-14T16:53:57.799166Z","steps":["trace[425290317] 'agreement among raft nodes before linearized reading' (duration: 105.557468ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:57.799205Z","caller":"traceutil/trace.go:171","msg":"trace[985535334] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1885; }","duration":"132.820363ms","start":"2026-07-14T16:53:57.666367Z","end":"2026-07-14T16:53:57.799188Z","steps":["trace[985535334] 'agreement among raft nodes before linearized reading' (duration: 132.57156ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:57.799441Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.929069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-14T16:53:57.799483Z","caller":"traceutil/trace.go:171","msg":"trace[1315705889] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1885; }","duration":"133.989531ms","start":"2026-07-14T16:53:57.665483Z","end":"2026-07-14T16:53:57.799473Z","steps":["trace[1315705889] 'agreement among raft nodes before linearized reading' (duration: 133.878799ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:57.798803Z","caller":"traceutil/trace.go:171","msg":"trace[936569723] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"133.201959ms","start":"2026-07-14T16:53:57.665558Z","end":"2026-07-14T16:53:57.79876Z","steps":["trace[936569723] 'process raft request' (duration: 132.882655ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:57.799592Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"583.744493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"info","ts":"2026-07-14T16:53:57.799661Z","caller":"traceutil/trace.go:171","msg":"trace[116722078] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1885; }","duration":"583.878565ms","start":"2026-07-14T16:53:57.215752Z","end":"2026-07-14T16:53:57.799631Z","steps":["trace[116722078] 'agreement among raft nodes before linearized reading' (duration: 583.042623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:57.799716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:57.215732Z","time spent":"583.970976ms","remote":"127.0.0.1:44480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5467,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-07-14T16:53:57.799884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.806776ms","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-14T16:53:57.799945Z","caller":"traceutil/trace.go:171","msg":"trace[128600201] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1885; }","duration":"135.921368ms","start":"2026-07-14T16:53:57.664009Z","end":"2026-07-14T16:53:57.79993Z","steps":["trace[128600201] 'agreement among raft nodes before linearized reading' (duration: 135.436591ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:57.801124Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"797.165074ms","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-14T16:53:57.801345Z","caller":"traceutil/trace.go:171","msg":"trace[752456928] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1885; }","duration":"797.387847ms","start":"2026-07-14T16:53:57.003855Z","end":"2026-07-14T16:53:57.801243Z","steps":["trace[752456928] 'agreement among raft nodes before linearized reading' (duration: 794.782259ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:57.801524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:53:57.003843Z","time spent":"797.58779ms","remote":"127.0.0.1:47670","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":"info","ts":"2026-07-14T16:53:58.102037Z","caller":"traceutil/trace.go:171","msg":"trace[408514681] transaction","detail":"{read_only:false; response_revision:1887; number_of_response:1; }","duration":"295.121408ms","start":"2026-07-14T16:53:57.806886Z","end":"2026-07-14T16:53:58.102008Z","steps":["trace[408514681] 'process raft request' (duration: 283.219227ms)","trace[408514681] 'compare' (duration: 11.594507ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:58.102125Z","caller":"traceutil/trace.go:171","msg":"trace[1295774234] linearizableReadLoop","detail":"{readStateIndex:1966; appliedIndex:1963; }","duration":"235.559796ms","start":"2026-07-14T16:53:57.866544Z","end":"2026-07-14T16:53:58.102104Z","steps":["trace[1295774234] 'read index received' (duration: 223.557743ms)","trace[1295774234] 'applied index is now lower than readState.Index' (duration: 12.000953ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:53:58.102272Z","caller":"traceutil/trace.go:171","msg":"trace[1360711893] transaction","detail":"{read_only:false; response_revision:1888; number_of_response:1; }","duration":"295.24661ms","start":"2026-07-14T16:53:57.807003Z","end":"2026-07-14T16:53:58.10225Z","steps":["trace[1360711893] 'process raft request' (duration: 294.914725ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:58.102365Z","caller":"traceutil/trace.go:171","msg":"trace[1430466244] transaction","detail":"{read_only:false; response_revision:1889; number_of_response:1; }","duration":"295.172539ms","start":"2026-07-14T16:53:57.807176Z","end":"2026-07-14T16:53:58.102348Z","steps":["trace[1430466244] 'process raft request' (duration: 294.866245ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:58.102498Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.931782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/\" range_end:\"/registry/cilium.io/ciliumendpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T16:53:58.102584Z","caller":"traceutil/trace.go:171","msg":"trace[1772466276] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/; range_end:/registry/cilium.io/ciliumendpoints0; response_count:0; response_revision:1889; }","duration":"236.102703ms","start":"2026-07-14T16:53:57.86646Z","end":"2026-07-14T16:53:58.102563Z","steps":["trace[1772466276] 'agreement among raft nodes before linearized reading' (duration: 235.913281ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:58.114565Z","caller":"traceutil/trace.go:171","msg":"trace[209051342] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"216.041062ms","start":"2026-07-14T16:53:57.898511Z","end":"2026-07-14T16:53:58.114552Z","steps":["trace[209051342] 'process raft request' (duration: 215.86961ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:58.114755Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.306247ms","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-14T16:53:58.114837Z","caller":"traceutil/trace.go:171","msg":"trace[447315805] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1891; }","duration":"245.434298ms","start":"2026-07-14T16:53:57.869385Z","end":"2026-07-14T16:53:58.114819Z","steps":["trace[447315805] 'agreement among raft nodes before linearized reading' (duration: 245.224865ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:53:58.11487Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.855771ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:53:58.115031Z","caller":"traceutil/trace.go:171","msg":"trace[1626647009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1891; }","duration":"125.119905ms","start":"2026-07-14T16:53:57.989856Z","end":"2026-07-14T16:53:58.114976Z","steps":["trace[1626647009] 'agreement among raft nodes before linearized reading' (duration: 124.841981ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:53:58.58038Z","caller":"traceutil/trace.go:171","msg":"trace[1016628128] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"144.272881ms","start":"2026-07-14T16:53:58.436075Z","end":"2026-07-14T16:53:58.580347Z","steps":["trace[1016628128] 'process raft request' (duration: 138.740592ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:54:10.297033Z","caller":"traceutil/trace.go:171","msg":"trace[1736317757] transaction","detail":"{read_only:false; response_revision:1988; number_of_response:1; }","duration":"138.614286ms","start":"2026-07-14T16:54:10.158382Z","end":"2026-07-14T16:54:10.296996Z","steps":["trace[1736317757] 'process raft request' (duration: 126.843503ms)","trace[1736317757] 'compare' (duration: 11.509799ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:54:10.297376Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.266133ms","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-14T16:54:10.297487Z","caller":"traceutil/trace.go:171","msg":"trace[1007675525] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1989; }","duration":"131.415836ms","start":"2026-07-14T16:54:10.166044Z","end":"2026-07-14T16:54:10.29746Z","steps":["trace[1007675525] 'agreement among raft nodes before linearized reading' (duration: 131.140032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:54:10.297671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.112732ms","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-14T16:54:10.297853Z","caller":"traceutil/trace.go:171","msg":"trace[314145077] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1989; }","duration":"139.603339ms","start":"2026-07-14T16:54:10.158213Z","end":"2026-07-14T16:54:10.297816Z","steps":["trace[314145077] 'agreement among raft nodes before linearized reading' (duration: 138.996201ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:54:46.714126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.823234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:54:46.714388Z","caller":"traceutil/trace.go:171","msg":"trace[1336068156] linearizableReadLoop","detail":"{readStateIndex:2286; appliedIndex:2284; }","duration":"231.150981ms","start":"2026-07-14T16:54:46.483219Z","end":"2026-07-14T16:54:46.71437Z","steps":["trace[1336068156] 'read index received' (duration: 55.987923ms)","trace[1336068156] 'applied index is now lower than readState.Index' (duration: 175.162228ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:54:46.714518Z","caller":"traceutil/trace.go:171","msg":"trace[1334662473] transaction","detail":"{read_only:false; response_revision:2198; number_of_response:1; }","duration":"269.664195ms","start":"2026-07-14T16:54:46.44484Z","end":"2026-07-14T16:54:46.714504Z","steps":["trace[1334662473] 'process raft request' (duration: 269.473932ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:54:46.714465Z","caller":"traceutil/trace.go:171","msg":"trace[1022601033] transaction","detail":"{read_only:false; response_revision:2197; number_of_response:1; }","duration":"352.516304ms","start":"2026-07-14T16:54:46.361887Z","end":"2026-07-14T16:54:46.714403Z","steps":["trace[1022601033] 'process raft request' (duration: 177.311066ms)","trace[1022601033] 'compare' (duration: 174.682612ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:54:46.714788Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.526656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-14T16:54:46.714847Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:54:46.361863Z","time spent":"352.877939ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:54:46.714869Z","caller":"traceutil/trace.go:171","msg":"trace[1208293977] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2198; }","duration":"231.672087ms","start":"2026-07-14T16:54:46.483185Z","end":"2026-07-14T16:54:46.714857Z","steps":["trace[1208293977] 'agreement among raft nodes before linearized reading' (duration: 231.391013ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:54:46.80489Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.575026ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-07-14T16:54:46.804933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.478109ms","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-14T16:54:46.804963Z","caller":"traceutil/trace.go:171","msg":"trace[831711648] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2199; }","duration":"317.682697ms","start":"2026-07-14T16:54:46.487261Z","end":"2026-07-14T16:54:46.804943Z","steps":["trace[831711648] 'agreement among raft nodes before linearized reading' (duration: 317.431623ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:54:46.80504Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:54:46.487251Z","time spent":"317.781328ms","remote":"127.0.0.1:47670","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-14T16:54:46.804994Z","caller":"traceutil/trace.go:171","msg":"trace[1333074989] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2199; }","duration":"307.57229ms","start":"2026-07-14T16:54:46.497405Z","end":"2026-07-14T16:54:46.804978Z","steps":["trace[1333074989] 'agreement among raft nodes before linearized reading' (duration: 307.427028ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:54:46.805218Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:54:46.497395Z","time spent":"307.805423ms","remote":"127.0.0.1:47670","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-14T16:54:46.805305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.49779ms","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-14T16:54:46.805395Z","caller":"traceutil/trace.go:171","msg":"trace[76738756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2200; }","duration":"315.627851ms","start":"2026-07-14T16:54:46.489744Z","end":"2026-07-14T16:54:46.805372Z","steps":["trace[76738756] 'agreement among raft nodes before linearized reading' (duration: 315.223986ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:54:46.80545Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:54:46.489735Z","time spent":"315.704262ms","remote":"127.0.0.1:47670","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-14T16:54:52.239921Z","caller":"traceutil/trace.go:171","msg":"trace[796531871] transaction","detail":"{read_only:false; response_revision:2228; number_of_response:1; }","duration":"108.692958ms","start":"2026-07-14T16:54:52.131202Z","end":"2026-07-14T16:54:52.239895Z","steps":["trace[796531871] 'process raft request' (duration: 108.461445ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:00.712391Z","caller":"traceutil/trace.go:171","msg":"trace[91601839] linearizableReadLoop","detail":"{readStateIndex:2812; appliedIndex:2811; }","duration":"146.271657ms","start":"2026-07-14T16:56:00.566094Z","end":"2026-07-14T16:56:00.712365Z","steps":["trace[91601839] 'read index received' (duration: 146.243906ms)","trace[91601839] 'applied index is now lower than readState.Index' (duration: 26.641µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:56:00.712422Z","caller":"traceutil/trace.go:171","msg":"trace[1190720309] transaction","detail":"{read_only:false; response_revision:2707; number_of_response:1; }","duration":"250.33398ms","start":"2026-07-14T16:56:00.462064Z","end":"2026-07-14T16:56:00.712398Z","steps":["trace[1190720309] 'process raft request' (duration: 250.157231ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:00.71271Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.602963ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-14T16:56:00.712775Z","caller":"traceutil/trace.go:171","msg":"trace[1171442756] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2707; }","duration":"146.72984ms","start":"2026-07-14T16:56:00.56603Z","end":"2026-07-14T16:56:00.71276Z","steps":["trace[1171442756] 'agreement among raft nodes before linearized reading' (duration: 146.441046ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:00.933435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.798286ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:56:00.933664Z","caller":"traceutil/trace.go:171","msg":"trace[375931734] transaction","detail":"{read_only:false; response_revision:2709; number_of_response:1; }","duration":"216.514738ms","start":"2026-07-14T16:56:00.717131Z","end":"2026-07-14T16:56:00.933646Z","steps":["trace[375931734] 'process raft request' (duration: 216.411793ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:00.93375Z","caller":"traceutil/trace.go:171","msg":"trace[1003726364] transaction","detail":"{read_only:false; response_revision:2708; number_of_response:1; }","duration":"332.730899ms","start":"2026-07-14T16:56:00.600989Z","end":"2026-07-14T16:56:00.93372Z","steps":["trace[1003726364] 'process raft request' (duration: 224.535563ms)","trace[1003726364] 'compare' (duration: 107.574015ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:56:00.933876Z","caller":"traceutil/trace.go:171","msg":"trace[427642816] linearizableReadLoop","detail":"{readStateIndex:2813; appliedIndex:2812; }","duration":"221.378708ms","start":"2026-07-14T16:56:00.712486Z","end":"2026-07-14T16:56:00.933864Z","steps":["trace[427642816] 'read index received' (duration: 113.032044ms)","trace[427642816] 'applied index is now lower than readState.Index' (duration: 108.345074ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:56:00.934045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.612383ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-07-14T16:56:00.93408Z","caller":"traceutil/trace.go:171","msg":"trace[1946863738] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2709; }","duration":"355.648265ms","start":"2026-07-14T16:56:00.578422Z","end":"2026-07-14T16:56:00.93407Z","steps":["trace[1946863738] 'agreement among raft nodes before linearized reading' (duration: 355.488567ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:00.934115Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:00.578369Z","time spent":"355.739309ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":71,"response count":1,"response size":582,"request content":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" "} {"level":"warn","ts":"2026-07-14T16:56:00.934034Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:00.60097Z","time spent":"332.921418ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:56:01.867397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.001871ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:56:01.867535Z","caller":"traceutil/trace.go:171","msg":"trace[2103900573] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:2710; }","duration":"132.152449ms","start":"2026-07-14T16:56:01.735361Z","end":"2026-07-14T16:56:01.867513Z","steps":["trace[2103900573] 'range keys from in-memory index tree' (duration: 131.98397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:01.867957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"533.03969ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:56:01.868206Z","caller":"traceutil/trace.go:171","msg":"trace[271918927] transaction","detail":"{read_only:false; response_revision:2711; number_of_response:1; }","duration":"860.743636ms","start":"2026-07-14T16:56:01.007431Z","end":"2026-07-14T16:56:01.868175Z","steps":["trace[271918927] 'process raft request' (duration: 327.038533ms)","trace[271918927] 'compare' (duration: 531.105696ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:56:01.868346Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.007414Z","time spent":"860.84792ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:56:01.868353Z","caller":"traceutil/trace.go:171","msg":"trace[1378848817] linearizableReadLoop","detail":"{readStateIndex:2817; appliedIndex:2815; }","duration":"706.879926ms","start":"2026-07-14T16:56:01.161433Z","end":"2026-07-14T16:56:01.868313Z","steps":["trace[1378848817] 'read index received' (duration: 173.050427ms)","trace[1378848817] 'applied index is now lower than readState.Index' (duration: 533.828209ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:56:01.868446Z","caller":"traceutil/trace.go:171","msg":"trace[1714765396] transaction","detail":"{read_only:false; response_revision:2712; number_of_response:1; }","duration":"781.994397ms","start":"2026-07-14T16:56:01.086432Z","end":"2026-07-14T16:56:01.868427Z","steps":["trace[1714765396] 'process raft request' (duration: 781.683282ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:01.868586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.086403Z","time spent":"782.136464ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:56:01.868758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.439581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-14T16:56:01.868809Z","caller":"traceutil/trace.go:171","msg":"trace[1844279091] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2712; }","duration":"488.522125ms","start":"2026-07-14T16:56:01.380269Z","end":"2026-07-14T16:56:01.868791Z","steps":["trace[1844279091] 'agreement among raft nodes before linearized reading' (duration: 488.374198ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:01.868793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"596.720833ms","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-14T16:56:01.868887Z","caller":"traceutil/trace.go:171","msg":"trace[1201852846] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2712; }","duration":"596.841188ms","start":"2026-07-14T16:56:01.272032Z","end":"2026-07-14T16:56:01.868873Z","steps":["trace[1201852846] 'agreement among raft nodes before linearized reading' (duration: 596.560774ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:01.868965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.305545ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:529"} {"level":"warn","ts":"2026-07-14T16:56:01.868966Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"580.113181ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-07-14T16:56:01.869144Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"586.454341ms","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-14T16:56:01.86918Z","caller":"traceutil/trace.go:171","msg":"trace[835960733] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2712; }","duration":"586.529765ms","start":"2026-07-14T16:56:01.282643Z","end":"2026-07-14T16:56:01.869173Z","steps":["trace[835960733] 'agreement among raft nodes before linearized reading' (duration: 586.443871ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:01.869207Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.282623Z","time spent":"586.577547ms","remote":"127.0.0.1:47670","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-14T16:56:01.869188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"585.793888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-07-14T16:56:01.868746Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"707.308308ms","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-14T16:56:01.869257Z","caller":"traceutil/trace.go:171","msg":"trace[1206815933] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2712; }","duration":"585.882782ms","start":"2026-07-14T16:56:01.283365Z","end":"2026-07-14T16:56:01.869247Z","steps":["trace[1206815933] 'agreement among raft nodes before linearized reading' (duration: 585.677292ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:01.869342Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.283356Z","time spent":"585.976668ms","remote":"127.0.0.1:47670","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-14T16:56:01.869389Z","caller":"traceutil/trace.go:171","msg":"trace[1632130141] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2712; }","duration":"707.947489ms","start":"2026-07-14T16:56:01.161363Z","end":"2026-07-14T16:56:01.869311Z","steps":["trace[1632130141] 'agreement among raft nodes before linearized reading' (duration: 707.16756ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:01.86956Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.161336Z","time spent":"708.207291ms","remote":"127.0.0.1:47596","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-14T16:56:01.868993Z","caller":"traceutil/trace.go:171","msg":"trace[1669963033] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2712; }","duration":"106.369619ms","start":"2026-07-14T16:56:01.762616Z","end":"2026-07-14T16:56:01.868986Z","steps":["trace[1669963033] 'agreement among raft nodes before linearized reading' (duration: 106.280314ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:01.869771Z","caller":"traceutil/trace.go:171","msg":"trace[1952327255] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2712; }","duration":"580.949442ms","start":"2026-07-14T16:56:01.288801Z","end":"2026-07-14T16:56:01.869751Z","steps":["trace[1952327255] 'agreement among raft nodes before linearized reading' (duration: 579.88157ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:01.871148Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.288784Z","time spent":"582.337549ms","remote":"127.0.0.1:47670","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":"warn","ts":"2026-07-14T16:56:01.868967Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.272017Z","time spent":"596.938483ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-07-14T16:56:01.868848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.38025Z","time spent":"488.590589ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-07-14T16:56:01.869036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"398.737877ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-14T16:56:01.869061Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"560.781836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-14T16:56:01.871352Z","caller":"traceutil/trace.go:171","msg":"trace[1071984482] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:2712; }","duration":"563.1062ms","start":"2026-07-14T16:56:01.308232Z","end":"2026-07-14T16:56:01.871339Z","steps":["trace[1071984482] 'agreement among raft nodes before linearized reading' (duration: 560.672101ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:01.871417Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.308197Z","time spent":"563.209315ms","remote":"127.0.0.1:47424","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.201\" "} {"level":"info","ts":"2026-07-14T16:56:01.871481Z","caller":"traceutil/trace.go:171","msg":"trace[1156384214] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:2712; }","duration":"401.323253ms","start":"2026-07-14T16:56:01.470142Z","end":"2026-07-14T16:56:01.871465Z","steps":["trace[1156384214] 'agreement among raft nodes before linearized reading' (duration: 398.870573ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:01.871548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.470119Z","time spent":"401.405427ms","remote":"127.0.0.1:44266","response type":"/etcdserverpb.KV/Range","request count":0,"request size":100,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/httproutes/\" range_end:\"/registry/gateway.networking.k8s.io/httproutes0\" count_only:true "} {"level":"info","ts":"2026-07-14T16:56:02.185506Z","caller":"traceutil/trace.go:171","msg":"trace[191938976] transaction","detail":"{read_only:false; response_revision:2713; number_of_response:1; }","duration":"312.278075ms","start":"2026-07-14T16:56:01.873211Z","end":"2026-07-14T16:56:02.185489Z","steps":["trace[191938976] 'process raft request' (duration: 312.17355ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:02.185666Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.873188Z","time spent":"312.422402ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:56:02.199236Z","caller":"traceutil/trace.go:171","msg":"trace[506297503] linearizableReadLoop","detail":"{readStateIndex:2819; appliedIndex:2817; }","duration":"325.35539ms","start":"2026-07-14T16:56:01.873865Z","end":"2026-07-14T16:56:02.199221Z","steps":["trace[506297503] 'read index received' (duration: 311.500377ms)","trace[506297503] 'applied index is now lower than readState.Index' (duration: 13.853923ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:56:02.199458Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"325.583101ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1443"} {"level":"info","ts":"2026-07-14T16:56:02.199537Z","caller":"traceutil/trace.go:171","msg":"trace[2129694755] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2718; }","duration":"325.665125ms","start":"2026-07-14T16:56:01.87386Z","end":"2026-07-14T16:56:02.199525Z","steps":["trace[2129694755] 'agreement among raft nodes before linearized reading' (duration: 325.472716ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:02.199607Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.873826Z","time spent":"325.76979ms","remote":"127.0.0.1:47596","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1466,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"info","ts":"2026-07-14T16:56:02.199824Z","caller":"traceutil/trace.go:171","msg":"trace[1738370008] transaction","detail":"{read_only:false; response_revision:2715; number_of_response:1; }","duration":"321.452629ms","start":"2026-07-14T16:56:01.878266Z","end":"2026-07-14T16:56:02.199718Z","steps":["trace[1738370008] 'process raft request' (duration: 320.789567ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:02.199868Z","caller":"traceutil/trace.go:171","msg":"trace[1914875592] transaction","detail":"{read_only:false; response_revision:2716; number_of_response:1; }","duration":"321.562045ms","start":"2026-07-14T16:56:01.878293Z","end":"2026-07-14T16:56:02.199855Z","steps":["trace[1914875592] 'process raft request' (duration: 320.802038ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:02.199959Z","caller":"traceutil/trace.go:171","msg":"trace[930377854] transaction","detail":"{read_only:false; response_revision:2718; number_of_response:1; }","duration":"309.934196ms","start":"2026-07-14T16:56:01.889978Z","end":"2026-07-14T16:56:02.199912Z","steps":["trace[930377854] 'process raft request' (duration: 309.20472ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:56:02.200069Z","caller":"traceutil/trace.go:171","msg":"trace[1288920237] transaction","detail":"{read_only:false; response_revision:2717; number_of_response:1; }","duration":"317.356239ms","start":"2026-07-14T16:56:01.882664Z","end":"2026-07-14T16:56:02.20002Z","steps":["trace[1288920237] 'process raft request' (duration: 316.484307ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:02.200135Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.889969Z","time spent":"310.072093ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:56:02.200114Z","caller":"traceutil/trace.go:171","msg":"trace[1682378322] transaction","detail":"{read_only:false; response_revision:2714; number_of_response:1; }","duration":"321.919932ms","start":"2026-07-14T16:56:01.878178Z","end":"2026-07-14T16:56:02.200098Z","steps":["trace[1682378322] 'process raft request' (duration: 320.815508ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:02.200255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.878157Z","time spent":"322.063799ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:56:02.20026Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.878257Z","time spent":"321.648988ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:56:02.200013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.878262Z","time spent":"321.708682ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:56:02.200152Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.873577Z","time spent":"326.570299ms","remote":"127.0.0.1:47424","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"warn","ts":"2026-07-14T16:56:02.201091Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:01.882656Z","time spent":"317.491515ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T16:56:07.288444Z","caller":"traceutil/trace.go:171","msg":"trace[372357418] linearizableReadLoop","detail":"{readStateIndex:2848; appliedIndex:2847; }","duration":"327.926842ms","start":"2026-07-14T16:56:06.960496Z","end":"2026-07-14T16:56:07.288423Z","steps":["trace[372357418] 'read index received' (duration: 327.786826ms)","trace[372357418] 'applied index is now lower than readState.Index' (duration: 138.946µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:56:07.288499Z","caller":"traceutil/trace.go:171","msg":"trace[828559071] transaction","detail":"{read_only:false; response_revision:2741; number_of_response:1; }","duration":"331.618717ms","start":"2026-07-14T16:56:06.95685Z","end":"2026-07-14T16:56:07.288468Z","steps":["trace[828559071] 'process raft request' (duration: 331.401057ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:07.289346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.453798ms","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-14T16:56:07.28938Z","caller":"traceutil/trace.go:171","msg":"trace[935824411] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2741; }","duration":"129.50646ms","start":"2026-07-14T16:56:07.159861Z","end":"2026-07-14T16:56:07.289367Z","steps":["trace[935824411] 'agreement among raft nodes before linearized reading' (duration: 129.426326ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:07.289401Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:06.956817Z","time spent":"332.457868ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T16:56:07.289402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.846575ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/backendtlspolicies/\" range_end:\"/registry/gateway.networking.k8s.io/backendtlspolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:56:07.289519Z","caller":"traceutil/trace.go:171","msg":"trace[2091411049] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:2741; }","duration":"197.987132ms","start":"2026-07-14T16:56:07.0915Z","end":"2026-07-14T16:56:07.289487Z","steps":["trace[2091411049] 'agreement among raft nodes before linearized reading' (duration: 197.791652ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:07.288722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"328.205155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-14T16:56:07.289663Z","caller":"traceutil/trace.go:171","msg":"trace[1015139386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2741; }","duration":"329.177231ms","start":"2026-07-14T16:56:06.96048Z","end":"2026-07-14T16:56:07.289657Z","steps":["trace[1015139386] 'agreement among raft nodes before linearized reading' (duration: 328.066538ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:56:07.289687Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:56:06.960467Z","time spent":"329.208603ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-07-14T16:59:40.594394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.934273ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-14T16:59:40.594656Z","caller":"traceutil/trace.go:171","msg":"trace[273639130] transaction","detail":"{read_only:false; response_revision:4322; number_of_response:1; }","duration":"164.381891ms","start":"2026-07-14T16:59:40.43024Z","end":"2026-07-14T16:59:40.594622Z","steps":["trace[273639130] 'process raft request' (duration: 164.249098ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:40.594676Z","caller":"traceutil/trace.go:171","msg":"trace[623739878] transaction","detail":"{read_only:false; response_revision:4321; number_of_response:1; }","duration":"172.909933ms","start":"2026-07-14T16:59:40.421691Z","end":"2026-07-14T16:59:40.594601Z","steps":["trace[623739878] 'process raft request' (duration: 56.691334ms)","trace[623739878] 'compare' (duration: 115.523845ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:40.594972Z","caller":"traceutil/trace.go:171","msg":"trace[513148228] transaction","detail":"{read_only:false; response_revision:4326; number_of_response:1; }","duration":"164.528204ms","start":"2026-07-14T16:59:40.430429Z","end":"2026-07-14T16:59:40.594958Z","steps":["trace[513148228] 'process raft request' (duration: 164.205637ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:40.595089Z","caller":"traceutil/trace.go:171","msg":"trace[1110340830] transaction","detail":"{read_only:false; response_revision:4323; number_of_response:1; }","duration":"164.80995ms","start":"2026-07-14T16:59:40.43027Z","end":"2026-07-14T16:59:40.59508Z","steps":["trace[1110340830] 'process raft request' (duration: 164.285988ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:40.595185Z","caller":"traceutil/trace.go:171","msg":"trace[1757812179] transaction","detail":"{read_only:false; response_revision:4324; number_of_response:1; }","duration":"164.82379ms","start":"2026-07-14T16:59:40.430351Z","end":"2026-07-14T16:59:40.595175Z","steps":["trace[1757812179] 'process raft request' (duration: 164.230967ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:40.59529Z","caller":"traceutil/trace.go:171","msg":"trace[110782986] transaction","detail":"{read_only:false; response_revision:4325; number_of_response:1; }","duration":"164.874401ms","start":"2026-07-14T16:59:40.430404Z","end":"2026-07-14T16:59:40.595278Z","steps":["trace[110782986] 'process raft request' (duration: 164.205657ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:40.595373Z","caller":"traceutil/trace.go:171","msg":"trace[545677370] transaction","detail":"{read_only:false; response_revision:4327; number_of_response:1; }","duration":"158.560126ms","start":"2026-07-14T16:59:40.436805Z","end":"2026-07-14T16:59:40.595365Z","steps":["trace[545677370] 'process raft request' (duration: 157.882521ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:40.594855Z","caller":"traceutil/trace.go:171","msg":"trace[2087079189] transaction","detail":"{read_only:false; response_revision:4328; number_of_response:1; }","duration":"156.047952ms","start":"2026-07-14T16:59:40.438774Z","end":"2026-07-14T16:59:40.594822Z","steps":["trace[2087079189] 'process raft request' (duration: 155.96788ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:40.595459Z","caller":"traceutil/trace.go:171","msg":"trace[1905242789] transaction","detail":"{read_only:false; response_revision:4329; number_of_response:1; }","duration":"106.392288ms","start":"2026-07-14T16:59:40.489047Z","end":"2026-07-14T16:59:40.595439Z","steps":["trace[1905242789] 'process raft request' (duration: 106.00035ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:41.632867Z","caller":"traceutil/trace.go:171","msg":"trace[62303913] linearizableReadLoop","detail":"{readStateIndex:4560; appliedIndex:4559; }","duration":"117.286877ms","start":"2026-07-14T16:59:41.515558Z","end":"2026-07-14T16:59:41.632845Z","steps":["trace[62303913] 'read index received' (duration: 117.160054ms)","trace[62303913] 'applied index is now lower than readState.Index' (duration: 125.893µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:41.632959Z","caller":"traceutil/trace.go:171","msg":"trace[618644366] transaction","detail":"{read_only:false; response_revision:4383; number_of_response:1; }","duration":"128.733082ms","start":"2026-07-14T16:59:41.504217Z","end":"2026-07-14T16:59:41.63295Z","steps":["trace[618644366] 'process raft request' (duration: 128.493587ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:41.633382Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.811267ms","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-14T16:59:41.633436Z","caller":"traceutil/trace.go:171","msg":"trace[4585540] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4383; }","duration":"117.881739ms","start":"2026-07-14T16:59:41.515542Z","end":"2026-07-14T16:59:41.633424Z","steps":["trace[4585540] 'agreement among raft nodes before linearized reading' (duration: 117.671664ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:41.638416Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.136841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:59:41.638484Z","caller":"traceutil/trace.go:171","msg":"trace[1077006000] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:4384; }","duration":"115.257493ms","start":"2026-07-14T16:59:41.523213Z","end":"2026-07-14T16:59:41.63847Z","steps":["trace[1077006000] 'agreement among raft nodes before linearized reading' (duration: 115.1014ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:43.813648Z","caller":"traceutil/trace.go:171","msg":"trace[846819073] transaction","detail":"{read_only:false; response_revision:4430; number_of_response:1; }","duration":"133.662994ms","start":"2026-07-14T16:59:43.67996Z","end":"2026-07-14T16:59:43.813623Z","steps":["trace[846819073] 'process raft request' (duration: 133.45395ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:43.813881Z","caller":"traceutil/trace.go:171","msg":"trace[520225679] transaction","detail":"{read_only:false; response_revision:4431; number_of_response:1; }","duration":"133.857638ms","start":"2026-07-14T16:59:43.680013Z","end":"2026-07-14T16:59:43.81387Z","steps":["trace[520225679] 'process raft request' (duration: 133.549602ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:43.814152Z","caller":"traceutil/trace.go:171","msg":"trace[118802415] transaction","detail":"{read_only:false; response_revision:4433; number_of_response:1; }","duration":"133.641443ms","start":"2026-07-14T16:59:43.680473Z","end":"2026-07-14T16:59:43.814115Z","steps":["trace[118802415] 'process raft request' (duration: 133.551272ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:43.814185Z","caller":"traceutil/trace.go:171","msg":"trace[1001640035] transaction","detail":"{read_only:false; response_revision:4432; number_of_response:1; }","duration":"134.122264ms","start":"2026-07-14T16:59:43.680028Z","end":"2026-07-14T16:59:43.814151Z","steps":["trace[1001640035] 'process raft request' (duration: 133.804037ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:47.797045Z","caller":"traceutil/trace.go:171","msg":"trace[288010318] linearizableReadLoop","detail":"{readStateIndex:4721; appliedIndex:4720; }","duration":"231.089165ms","start":"2026-07-14T16:59:47.565932Z","end":"2026-07-14T16:59:47.797022Z","steps":["trace[288010318] 'read index received' (duration: 230.927062ms)","trace[288010318] 'applied index is now lower than readState.Index' (duration: 160.903µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:47.797349Z","caller":"traceutil/trace.go:171","msg":"trace[562576514] transaction","detail":"{read_only:false; response_revision:4541; number_of_response:1; }","duration":"290.042839ms","start":"2026-07-14T16:59:47.507293Z","end":"2026-07-14T16:59:47.797336Z","steps":["trace[562576514] 'process raft request' (duration: 289.584209ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:47.797712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.754469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5427176a-48df-4963-bedd-e02429cb449e\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-07-14T16:59:47.797757Z","caller":"traceutil/trace.go:171","msg":"trace[713567136] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5427176a-48df-4963-bedd-e02429cb449e; range_end:; response_count:1; response_revision:4541; }","duration":"231.849941ms","start":"2026-07-14T16:59:47.565896Z","end":"2026-07-14T16:59:47.797746Z","steps":["trace[713567136] 'agreement among raft nodes before linearized reading' (duration: 231.658167ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:47.797965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.406579ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5427176a-48df-4963-bedd-e02429cb449e\" ","response":"range_response_count:1 size:3774"} {"level":"info","ts":"2026-07-14T16:59:47.798003Z","caller":"traceutil/trace.go:171","msg":"trace[1616804747] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5427176a-48df-4963-bedd-e02429cb449e; range_end:; response_count:1; response_revision:4541; }","duration":"183.494751ms","start":"2026-07-14T16:59:47.6145Z","end":"2026-07-14T16:59:47.797995Z","steps":["trace[1616804747] 'agreement among raft nodes before linearized reading' (duration: 183.407059ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:47.799107Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.011519ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-07-14T16:59:47.799153Z","caller":"traceutil/trace.go:171","msg":"trace[1403291639] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4541; }","duration":"100.063581ms","start":"2026-07-14T16:59:47.699079Z","end":"2026-07-14T16:59:47.799143Z","steps":["trace[1403291639] 'agreement among raft nodes before linearized reading' (duration: 99.276765ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:48.021338Z","caller":"traceutil/trace.go:171","msg":"trace[651770992] transaction","detail":"{read_only:false; response_revision:4542; number_of_response:1; }","duration":"201.242415ms","start":"2026-07-14T16:59:47.82007Z","end":"2026-07-14T16:59:48.021313Z","steps":["trace[651770992] 'process raft request' (duration: 201.071611ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:48.030714Z","caller":"traceutil/trace.go:171","msg":"trace[311358013] linearizableReadLoop","detail":"{readStateIndex:4723; appliedIndex:4721; }","duration":"200.938409ms","start":"2026-07-14T16:59:47.829758Z","end":"2026-07-14T16:59:48.030696Z","steps":["trace[311358013] 'read index received' (duration: 191.450679ms)","trace[311358013] 'applied index is now lower than readState.Index' (duration: 9.48704ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:48.030839Z","caller":"traceutil/trace.go:171","msg":"trace[1374026286] transaction","detail":"{read_only:false; response_revision:4543; number_of_response:1; }","duration":"210.228794ms","start":"2026-07-14T16:59:47.820579Z","end":"2026-07-14T16:59:48.030808Z","steps":["trace[1374026286] 'process raft request' (duration: 210.03116ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:48.030917Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.139243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-14T16:59:48.030963Z","caller":"traceutil/trace.go:171","msg":"trace[1944120496] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4543; }","duration":"201.219004ms","start":"2026-07-14T16:59:47.829731Z","end":"2026-07-14T16:59:48.03095Z","steps":["trace[1944120496] 'agreement among raft nodes before linearized reading' (duration: 201.061281ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:48.031058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.414387ms","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-14T16:59:48.031131Z","caller":"traceutil/trace.go:171","msg":"trace[1702709430] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4543; }","duration":"200.500559ms","start":"2026-07-14T16:59:47.830617Z","end":"2026-07-14T16:59:48.031118Z","steps":["trace[1702709430] 'agreement among raft nodes before linearized reading' (duration: 200.247954ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:48.031249Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.284374ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T16:59:48.031312Z","caller":"traceutil/trace.go:171","msg":"trace[367798947] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4543; }","duration":"199.365675ms","start":"2026-07-14T16:59:47.831933Z","end":"2026-07-14T16:59:48.031298Z","steps":["trace[367798947] 'agreement among raft nodes before linearized reading' (duration: 199.171731ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:48.031248Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.223812ms","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-14T16:59:48.031548Z","caller":"traceutil/trace.go:171","msg":"trace[997974430] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4543; }","duration":"199.425816ms","start":"2026-07-14T16:59:47.831961Z","end":"2026-07-14T16:59:48.031387Z","steps":["trace[997974430] 'agreement among raft nodes before linearized reading' (duration: 199.1149ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:48.60974Z","caller":"traceutil/trace.go:171","msg":"trace[239994243] linearizableReadLoop","detail":"{readStateIndex:4735; appliedIndex:4734; }","duration":"100.830913ms","start":"2026-07-14T16:59:48.508885Z","end":"2026-07-14T16:59:48.609716Z","steps":["trace[239994243] 'read index received' (duration: 100.597548ms)","trace[239994243] 'applied index is now lower than readState.Index' (duration: 232.215µs)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:59:48.610004Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.105658ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-07-14T16:59:48.610059Z","caller":"traceutil/trace.go:171","msg":"trace[1924537553] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4555; }","duration":"101.230911ms","start":"2026-07-14T16:59:48.508816Z","end":"2026-07-14T16:59:48.610047Z","steps":["trace[1924537553] 'agreement among raft nodes before linearized reading' (duration: 101.002526ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:48.610085Z","caller":"traceutil/trace.go:171","msg":"trace[710956307] transaction","detail":"{read_only:false; response_revision:4555; number_of_response:1; }","duration":"145.950293ms","start":"2026-07-14T16:59:48.464092Z","end":"2026-07-14T16:59:48.610043Z","steps":["trace[710956307] 'process raft request' (duration: 145.461852ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:50.378219Z","caller":"traceutil/trace.go:171","msg":"trace[169376906] linearizableReadLoop","detail":"{readStateIndex:4779; appliedIndex:4778; }","duration":"203.450735ms","start":"2026-07-14T16:59:50.174741Z","end":"2026-07-14T16:59:50.378192Z","steps":["trace[169376906] 'read index received' (duration: 203.299222ms)","trace[169376906] 'applied index is now lower than readState.Index' (duration: 150.163µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:50.378294Z","caller":"traceutil/trace.go:171","msg":"trace[966061831] transaction","detail":"{read_only:false; response_revision:4597; number_of_response:1; }","duration":"210.870251ms","start":"2026-07-14T16:59:50.167392Z","end":"2026-07-14T16:59:50.378262Z","steps":["trace[966061831] 'process raft request' (duration: 210.613136ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:50.378546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.787972ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-qwhnf\" ","response":"range_response_count:1 size:5148"} {"level":"info","ts":"2026-07-14T16:59:50.3786Z","caller":"traceutil/trace.go:171","msg":"trace[1190655260] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-5c97764fc9-qwhnf; range_end:; response_count:1; response_revision:4597; }","duration":"203.877285ms","start":"2026-07-14T16:59:50.174708Z","end":"2026-07-14T16:59:50.378585Z","steps":["trace[1190655260] 'agreement among raft nodes before linearized reading' (duration: 203.67194ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:50.379196Z","caller":"traceutil/trace.go:171","msg":"trace[809592815] transaction","detail":"{read_only:false; response_revision:4600; number_of_response:1; }","duration":"176.00649ms","start":"2026-07-14T16:59:50.203156Z","end":"2026-07-14T16:59:50.379163Z","steps":["trace[809592815] 'process raft request' (duration: 175.947349ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:50.379259Z","caller":"traceutil/trace.go:171","msg":"trace[178137651] transaction","detail":"{read_only:false; response_revision:4599; number_of_response:1; }","duration":"177.857059ms","start":"2026-07-14T16:59:50.201385Z","end":"2026-07-14T16:59:50.379242Z","steps":["trace[178137651] 'process raft request' (duration: 177.690985ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:50.379279Z","caller":"traceutil/trace.go:171","msg":"trace[950674383] transaction","detail":"{read_only:false; response_revision:4598; number_of_response:1; }","duration":"178.682806ms","start":"2026-07-14T16:59:50.200571Z","end":"2026-07-14T16:59:50.379254Z","steps":["trace[950674383] 'process raft request' (duration: 178.347169ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:51.644748Z","caller":"traceutil/trace.go:171","msg":"trace[1646332000] transaction","detail":"{read_only:false; response_revision:4622; number_of_response:1; }","duration":"115.916695ms","start":"2026-07-14T16:59:51.528809Z","end":"2026-07-14T16:59:51.644725Z","steps":["trace[1646332000] 'process raft request' (duration: 115.762852ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:56.027599Z","caller":"traceutil/trace.go:171","msg":"trace[1899453035] transaction","detail":"{read_only:false; response_revision:4726; number_of_response:1; }","duration":"151.027293ms","start":"2026-07-14T16:59:55.876537Z","end":"2026-07-14T16:59:56.027564Z","steps":["trace[1899453035] 'process raft request' (duration: 150.832319ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:56.499282Z","caller":"traceutil/trace.go:171","msg":"trace[1675569163] linearizableReadLoop","detail":"{readStateIndex:4913; appliedIndex:4912; }","duration":"420.778683ms","start":"2026-07-14T16:59:56.07844Z","end":"2026-07-14T16:59:56.499218Z","steps":["trace[1675569163] 'read index received' (duration: 408.987649ms)","trace[1675569163] 'applied index is now lower than readState.Index' (duration: 11.788644ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:56.499305Z","caller":"traceutil/trace.go:171","msg":"trace[1463054955] transaction","detail":"{read_only:false; response_revision:4727; number_of_response:1; }","duration":"429.224778ms","start":"2026-07-14T16:59:56.070049Z","end":"2026-07-14T16:59:56.499274Z","steps":["trace[1463054955] 'process raft request' (duration: 417.370442ms)","trace[1463054955] 'compare' (duration: 11.224883ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:59:56.499492Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.039128ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-07-14T16:59:56.499537Z","caller":"traceutil/trace.go:171","msg":"trace[124570669] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4727; }","duration":"421.145471ms","start":"2026-07-14T16:59:56.078384Z","end":"2026-07-14T16:59:56.499529Z","steps":["trace[124570669] 'agreement among raft nodes before linearized reading' (duration: 420.971967ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:56.499534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:56.070013Z","time spent":"429.415753ms","remote":"127.0.0.1:47670","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-14T16:59:56.499562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:56.078361Z","time spent":"421.195372ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":578,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"info","ts":"2026-07-14T16:59:56.514656Z","caller":"traceutil/trace.go:171","msg":"trace[704309663] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"110.868866ms","start":"2026-07-14T16:59:56.40376Z","end":"2026-07-14T16:59:56.514629Z","steps":["trace[704309663] 'process raft request' (duration: 110.536079ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:56.514742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"336.757885ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T16:59:56.515368Z","caller":"traceutil/trace.go:171","msg":"trace[1723547567] range","detail":"{range_begin:/registry/cert-manager.io/certificaterequests/; range_end:/registry/cert-manager.io/certificaterequests0; response_count:0; response_revision:4728; }","duration":"337.370538ms","start":"2026-07-14T16:59:56.177953Z","end":"2026-07-14T16:59:56.515323Z","steps":["trace[1723547567] 'agreement among raft nodes before linearized reading' (duration: 336.755365ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:56.5155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:56.177942Z","time spent":"337.51006ms","remote":"127.0.0.1:60506","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":8,"response size":30,"request content":"key:\"/registry/cert-manager.io/certificaterequests/\" range_end:\"/registry/cert-manager.io/certificaterequests0\" count_only:true "} {"level":"warn","ts":"2026-07-14T16:59:56.514802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.536176ms","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-14T16:59:56.515762Z","caller":"traceutil/trace.go:171","msg":"trace[1916086512] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4728; }","duration":"107.556078ms","start":"2026-07-14T16:59:56.408193Z","end":"2026-07-14T16:59:56.515749Z","steps":["trace[1916086512] 'agreement among raft nodes before linearized reading' (duration: 106.446455ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:56.51467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.325833ms","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-14T16:59:56.515912Z","caller":"traceutil/trace.go:171","msg":"trace[1490477794] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4728; }","duration":"108.61424ms","start":"2026-07-14T16:59:56.407284Z","end":"2026-07-14T16:59:56.515899Z","steps":["trace[1490477794] 'agreement among raft nodes before linearized reading' (duration: 107.286802ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:56.515186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.502656ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T16:59:56.516022Z","caller":"traceutil/trace.go:171","msg":"trace[632964338] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4728; }","duration":"107.328943ms","start":"2026-07-14T16:59:56.408646Z","end":"2026-07-14T16:59:56.515975Z","steps":["trace[632964338] 'agreement among raft nodes before linearized reading' (duration: 106.455005ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:56.515164Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.288105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-255c075a-f691-419c-8852-4ee3e73650cb\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-07-14T16:59:56.516139Z","caller":"traceutil/trace.go:171","msg":"trace[160438549] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-255c075a-f691-419c-8852-4ee3e73650cb; range_end:; response_count:1; response_revision:4728; }","duration":"351.339806ms","start":"2026-07-14T16:59:56.164779Z","end":"2026-07-14T16:59:56.516095Z","steps":["trace[160438549] 'agreement among raft nodes before linearized reading' (duration: 349.740373ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:56.517072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T16:59:56.164765Z","time spent":"352.285596ms","remote":"127.0.0.1:47584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3669,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-255c075a-f691-419c-8852-4ee3e73650cb\" "} {"level":"info","ts":"2026-07-14T16:59:56.678822Z","caller":"traceutil/trace.go:171","msg":"trace[1113881618] transaction","detail":"{read_only:false; response_revision:4729; number_of_response:1; }","duration":"174.442123ms","start":"2026-07-14T16:59:56.50435Z","end":"2026-07-14T16:59:56.678792Z","steps":["trace[1113881618] 'process raft request' (duration: 139.585651ms)","trace[1113881618] 'compare' (duration: 34.379522ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:56.708791Z","caller":"traceutil/trace.go:171","msg":"trace[1794075539] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"188.78196ms","start":"2026-07-14T16:59:56.519985Z","end":"2026-07-14T16:59:56.708767Z","steps":["trace[1794075539] 'process raft request' (duration: 188.606426ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:56.709136Z","caller":"traceutil/trace.go:171","msg":"trace[192407843] transaction","detail":"{read_only:false; response_revision:4731; number_of_response:1; }","duration":"188.372411ms","start":"2026-07-14T16:59:56.520742Z","end":"2026-07-14T16:59:56.709114Z","steps":["trace[192407843] 'process raft request' (duration: 187.969733ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:56.709474Z","caller":"traceutil/trace.go:171","msg":"trace[1861613252] transaction","detail":"{read_only:false; response_revision:4732; number_of_response:1; }","duration":"187.989903ms","start":"2026-07-14T16:59:56.521471Z","end":"2026-07-14T16:59:56.709461Z","steps":["trace[1861613252] 'process raft request' (duration: 187.531544ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:56.743351Z","caller":"traceutil/trace.go:171","msg":"trace[1441898270] linearizableReadLoop","detail":"{readStateIndex:4919; appliedIndex:4914; }","duration":"103.942742ms","start":"2026-07-14T16:59:56.639385Z","end":"2026-07-14T16:59:56.743328Z","steps":["trace[1441898270] 'read index received' (duration: 4.563924ms)","trace[1441898270] 'applied index is now lower than readState.Index' (duration: 99.377678ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:59:56.743611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.214628ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-14T16:59:56.743668Z","caller":"traceutil/trace.go:171","msg":"trace[902763463] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4732; }","duration":"104.32838ms","start":"2026-07-14T16:59:56.639327Z","end":"2026-07-14T16:59:56.743655Z","steps":["trace[902763463] 'agreement among raft nodes before linearized reading' (duration: 104.153717ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:57.214359Z","caller":"traceutil/trace.go:171","msg":"trace[785479248] transaction","detail":"{read_only:false; response_revision:4740; number_of_response:1; }","duration":"287.819762ms","start":"2026-07-14T16:59:56.926506Z","end":"2026-07-14T16:59:57.214326Z","steps":["trace[785479248] 'process raft request' (duration: 229.197521ms)","trace[785479248] 'compare' (duration: 58.400317ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:57.214562Z","caller":"traceutil/trace.go:171","msg":"trace[145590306] linearizableReadLoop","detail":"{readStateIndex:4928; appliedIndex:4926; }","duration":"225.052313ms","start":"2026-07-14T16:59:56.989478Z","end":"2026-07-14T16:59:57.214531Z","steps":["trace[145590306] 'read index received' (duration: 166.222577ms)","trace[145590306] 'applied index is now lower than readState.Index' (duration: 58.828156ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T16:59:57.214906Z","caller":"traceutil/trace.go:171","msg":"trace[214827370] transaction","detail":"{read_only:false; response_revision:4741; number_of_response:1; }","duration":"284.83769ms","start":"2026-07-14T16:59:56.930053Z","end":"2026-07-14T16:59:57.214891Z","steps":["trace[214827370] 'process raft request' (duration: 284.229737ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T16:59:57.215125Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.624044ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T16:59:57.215173Z","caller":"traceutil/trace.go:171","msg":"trace[1702864815] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4741; }","duration":"225.711476ms","start":"2026-07-14T16:59:56.989447Z","end":"2026-07-14T16:59:57.215158Z","steps":["trace[1702864815] 'agreement among raft nodes before linearized reading' (duration: 225.633024ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:57.405922Z","caller":"traceutil/trace.go:171","msg":"trace[1453376436] linearizableReadLoop","detail":"{readStateIndex:4929; appliedIndex:4928; }","duration":"171.804751ms","start":"2026-07-14T16:59:57.234093Z","end":"2026-07-14T16:59:57.405898Z","steps":["trace[1453376436] 'read index received' (duration: 85.669421ms)","trace[1453376436] 'applied index is now lower than readState.Index' (duration: 86.13341ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T16:59:57.406231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.114027ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9637"} {"level":"info","ts":"2026-07-14T16:59:57.406318Z","caller":"traceutil/trace.go:171","msg":"trace[295091366] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4743; }","duration":"172.22625ms","start":"2026-07-14T16:59:57.234074Z","end":"2026-07-14T16:59:57.4063Z","steps":["trace[295091366] 'agreement among raft nodes before linearized reading' (duration: 171.931664ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T16:59:57.406503Z","caller":"traceutil/trace.go:171","msg":"trace[1122390960] transaction","detail":"{read_only:false; response_revision:4742; number_of_response:1; }","duration":"174.239271ms","start":"2026-07-14T16:59:57.231584Z","end":"2026-07-14T16:59:57.405823Z","steps":["trace[1122390960] 'process raft request' (duration: 88.215793ms)","trace[1122390960] 'compare' (duration: 85.856825ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:00:06.137105Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"235.451107ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/daemonsets/\" range_end:\"/registry/daemonsets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:00:06.137199Z","caller":"traceutil/trace.go:171","msg":"trace[1494640778] range","detail":"{range_begin:/registry/daemonsets/; range_end:/registry/daemonsets0; response_count:0; response_revision:4871; }","duration":"235.565179ms","start":"2026-07-14T17:00:05.90161Z","end":"2026-07-14T17:00:06.137175Z","steps":["trace[1494640778] 'count revisions from in-memory index tree' (duration: 235.337784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:06.137291Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.502617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-14T17:00:06.137375Z","caller":"traceutil/trace.go:171","msg":"trace[59347967] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4871; }","duration":"206.600479ms","start":"2026-07-14T17:00:05.93074Z","end":"2026-07-14T17:00:06.13734Z","steps":["trace[59347967] 'range keys from in-memory index tree' (duration: 206.311003ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:06.13704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.137221ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:00:06.137594Z","caller":"traceutil/trace.go:171","msg":"trace[2074525116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4871; }","duration":"150.696402ms","start":"2026-07-14T17:00:05.986862Z","end":"2026-07-14T17:00:06.137558Z","steps":["trace[2074525116] 'range keys from in-memory index tree' (duration: 150.053298ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:06.137018Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.191115ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-07-14T17:00:06.138096Z","caller":"traceutil/trace.go:171","msg":"trace[307224334] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:4871; }","duration":"162.319298ms","start":"2026-07-14T17:00:05.975742Z","end":"2026-07-14T17:00:06.138061Z","steps":["trace[307224334] 'count revisions from in-memory index tree' (duration: 161.102934ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:10.105565Z","caller":"traceutil/trace.go:171","msg":"trace[1663964723] linearizableReadLoop","detail":"{readStateIndex:5092; appliedIndex:5091; }","duration":"215.626741ms","start":"2026-07-14T17:00:09.889854Z","end":"2026-07-14T17:00:10.105481Z","steps":["trace[1663964723] 'read index received' (duration: 215.173062ms)","trace[1663964723] 'applied index is now lower than readState.Index' (duration: 452.219µs)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:00:10.105641Z","caller":"traceutil/trace.go:171","msg":"trace[33995069] transaction","detail":"{read_only:false; response_revision:4901; number_of_response:1; }","duration":"236.235558ms","start":"2026-07-14T17:00:09.86938Z","end":"2026-07-14T17:00:10.105616Z","steps":["trace[33995069] 'process raft request' (duration: 235.710397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:10.105983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.959438ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:00:10.106043Z","caller":"traceutil/trace.go:171","msg":"trace[1193289515] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4901; }","duration":"116.03141ms","start":"2026-07-14T17:00:09.989997Z","end":"2026-07-14T17:00:10.106029Z","steps":["trace[1193289515] 'agreement among raft nodes before linearized reading' (duration: 115.935868ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:10.106085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.121971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3243"} {"level":"info","ts":"2026-07-14T17:00:10.106164Z","caller":"traceutil/trace.go:171","msg":"trace[1130685621] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4901; }","duration":"216.320775ms","start":"2026-07-14T17:00:09.8898Z","end":"2026-07-14T17:00:10.106121Z","steps":["trace[1130685621] 'agreement among raft nodes before linearized reading' (duration: 216.025539ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:10.106542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.347124ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-14T17:00:10.106599Z","caller":"traceutil/trace.go:171","msg":"trace[1254826096] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4901; }","duration":"206.427616ms","start":"2026-07-14T17:00:09.900155Z","end":"2026-07-14T17:00:10.106582Z","steps":["trace[1254826096] 'agreement among raft nodes before linearized reading' (duration: 205.696821ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:10.616623Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":5639526399683164136,"retry-timeout":"500ms"} {"level":"info","ts":"2026-07-14T17:00:10.760372Z","caller":"traceutil/trace.go:171","msg":"trace[589262455] transaction","detail":"{read_only:false; response_revision:4902; number_of_response:1; }","duration":"645.338639ms","start":"2026-07-14T17:00:10.115011Z","end":"2026-07-14T17:00:10.76035Z","steps":["trace[589262455] 'process raft request' (duration: 645.188446ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:10.760514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:10.114996Z","time spent":"645.440651ms","remote":"127.0.0.1:47566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":950,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:00:10.933172Z","caller":"traceutil/trace.go:171","msg":"trace[1813627658] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"816.715183ms","start":"2026-07-14T17:00:10.116421Z","end":"2026-07-14T17:00:10.933136Z","steps":["trace[1813627658] 'process raft request' (duration: 816.530509ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:10.933221Z","caller":"traceutil/trace.go:171","msg":"trace[1864773252] transaction","detail":"{read_only:false; response_revision:4905; number_of_response:1; }","duration":"816.493379ms","start":"2026-07-14T17:00:10.116688Z","end":"2026-07-14T17:00:10.933181Z","steps":["trace[1864773252] 'process raft request' (duration: 816.329155ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:10.933421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:10.116413Z","time spent":"816.866276ms","remote":"127.0.0.1:47686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1505,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:00:10.933454Z","caller":"traceutil/trace.go:171","msg":"trace[1443223385] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"769.293648ms","start":"2026-07-14T17:00:10.164135Z","end":"2026-07-14T17:00:10.933428Z","steps":["trace[1443223385] 'process raft request' (duration: 768.953451ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:10.933537Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:10.116669Z","time spent":"816.657081ms","remote":"127.0.0.1:47566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:00:10.933606Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:10.164117Z","time spent":"769.396259ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:00:10.933624Z","caller":"traceutil/trace.go:171","msg":"trace[527457959] linearizableReadLoop","detail":"{readStateIndex:5098; appliedIndex:5093; }","duration":"817.850256ms","start":"2026-07-14T17:00:10.115747Z","end":"2026-07-14T17:00:10.933598Z","steps":["trace[527457959] 'read index received' (duration: 644.685575ms)","trace[527457959] 'applied index is now lower than readState.Index' (duration: 173.163131ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:00:10.933858Z","caller":"traceutil/trace.go:171","msg":"trace[1483262845] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"370.817295ms","start":"2026-07-14T17:00:10.563026Z","end":"2026-07-14T17:00:10.933843Z","steps":["trace[1483262845] 'process raft request' (duration: 370.326235ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:10.933973Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:10.562993Z","time spent":"370.902957ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:00:10.934162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.323004ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-07-14T17:00:10.934214Z","caller":"traceutil/trace.go:171","msg":"trace[233820522] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4907; }","duration":"110.475327ms","start":"2026-07-14T17:00:10.823727Z","end":"2026-07-14T17:00:10.934203Z","steps":["trace[233820522] 'agreement among raft nodes before linearized reading' (duration: 110.328514ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:10.934264Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.567366ms","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":"warn","ts":"2026-07-14T17:00:10.934433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"368.288335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-07-14T17:00:10.934448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.554975ms","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-14T17:00:10.934445Z","caller":"traceutil/trace.go:171","msg":"trace[1539668198] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4907; }","duration":"155.7657ms","start":"2026-07-14T17:00:10.778636Z","end":"2026-07-14T17:00:10.934402Z","steps":["trace[1539668198] 'agreement among raft nodes before linearized reading' (duration: 155.350052ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:10.934492Z","caller":"traceutil/trace.go:171","msg":"trace[78061777] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4907; }","duration":"153.615247ms","start":"2026-07-14T17:00:10.780865Z","end":"2026-07-14T17:00:10.93448Z","steps":["trace[78061777] 'agreement among raft nodes before linearized reading' (duration: 153.522445ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:10.934497Z","caller":"traceutil/trace.go:171","msg":"trace[1761831375] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4907; }","duration":"368.391127ms","start":"2026-07-14T17:00:10.566089Z","end":"2026-07-14T17:00:10.93448Z","steps":["trace[1761831375] 'agreement among raft nodes before linearized reading' (duration: 368.256964ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:10.934542Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:10.56606Z","time spent":"368.470548ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-07-14T17:00:10.934523Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.418688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-07-14T17:00:10.934663Z","caller":"traceutil/trace.go:171","msg":"trace[1617438473] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4907; }","duration":"185.555611ms","start":"2026-07-14T17:00:10.749036Z","end":"2026-07-14T17:00:10.934591Z","steps":["trace[1617438473] 'agreement among raft nodes before linearized reading' (duration: 185.282955ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:10.934313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.73931ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-07-14T17:00:10.934786Z","caller":"traceutil/trace.go:171","msg":"trace[558556425] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4907; }","duration":"161.269321ms","start":"2026-07-14T17:00:10.773505Z","end":"2026-07-14T17:00:10.934774Z","steps":["trace[558556425] 'agreement among raft nodes before linearized reading' (duration: 160.692179ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:10.934359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"818.607191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-255c075a-f691-419c-8852-4ee3e73650cb\" ","response":"range_response_count:1 size:1322"} {"level":"info","ts":"2026-07-14T17:00:10.934882Z","caller":"traceutil/trace.go:171","msg":"trace[1244597586] range","detail":"{range_begin:/registry/persistentvolumes/pvc-255c075a-f691-419c-8852-4ee3e73650cb; range_end:; response_count:1; response_revision:4907; }","duration":"819.132191ms","start":"2026-07-14T17:00:10.115731Z","end":"2026-07-14T17:00:10.934863Z","steps":["trace[1244597586] 'agreement among raft nodes before linearized reading' (duration: 818.55984ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:10.934733Z","caller":"traceutil/trace.go:171","msg":"trace[1872186513] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"817.57051ms","start":"2026-07-14T17:00:10.115506Z","end":"2026-07-14T17:00:10.933077Z","steps":["trace[1872186513] 'process raft request' (duration: 777.168586ms)","trace[1872186513] 'compare' (duration: 39.885114ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:00:10.934935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:10.115724Z","time spent":"819.194273ms","remote":"127.0.0.1:47544","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":1345,"request content":"key:\"/registry/persistentvolumes/pvc-255c075a-f691-419c-8852-4ee3e73650cb\" "} {"level":"warn","ts":"2026-07-14T17:00:10.934159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"767.350318ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-9jdl8\" ","response":"range_response_count:1 size:9769"} {"level":"info","ts":"2026-07-14T17:00:10.935003Z","caller":"traceutil/trace.go:171","msg":"trace[784594296] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-9jdl8; range_end:; response_count:1; response_revision:4907; }","duration":"768.228056ms","start":"2026-07-14T17:00:10.166757Z","end":"2026-07-14T17:00:10.934985Z","steps":["trace[784594296] 'agreement among raft nodes before linearized reading' (duration: 767.191445ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:10.935063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:10.166738Z","time spent":"768.303667ms","remote":"127.0.0.1:47584","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":9792,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-prometheus-node-exporter-9jdl8\" "} {"level":"warn","ts":"2026-07-14T17:00:10.937031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:10.115497Z","time spent":"821.376347ms","remote":"127.0.0.1:47686","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1428,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:00:11.248117Z","caller":"traceutil/trace.go:171","msg":"trace[607822560] transaction","detail":"{read_only:false; response_revision:4919; number_of_response:1; }","duration":"221.563859ms","start":"2026-07-14T17:00:11.026536Z","end":"2026-07-14T17:00:11.248099Z","steps":["trace[607822560] 'process raft request' (duration: 221.522848ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:11.248327Z","caller":"traceutil/trace.go:171","msg":"trace[856912940] transaction","detail":"{read_only:false; response_revision:4917; number_of_response:1; }","duration":"224.280813ms","start":"2026-07-14T17:00:11.02404Z","end":"2026-07-14T17:00:11.24832Z","steps":["trace[856912940] 'process raft request' (duration: 223.922496ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:11.248435Z","caller":"traceutil/trace.go:171","msg":"trace[230108734] transaction","detail":"{read_only:false; response_revision:4916; number_of_response:1; }","duration":"228.332664ms","start":"2026-07-14T17:00:11.020081Z","end":"2026-07-14T17:00:11.248413Z","steps":["trace[230108734] 'process raft request' (duration: 195.3487ms)","trace[230108734] 'compare' (duration: 32.264749ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:00:11.248519Z","caller":"traceutil/trace.go:171","msg":"trace[1459691064] transaction","detail":"{read_only:false; response_revision:4918; number_of_response:1; }","duration":"222.812023ms","start":"2026-07-14T17:00:11.0257Z","end":"2026-07-14T17:00:11.248512Z","steps":["trace[1459691064] 'process raft request' (duration: 222.317863ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:11.575139Z","caller":"traceutil/trace.go:171","msg":"trace[699185508] transaction","detail":"{read_only:false; response_revision:4921; number_of_response:1; }","duration":"312.716213ms","start":"2026-07-14T17:00:11.262374Z","end":"2026-07-14T17:00:11.57509Z","steps":["trace[699185508] 'process raft request' (duration: 260.930321ms)","trace[699185508] 'compare' (duration: 51.308762ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:00:11.575178Z","caller":"traceutil/trace.go:171","msg":"trace[397121086] transaction","detail":"{read_only:false; response_revision:4922; number_of_response:1; }","duration":"312.61663ms","start":"2026-07-14T17:00:11.262544Z","end":"2026-07-14T17:00:11.57516Z","steps":["trace[397121086] 'process raft request' (duration: 312.441806ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:11.575311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:11.262539Z","time spent":"312.693991ms","remote":"127.0.0.1:47884","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10497,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:00:11.575364Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:11.262355Z","time spent":"312.878895ms","remote":"127.0.0.1:47566","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":992,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:00:11.57543Z","caller":"traceutil/trace.go:171","msg":"trace[886906074] transaction","detail":"{read_only:false; response_revision:4923; number_of_response:1; }","duration":"311.883556ms","start":"2026-07-14T17:00:11.263522Z","end":"2026-07-14T17:00:11.575405Z","steps":["trace[886906074] 'process raft request' (duration: 311.58419ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:11.576285Z","caller":"traceutil/trace.go:171","msg":"trace[1094726490] linearizableReadLoop","detail":"{readStateIndex:5115; appliedIndex:5111; }","duration":"247.857758ms","start":"2026-07-14T17:00:11.328245Z","end":"2026-07-14T17:00:11.576103Z","steps":["trace[1094726490] 'read index received' (duration: 195.021175ms)","trace[1094726490] 'applied index is now lower than readState.Index' (duration: 52.834943ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:00:11.576566Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.310427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.201\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-07-14T17:00:11.57666Z","caller":"traceutil/trace.go:171","msg":"trace[1233399219] range","detail":"{range_begin:/registry/masterleases/199.19.213.201; range_end:; response_count:1; response_revision:4924; }","duration":"248.45653ms","start":"2026-07-14T17:00:11.328189Z","end":"2026-07-14T17:00:11.576645Z","steps":["trace[1233399219] 'agreement among raft nodes before linearized reading' (duration: 248.128413ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:00:11.577471Z","caller":"traceutil/trace.go:171","msg":"trace[380240849] transaction","detail":"{read_only:false; response_revision:4924; number_of_response:1; }","duration":"307.797554ms","start":"2026-07-14T17:00:11.268401Z","end":"2026-07-14T17:00:11.576199Z","steps":["trace[380240849] 'process raft request' (duration: 306.937297ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:00:11.577622Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:11.268395Z","time spent":"309.138731ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-14T17:00:11.578799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-14T17:00:11.263516Z","time spent":"311.985368ms","remote":"127.0.0.1:47670","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-14T17:00:11.706107Z","caller":"traceutil/trace.go:171","msg":"trace[736682784] transaction","detail":"{read_only:false; response_revision:4925; number_of_response:1; }","duration":"117.436603ms","start":"2026-07-14T17:00:11.588638Z","end":"2026-07-14T17:00:11.706075Z","steps":["trace[736682784] 'process raft request' (duration: 86.862228ms)","trace[736682784] 'compare' (duration: 29.932092ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:01.402807Z","caller":"traceutil/trace.go:171","msg":"trace[1105252874] transaction","detail":"{read_only:false; response_revision:5314; number_of_response:1; }","duration":"172.395695ms","start":"2026-07-14T17:01:01.230392Z","end":"2026-07-14T17:01:01.402788Z","steps":["trace[1105252874] 'process raft request' (duration: 172.009218ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:01.53184Z","caller":"traceutil/trace.go:171","msg":"trace[247391147] transaction","detail":"{read_only:false; response_revision:5321; number_of_response:1; }","duration":"105.169315ms","start":"2026-07-14T17:01:01.426624Z","end":"2026-07-14T17:01:01.531793Z","steps":["trace[247391147] 'process raft request' (duration: 59.298152ms)","trace[247391147] 'compare' (duration: 45.67177ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:01.906696Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.619291ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:01:01.906873Z","caller":"traceutil/trace.go:171","msg":"trace[1800700665] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:5321; }","duration":"171.830935ms","start":"2026-07-14T17:01:01.735019Z","end":"2026-07-14T17:01:01.90685Z","steps":["trace[1800700665] 'range keys from in-memory index tree' (duration: 171.593751ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:01.907428Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.705886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/httproutefilters/\" range_end:\"/registry/gateway.envoyproxy.io/httproutefilters0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:01:01.907521Z","caller":"traceutil/trace.go:171","msg":"trace[1416322733] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/httproutefilters/; range_end:/registry/gateway.envoyproxy.io/httproutefilters0; response_count:0; response_revision:5321; }","duration":"184.146594ms","start":"2026-07-14T17:01:01.723353Z","end":"2026-07-14T17:01:01.907499Z","steps":["trace[1416322733] 'count revisions from in-memory index tree' (duration: 183.541423ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:01.90766Z","caller":"traceutil/trace.go:171","msg":"trace[2048367247] transaction","detail":"{read_only:false; response_revision:5322; number_of_response:1; }","duration":"149.136573ms","start":"2026-07-14T17:01:01.758427Z","end":"2026-07-14T17:01:01.907563Z","steps":["trace[2048367247] 'process raft request' (duration: 138.342873ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:01.914286Z","caller":"traceutil/trace.go:171","msg":"trace[981923649] transaction","detail":"{read_only:false; response_revision:5323; number_of_response:1; }","duration":"149.298726ms","start":"2026-07-14T17:01:01.764962Z","end":"2026-07-14T17:01:01.91426Z","steps":["trace[981923649] 'process raft request' (duration: 149.105222ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:01.914514Z","caller":"traceutil/trace.go:171","msg":"trace[1513394075] transaction","detail":"{read_only:false; response_revision:5324; number_of_response:1; }","duration":"147.756177ms","start":"2026-07-14T17:01:01.766742Z","end":"2026-07-14T17:01:01.914498Z","steps":["trace[1513394075] 'process raft request' (duration: 147.462412ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:02.550263Z","caller":"traceutil/trace.go:171","msg":"trace[710970450] transaction","detail":"{read_only:false; response_revision:5325; number_of_response:1; }","duration":"117.109504ms","start":"2026-07-14T17:01:02.433084Z","end":"2026-07-14T17:01:02.550194Z","steps":["trace[710970450] 'process raft request' (duration: 116.970771ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:15.84398Z","caller":"traceutil/trace.go:171","msg":"trace[422169754] transaction","detail":"{read_only:false; response_revision:5414; number_of_response:1; }","duration":"116.451277ms","start":"2026-07-14T17:01:15.727497Z","end":"2026-07-14T17:01:15.843948Z","steps":["trace[422169754] 'process raft request' (duration: 62.248947ms)","trace[422169754] 'compare' (duration: 53.906364ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:15.843952Z","caller":"traceutil/trace.go:171","msg":"trace[1256570056] transaction","detail":"{read_only:false; response_revision:5415; number_of_response:1; }","duration":"116.196992ms","start":"2026-07-14T17:01:15.727725Z","end":"2026-07-14T17:01:15.843922Z","steps":["trace[1256570056] 'process raft request' (duration: 116.123601ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:16.077348Z","caller":"traceutil/trace.go:171","msg":"trace[2043623172] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"208.763091ms","start":"2026-07-14T17:01:15.868505Z","end":"2026-07-14T17:01:16.077268Z","steps":["trace[2043623172] 'process raft request' (duration: 189.654683ms)","trace[2043623172] 'compare' (duration: 17.57996ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:01:16.081049Z","caller":"traceutil/trace.go:171","msg":"trace[1934546034] linearizableReadLoop","detail":"{readStateIndex:5622; appliedIndex:5621; }","duration":"130.699605ms","start":"2026-07-14T17:01:15.950294Z","end":"2026-07-14T17:01:16.080994Z","steps":["trace[1934546034] 'read index received' (duration: 107.854658ms)","trace[1934546034] 'applied index is now lower than readState.Index' (duration: 22.843247ms)"],"step_count":2} {"level":"warn","ts":"2026-07-14T17:01:16.081974Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.662583ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:01:16.082055Z","caller":"traceutil/trace.go:171","msg":"trace[451327674] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5418; }","duration":"131.787185ms","start":"2026-07-14T17:01:15.950235Z","end":"2026-07-14T17:01:16.082022Z","steps":["trace[451327674] 'agreement among raft nodes before linearized reading' (duration: 131.664843ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:01:16.082318Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.639012ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-14T17:01:16.082379Z","caller":"traceutil/trace.go:171","msg":"trace[1450258019] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5418; }","duration":"131.716284ms","start":"2026-07-14T17:01:15.950631Z","end":"2026-07-14T17:01:16.082348Z","steps":["trace[1450258019] 'agreement among raft nodes before linearized reading' (duration: 131.652733ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:01:38.380333Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2903} {"level":"info","ts":"2026-07-14T17:01:38.466219Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2903,"took":"84.022314ms","hash":1668879774,"current-db-size-bytes":52776960,"current-db-size":"53 MB","current-db-size-in-use-bytes":29192192,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-07-14T17:01:38.466279Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1668879774,"revision":2903,"compact-revision":-1} {"level":"info","ts":"2026-07-14T17:03:50.737538Z","caller":"traceutil/trace.go:171","msg":"trace[701100495] transaction","detail":"{read_only:false; response_revision:6512; number_of_response:1; }","duration":"108.27578ms","start":"2026-07-14T17:03:50.629246Z","end":"2026-07-14T17:03:50.737522Z","steps":["trace[701100495] 'process raft request' (duration: 108.176088ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:03:50.739206Z","caller":"traceutil/trace.go:171","msg":"trace[1541107657] linearizableReadLoop","detail":"{readStateIndex:6756; appliedIndex:6755; }","duration":"107.435317ms","start":"2026-07-14T17:03:50.631746Z","end":"2026-07-14T17:03:50.739181Z","steps":["trace[1541107657] 'read index received' (duration: 106.168396ms)","trace[1541107657] 'applied index is now lower than readState.Index' (duration: 1.265701ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:03:50.739322Z","caller":"traceutil/trace.go:171","msg":"trace[624481271] transaction","detail":"{read_only:false; response_revision:6513; number_of_response:1; }","duration":"109.572841ms","start":"2026-07-14T17:03:50.629738Z","end":"2026-07-14T17:03:50.739311Z","steps":["trace[624481271] 'process raft request' (duration: 109.290066ms)"],"step_count":1} {"level":"warn","ts":"2026-07-14T17:03:50.73943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.670071ms","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-14T17:03:50.739458Z","caller":"traceutil/trace.go:171","msg":"trace[1205842643] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:6513; }","duration":"107.710781ms","start":"2026-07-14T17:03:50.631741Z","end":"2026-07-14T17:03:50.739451Z","steps":["trace[1205842643] 'agreement among raft nodes before linearized reading' (duration: 107.65408ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:04:06.975158Z","caller":"traceutil/trace.go:171","msg":"trace[47516823] transaction","detail":"{read_only:false; response_revision:6656; number_of_response:1; }","duration":"116.049445ms","start":"2026-07-14T17:04:06.859078Z","end":"2026-07-14T17:04:06.975127Z","steps":["trace[47516823] 'process raft request' (duration: 31.696432ms)","trace[47516823] 'compare' (duration: 83.897856ms)"],"step_count":2} {"level":"info","ts":"2026-07-14T17:04:06.975188Z","caller":"traceutil/trace.go:171","msg":"trace[1426039434] transaction","detail":"{read_only:false; response_revision:6657; number_of_response:1; }","duration":"116.095766ms","start":"2026-07-14T17:04:06.859073Z","end":"2026-07-14T17:04:06.975169Z","steps":["trace[1426039434] 'process raft request' (duration: 116.003554ms)"],"step_count":1} {"level":"info","ts":"2026-07-14T17:06:38.386441Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5594} {"level":"info","ts":"2026-07-14T17:06:38.472214Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5594,"took":"84.01426ms","hash":2158127945,"current-db-size-bytes":52776960,"current-db-size":"53 MB","current-db-size-in-use-bytes":18178048,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-14T17:06:38.472302Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2158127945,"revision":5594,"compact-revision":2903} {"level":"info","ts":"2026-07-14T17:11:38.391953Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7444} {"level":"info","ts":"2026-07-14T17:11:38.447523Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7444,"took":"53.65052ms","hash":1143639450,"current-db-size-bytes":52776960,"current-db-size":"53 MB","current-db-size-in-use-bytes":14917632,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-07-14T17:11:38.447582Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1143639450,"revision":7444,"compact-revision":5594}