{"level":"warn","ts":"2026-06-09T16:51:12.252472Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T16:51:12.252582Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.95: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.204.45.95:2380","--initial-cluster=instance=https://199.204.45.95:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.95:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.95: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-06-09T16:51:12.252659Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-06-09T16:51:12.252677Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-06-09T16:51:12.252701Z","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-06-09T16:51:12.253563Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"]} {"level":"info","ts":"2026-06-09T16:51:12.253656Z","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":16,"max-cpu-available":16,"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.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.95: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-06-09T16:51:12.256932Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.973947ms"} {"level":"info","ts":"2026-06-09T16:51:12.26315Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"be773dfc027af3eb","cluster-id":"9ba5fbd7288fd5af"} {"level":"info","ts":"2026-06-09T16:51:12.263219Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=()"} {"level":"info","ts":"2026-06-09T16:51:12.263242Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 0"} {"level":"info","ts":"2026-06-09T16:51:12.263256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft be773dfc027af3eb [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-06-09T16:51:12.263268Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became follower at term 1"} {"level":"info","ts":"2026-06-09T16:51:12.263312Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"warn","ts":"2026-06-09T16:51:12.272797Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-06-09T16:51:12.274355Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-06-09T16:51:12.275468Z","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-06-09T16:51:12.277786Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"be773dfc027af3eb","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-06-09T16:51:12.278368Z","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-06-09T16:51:12.2785Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"be773dfc027af3eb","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-06-09T16:51:12.27857Z","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-06-09T16:51:12.2786Z","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-06-09T16:51:12.279488Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb switched to configuration voters=(13724506542017541099)"} {"level":"info","ts":"2026-06-09T16:51:12.281315Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","added-peer-id":"be773dfc027af3eb","added-peer-peer-urls":["https://199.204.45.95:2380"]} {"level":"info","ts":"2026-06-09T16:51:12.282922Z","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-06-09T16:51:12.283172Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"be773dfc027af3eb","initial-advertise-peer-urls":["https://199.204.45.95:2380"],"listen-peer-urls":["https://199.204.45.95:2380"],"advertise-client-urls":["https://199.204.45.95:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.95:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-06-09T16:51:12.283199Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-06-09T16:51:12.283823Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-06-09T16:51:12.283854Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.95:2380"} {"level":"info","ts":"2026-06-09T16:51:12.863477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb is starting a new election at term 1"} {"level":"info","ts":"2026-06-09T16:51:12.863577Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became pre-candidate at term 1"} {"level":"info","ts":"2026-06-09T16:51:12.863606Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgPreVoteResp from be773dfc027af3eb at term 1"} {"level":"info","ts":"2026-06-09T16:51:12.863628Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became candidate at term 2"} {"level":"info","ts":"2026-06-09T16:51:12.86364Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb received MsgVoteResp from be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-06-09T16:51:12.863659Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"be773dfc027af3eb became leader at term 2"} {"level":"info","ts":"2026-06-09T16:51:12.863674Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: be773dfc027af3eb elected leader be773dfc027af3eb at term 2"} {"level":"info","ts":"2026-06-09T16:51:12.865205Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"be773dfc027af3eb","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.95:2379]}","request-path":"/0/members/be773dfc027af3eb/attributes","cluster-id":"9ba5fbd7288fd5af","publish-timeout":"7s"} {"level":"info","ts":"2026-06-09T16:51:12.865206Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T16:51:12.86519Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T16:51:12.865273Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-06-09T16:51:12.865609Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-06-09T16:51:12.866005Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-06-09T16:51:12.866339Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"9ba5fbd7288fd5af","local-member-id":"be773dfc027af3eb","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T16:51:12.866456Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T16:51:12.866495Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-06-09T16:51:12.868446Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-06-09T16:51:12.86856Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.95:2379"} {"level":"info","ts":"2026-06-09T16:51:56.299098Z","caller":"traceutil/trace.go:171","msg":"trace[2063686387] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"167.71471ms","start":"2026-06-09T16:51:56.131354Z","end":"2026-06-09T16:51:56.299069Z","steps":["trace[2063686387] 'process raft request' (duration: 167.114722ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:51:56.480554Z","caller":"traceutil/trace.go:171","msg":"trace[1593280815] linearizableReadLoop","detail":"{readStateIndex:661; appliedIndex:660; }","duration":"114.429404ms","start":"2026-06-09T16:51:56.366097Z","end":"2026-06-09T16:51:56.480526Z","steps":["trace[1593280815] 'read index received' (duration: 82.565396ms)","trace[1593280815] 'applied index is now lower than readState.Index' (duration: 31.863198ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T16:51:56.480708Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.591758ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T16:51:56.480785Z","caller":"traceutil/trace.go:171","msg":"trace[561703727] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"114.728442ms","start":"2026-06-09T16:51:56.366046Z","end":"2026-06-09T16:51:56.480774Z","steps":["trace[561703727] 'agreement among raft nodes before linearized reading' (duration: 114.582258ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:51:56.480802Z","caller":"traceutil/trace.go:171","msg":"trace[506865570] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"116.579987ms","start":"2026-06-09T16:51:56.36414Z","end":"2026-06-09T16:51:56.48072Z","steps":["trace[506865570] 'process raft request' (duration: 84.622877ms)","trace[506865570] 'compare' (duration: 31.625701ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T16:51:57.024429Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"319.006627ms","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-06-09T16:51:57.024533Z","caller":"traceutil/trace.go:171","msg":"trace[1615102077] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:637; }","duration":"319.143272ms","start":"2026-06-09T16:51:56.705367Z","end":"2026-06-09T16:51:57.02451Z","steps":["trace[1615102077] 'range keys from in-memory index tree' (duration: 318.819542ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:51:57.024583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T16:51:56.705358Z","time spent":"319.215444ms","remote":"127.0.0.1:50622","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-06-09T16:51:57.024904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.790502ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-mfxwf\" ","response":"range_response_count:1 size:16961"} {"level":"warn","ts":"2026-06-09T16:51:57.024945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"369.845621ms","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-06-09T16:51:57.024979Z","caller":"traceutil/trace.go:171","msg":"trace[502210317] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:637; }","duration":"369.922104ms","start":"2026-06-09T16:51:56.655048Z","end":"2026-06-09T16:51:57.02497Z","steps":["trace[502210317] 'range keys from in-memory index tree' (duration: 369.728948ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:51:57.025783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T16:51:56.655026Z","time spent":"370.722637ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T16:51:57.025834Z","caller":"traceutil/trace.go:171","msg":"trace[1488361736] range","detail":"{range_begin:/registry/pods/kube-system/cilium-mfxwf; range_end:; response_count:1; response_revision:637; }","duration":"316.896215ms","start":"2026-06-09T16:51:56.708066Z","end":"2026-06-09T16:51:57.024963Z","steps":["trace[1488361736] 'range keys from in-memory index tree' (duration: 316.325279ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:51:57.026507Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T16:51:56.70806Z","time spent":"317.843652ms","remote":"127.0.0.1:50562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16985,"request content":"key:\"/registry/pods/kube-system/cilium-mfxwf\" "} {"level":"info","ts":"2026-06-09T16:51:57.028542Z","caller":"traceutil/trace.go:171","msg":"trace[205734641] transaction","detail":"{read_only:false; response_revision:638; number_of_response:1; }","duration":"135.939276ms","start":"2026-06-09T16:51:56.892562Z","end":"2026-06-09T16:51:57.028502Z","steps":["trace[205734641] 'process raft request' (duration: 129.773815ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:52:12.629771Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.483034ms","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-06-09T16:52:12.629862Z","caller":"traceutil/trace.go:171","msg":"trace[704461927] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:800; }","duration":"231.629938ms","start":"2026-06-09T16:52:12.398211Z","end":"2026-06-09T16:52:12.629841Z","steps":["trace[704461927] 'range keys from in-memory index tree' (duration: 231.297388ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:53:26.821917Z","caller":"traceutil/trace.go:171","msg":"trace[523856521] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"109.819715ms","start":"2026-06-09T16:53:26.712071Z","end":"2026-06-09T16:53:26.821891Z","steps":["trace[523856521] 'process raft request' (duration: 109.63291ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:53:27.876933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"508.011162ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T16:53:27.87701Z","caller":"traceutil/trace.go:171","msg":"trace[2008456206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1873; }","duration":"508.136305ms","start":"2026-06-09T16:53:27.368855Z","end":"2026-06-09T16:53:27.876991Z","steps":["trace[2008456206] 'range keys from in-memory index tree' (duration: 507.918489ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:53:27.877043Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T16:53:27.368831Z","time spent":"508.205017ms","remote":"127.0.0.1:50332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T16:53:27.877026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"366.607549ms","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-06-09T16:53:27.877164Z","caller":"traceutil/trace.go:171","msg":"trace[935551820] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1873; }","duration":"366.820845ms","start":"2026-06-09T16:53:27.510309Z","end":"2026-06-09T16:53:27.877129Z","steps":["trace[935551820] 'range keys from in-memory index tree' (duration: 366.397863ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:53:27.877224Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T16:53:27.510291Z","time spent":"366.920228ms","remote":"127.0.0.1:50622","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-06-09T16:53:27.87729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.896511ms","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-06-09T16:53:27.877422Z","caller":"traceutil/trace.go:171","msg":"trace[1983652109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1873; }","duration":"361.077547ms","start":"2026-06-09T16:53:27.516315Z","end":"2026-06-09T16:53:27.877393Z","steps":["trace[1983652109] 'range keys from in-memory index tree' (duration: 360.732237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:53:27.877474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T16:53:27.516305Z","time spent":"361.153919ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-06-09T16:53:27.878277Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.639682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T16:53:27.878378Z","caller":"traceutil/trace.go:171","msg":"trace[1718493307] linearizableReadLoop","detail":"{readStateIndex:1945; appliedIndex:1944; }","duration":"208.484414ms","start":"2026-06-09T16:53:27.669879Z","end":"2026-06-09T16:53:27.878364Z","steps":["trace[1718493307] 'read index received' (duration: 5.734159ms)","trace[1718493307] 'applied index is now lower than readState.Index' (duration: 202.748965ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T16:53:27.878424Z","caller":"traceutil/trace.go:171","msg":"trace[1844915182] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"334.088613ms","start":"2026-06-09T16:53:27.544315Z","end":"2026-06-09T16:53:27.878404Z","steps":["trace[1844915182] 'process raft request' (duration: 131.332748ms)","trace[1844915182] 'compare' (duration: 201.20687ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T16:53:27.878539Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.66321ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-e9b04273-43c0-4a88-80ca-c584f87e699a\" ","response":"range_response_count:1 size:3743"} {"level":"warn","ts":"2026-06-09T16:53:27.878571Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T16:53:27.54428Z","time spent":"334.200567ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T16:53:27.87858Z","caller":"traceutil/trace.go:171","msg":"trace[1240259691] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-e9b04273-43c0-4a88-80ca-c584f87e699a; range_end:; response_count:1; response_revision:1874; }","duration":"208.815464ms","start":"2026-06-09T16:53:27.669753Z","end":"2026-06-09T16:53:27.878568Z","steps":["trace[1240259691] 'agreement among raft nodes before linearized reading' (duration: 208.677239ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:53:39.423327Z","caller":"traceutil/trace.go:171","msg":"trace[1192898054] transaction","detail":"{read_only:false; response_revision:1975; number_of_response:1; }","duration":"137.428946ms","start":"2026-06-09T16:53:39.285852Z","end":"2026-06-09T16:53:39.423281Z","steps":["trace[1192898054] 'process raft request' (duration: 118.03316ms)","trace[1192898054] 'compare' (duration: 18.908491ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T16:53:42.470497Z","caller":"traceutil/trace.go:171","msg":"trace[1402948479] linearizableReadLoop","detail":"{readStateIndex:2075; appliedIndex:2074; }","duration":"101.442916ms","start":"2026-06-09T16:53:42.369033Z","end":"2026-06-09T16:53:42.470476Z","steps":["trace[1402948479] 'read index received' (duration: 101.297232ms)","trace[1402948479] 'applied index is now lower than readState.Index' (duration: 144.754µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T16:53:42.470531Z","caller":"traceutil/trace.go:171","msg":"trace[2124489788] transaction","detail":"{read_only:false; response_revision:2001; number_of_response:1; }","duration":"120.958905ms","start":"2026-06-09T16:53:42.349544Z","end":"2026-06-09T16:53:42.470503Z","steps":["trace[2124489788] 'process raft request' (duration: 120.76963ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:53:42.470763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.55955ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T16:53:42.470801Z","caller":"traceutil/trace.go:171","msg":"trace[259366931] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2001; }","duration":"101.793617ms","start":"2026-06-09T16:53:42.368999Z","end":"2026-06-09T16:53:42.470793Z","steps":["trace[259366931] 'agreement among raft nodes before linearized reading' (duration: 101.55902ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:54:21.938063Z","caller":"traceutil/trace.go:171","msg":"trace[288732351] transaction","detail":"{read_only:false; response_revision:2217; number_of_response:1; }","duration":"281.264808ms","start":"2026-06-09T16:54:21.656756Z","end":"2026-06-09T16:54:21.938021Z","steps":["trace[288732351] 'process raft request' (duration: 271.40358ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:54:22.263299Z","caller":"traceutil/trace.go:171","msg":"trace[2122066815] linearizableReadLoop","detail":"{readStateIndex:2301; appliedIndex:2300; }","duration":"115.877066ms","start":"2026-06-09T16:54:22.147389Z","end":"2026-06-09T16:54:22.263267Z","steps":["trace[2122066815] 'read index received' (duration: 115.609549ms)","trace[2122066815] 'applied index is now lower than readState.Index' (duration: 266.277µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T16:54:22.263407Z","caller":"traceutil/trace.go:171","msg":"trace[939086769] transaction","detail":"{read_only:false; response_revision:2218; number_of_response:1; }","duration":"127.465305ms","start":"2026-06-09T16:54:22.135909Z","end":"2026-06-09T16:54:22.263374Z","steps":["trace[939086769] 'process raft request' (duration: 127.164796ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:54:22.26368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.835218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-06-09T16:54:22.26368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.275609ms","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-06-09T16:54:22.263733Z","caller":"traceutil/trace.go:171","msg":"trace[1333070685] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2218; }","duration":"113.952032ms","start":"2026-06-09T16:54:22.149769Z","end":"2026-06-09T16:54:22.263721Z","steps":["trace[1333070685] 'agreement among raft nodes before linearized reading' (duration: 113.693634ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:54:22.263742Z","caller":"traceutil/trace.go:171","msg":"trace[23429319] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2218; }","duration":"116.388552ms","start":"2026-06-09T16:54:22.147339Z","end":"2026-06-09T16:54:22.263728Z","steps":["trace[23429319] 'agreement among raft nodes before linearized reading' (duration: 116.046822ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:54:56.627854Z","caller":"traceutil/trace.go:171","msg":"trace[519215270] linearizableReadLoop","detail":"{readStateIndex:2676; appliedIndex:2675; }","duration":"114.766557ms","start":"2026-06-09T16:54:56.513059Z","end":"2026-06-09T16:54:56.627826Z","steps":["trace[519215270] 'read index received' (duration: 114.586822ms)","trace[519215270] 'applied index is now lower than readState.Index' (duration: 178.695µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T16:54:56.627942Z","caller":"traceutil/trace.go:171","msg":"trace[976850931] transaction","detail":"{read_only:false; response_revision:2579; number_of_response:1; }","duration":"158.451572ms","start":"2026-06-09T16:54:56.469452Z","end":"2026-06-09T16:54:56.627904Z","steps":["trace[976850931] 'process raft request' (duration: 158.198274ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:54:56.62809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.997284ms","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-06-09T16:54:56.628092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.024654ms","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-06-09T16:54:56.62815Z","caller":"traceutil/trace.go:171","msg":"trace[1113430571] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2579; }","duration":"115.113247ms","start":"2026-06-09T16:54:56.513016Z","end":"2026-06-09T16:54:56.62813Z","steps":["trace[1113430571] 'agreement among raft nodes before linearized reading' (duration: 114.975553ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:54:56.628164Z","caller":"traceutil/trace.go:171","msg":"trace[1761105942] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2579; }","duration":"115.105017ms","start":"2026-06-09T16:54:56.513042Z","end":"2026-06-09T16:54:56.628147Z","steps":["trace[1761105942] 'agreement among raft nodes before linearized reading' (duration: 114.897421ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:58:06.566729Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.424647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T16:58:06.56689Z","caller":"traceutil/trace.go:171","msg":"trace[1860968422] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3809; }","duration":"199.652515ms","start":"2026-06-09T16:58:06.367218Z","end":"2026-06-09T16:58:06.56687Z","steps":["trace[1860968422] 'range keys from in-memory index tree' (duration: 199.304975ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:59:33.932633Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.033679ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T16:59:33.93299Z","caller":"traceutil/trace.go:171","msg":"trace[216808223] linearizableReadLoop","detail":"{readStateIndex:5192; appliedIndex:5188; }","duration":"205.01033ms","start":"2026-06-09T16:59:33.727961Z","end":"2026-06-09T16:59:33.932971Z","steps":["trace[216808223] 'read index received' (duration: 102.713µs)","trace[216808223] 'applied index is now lower than readState.Index' (duration: 204.906497ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T16:59:33.93298Z","caller":"traceutil/trace.go:171","msg":"trace[1985200835] transaction","detail":"{read_only:false; response_revision:5001; number_of_response:1; }","duration":"285.500585ms","start":"2026-06-09T16:59:33.647435Z","end":"2026-06-09T16:59:33.932936Z","steps":["trace[1985200835] 'process raft request' (duration: 33.061835ms)","trace[1985200835] 'compare' (duration: 251.849504ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T16:59:33.933081Z","caller":"traceutil/trace.go:171","msg":"trace[48057239] transaction","detail":"{read_only:false; response_revision:5003; number_of_response:1; }","duration":"281.333824ms","start":"2026-06-09T16:59:33.65174Z","end":"2026-06-09T16:59:33.933073Z","steps":["trace[48057239] 'process raft request' (duration: 281.139019ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:59:33.933205Z","caller":"traceutil/trace.go:171","msg":"trace[1603419097] transaction","detail":"{read_only:false; response_revision:5002; number_of_response:1; }","duration":"284.30613ms","start":"2026-06-09T16:59:33.648848Z","end":"2026-06-09T16:59:33.933155Z","steps":["trace[1603419097] 'process raft request' (duration: 283.915799ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:59:33.933208Z","caller":"traceutil/trace.go:171","msg":"trace[856364734] transaction","detail":"{read_only:false; response_revision:5004; number_of_response:1; }","duration":"281.20112ms","start":"2026-06-09T16:59:33.651975Z","end":"2026-06-09T16:59:33.933176Z","steps":["trace[856364734] 'process raft request' (duration: 280.950803ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:59:33.933677Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.753463ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:25 size:786825"} {"level":"info","ts":"2026-06-09T16:59:33.933752Z","caller":"traceutil/trace.go:171","msg":"trace[1567526717] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:25; response_revision:5004; }","duration":"205.840505ms","start":"2026-06-09T16:59:33.727895Z","end":"2026-06-09T16:59:33.933735Z","steps":["trace[1567526717] 'agreement among raft nodes before linearized reading' (duration: 205.182925ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:59:34.465308Z","caller":"traceutil/trace.go:171","msg":"trace[714168596] transaction","detail":"{read_only:false; response_revision:5011; number_of_response:1; }","duration":"105.768034ms","start":"2026-06-09T16:59:34.359513Z","end":"2026-06-09T16:59:34.465281Z","steps":["trace[714168596] 'process raft request' (duration: 105.405633ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:59:45.506224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.422187ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T16:59:45.506437Z","caller":"traceutil/trace.go:171","msg":"trace[1967474425] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:5209; }","duration":"192.683274ms","start":"2026-06-09T16:59:45.313732Z","end":"2026-06-09T16:59:45.506416Z","steps":["trace[1967474425] 'count revisions from in-memory index tree' (duration: 192.336155ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:59:45.506558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.122558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T16:59:45.506627Z","caller":"traceutil/trace.go:171","msg":"trace[1616225948] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5209; }","duration":"139.248131ms","start":"2026-06-09T16:59:45.367363Z","end":"2026-06-09T16:59:45.506611Z","steps":["trace[1616225948] 'range keys from in-memory index tree' (duration: 139.050675ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:59:45.507186Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.103427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:9 size:380031"} {"level":"info","ts":"2026-06-09T16:59:45.507276Z","caller":"traceutil/trace.go:171","msg":"trace[827315413] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:9; response_revision:5209; }","duration":"157.23241ms","start":"2026-06-09T16:59:45.350024Z","end":"2026-06-09T16:59:45.507256Z","steps":["trace[827315413] 'range keys from in-memory index tree' (duration: 156.709435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:59:47.690688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.641083ms","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-06-09T16:59:47.691098Z","caller":"traceutil/trace.go:171","msg":"trace[1322194896] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5255; }","duration":"103.103557ms","start":"2026-06-09T16:59:47.58797Z","end":"2026-06-09T16:59:47.691073Z","steps":["trace[1322194896] 'range keys from in-memory index tree' (duration: 102.383395ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:59:48.027734Z","caller":"traceutil/trace.go:171","msg":"trace[2047106467] transaction","detail":"{read_only:false; response_revision:5257; number_of_response:1; }","duration":"245.715836ms","start":"2026-06-09T16:59:47.78198Z","end":"2026-06-09T16:59:48.027696Z","steps":["trace[2047106467] 'process raft request' (duration: 185.893798ms)","trace[2047106467] 'compare' (duration: 59.383575ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T16:59:55.405653Z","caller":"traceutil/trace.go:171","msg":"trace[2124306418] linearizableReadLoop","detail":"{readStateIndex:5617; appliedIndex:5616; }","duration":"136.180072ms","start":"2026-06-09T16:59:55.269444Z","end":"2026-06-09T16:59:55.405624Z","steps":["trace[2124306418] 'read index received' (duration: 135.903704ms)","trace[2124306418] 'applied index is now lower than readState.Index' (duration: 274.778µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T16:59:55.405674Z","caller":"traceutil/trace.go:171","msg":"trace[641720216] transaction","detail":"{read_only:false; response_revision:5415; number_of_response:1; }","duration":"152.229215ms","start":"2026-06-09T16:59:55.253429Z","end":"2026-06-09T16:59:55.405658Z","steps":["trace[641720216] 'process raft request' (duration: 151.983768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:59:55.405904Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.424349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/loki-0\" ","response":"range_response_count:1 size:5834"} {"level":"info","ts":"2026-06-09T16:59:55.406044Z","caller":"traceutil/trace.go:171","msg":"trace[1646709235] range","detail":"{range_begin:/registry/pods/monitoring/loki-0; range_end:; response_count:1; response_revision:5415; }","duration":"136.614685ms","start":"2026-06-09T16:59:55.269408Z","end":"2026-06-09T16:59:55.406023Z","steps":["trace[1646709235] 'agreement among raft nodes before linearized reading' (duration: 136.293265ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:59:55.542692Z","caller":"traceutil/trace.go:171","msg":"trace[359840855] transaction","detail":"{read_only:false; response_revision:5416; number_of_response:1; }","duration":"125.928067ms","start":"2026-06-09T16:59:55.416743Z","end":"2026-06-09T16:59:55.542671Z","steps":["trace[359840855] 'process raft request' (duration: 122.693333ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:59:55.696053Z","caller":"traceutil/trace.go:171","msg":"trace[1596269974] linearizableReadLoop","detail":"{readStateIndex:5620; appliedIndex:5618; }","duration":"146.802578ms","start":"2026-06-09T16:59:55.549226Z","end":"2026-06-09T16:59:55.696029Z","steps":["trace[1596269974] 'read index received' (duration: 34.380392ms)","trace[1596269974] 'applied index is now lower than readState.Index' (duration: 112.421376ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T16:59:55.69628Z","caller":"traceutil/trace.go:171","msg":"trace[566674419] transaction","detail":"{read_only:false; response_revision:5418; number_of_response:1; }","duration":"147.297433ms","start":"2026-06-09T16:59:55.548954Z","end":"2026-06-09T16:59:55.696252Z","steps":["trace[566674419] 'process raft request' (duration: 111.984124ms)","trace[566674419] 'compare' (duration: 34.711052ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T16:59:55.696293Z","caller":"traceutil/trace.go:171","msg":"trace[151207017] transaction","detail":"{read_only:false; response_revision:5420; number_of_response:1; }","duration":"146.366766ms","start":"2026-06-09T16:59:55.549857Z","end":"2026-06-09T16:59:55.696224Z","steps":["trace[151207017] 'process raft request' (duration: 145.984075ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:59:55.696382Z","caller":"traceutil/trace.go:171","msg":"trace[1638889182] transaction","detail":"{read_only:false; response_revision:5421; number_of_response:1; }","duration":"145.377088ms","start":"2026-06-09T16:59:55.55098Z","end":"2026-06-09T16:59:55.696357Z","steps":["trace[1638889182] 'process raft request' (duration: 144.947335ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:59:55.696411Z","caller":"traceutil/trace.go:171","msg":"trace[182638566] transaction","detail":"{read_only:false; response_revision:5419; number_of_response:1; }","duration":"146.926453ms","start":"2026-06-09T16:59:55.549428Z","end":"2026-06-09T16:59:55.696355Z","steps":["trace[182638566] 'process raft request' (duration: 146.362397ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T16:59:55.696481Z","caller":"traceutil/trace.go:171","msg":"trace[309590011] transaction","detail":"{read_only:false; response_revision:5422; number_of_response:1; }","duration":"145.221274ms","start":"2026-06-09T16:59:55.551245Z","end":"2026-06-09T16:59:55.696467Z","steps":["trace[309590011] 'process raft request' (duration: 144.73857ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T16:59:55.696395Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.142409ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5afde1dd-1634-4ba6-9395-022b52b205bf\" ","response":"range_response_count:1 size:3645"} {"level":"info","ts":"2026-06-09T16:59:55.696579Z","caller":"traceutil/trace.go:171","msg":"trace[732270382] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5afde1dd-1634-4ba6-9395-022b52b205bf; range_end:; response_count:1; response_revision:5422; }","duration":"147.362124ms","start":"2026-06-09T16:59:55.549195Z","end":"2026-06-09T16:59:55.696557Z","steps":["trace[732270382] 'agreement among raft nodes before linearized reading' (duration: 146.957763ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:01:12.898386Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3015} {"level":"info","ts":"2026-06-09T17:01:12.989644Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3015,"took":"89.563179ms","hash":2040372075,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":32047104,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-09T17:01:12.989725Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2040372075,"revision":3015,"compact-revision":-1} {"level":"info","ts":"2026-06-09T17:06:06.848081Z","caller":"traceutil/trace.go:171","msg":"trace[1970344456] transaction","detail":"{read_only:false; response_revision:8283; number_of_response:1; }","duration":"178.233847ms","start":"2026-06-09T17:06:06.669821Z","end":"2026-06-09T17:06:06.848055Z","steps":["trace[1970344456] 'process raft request' (duration: 178.047461ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:07.128939Z","caller":"traceutil/trace.go:171","msg":"trace[1057972827] linearizableReadLoop","detail":"{readStateIndex:8577; appliedIndex:8575; }","duration":"341.03833ms","start":"2026-06-09T17:06:06.787866Z","end":"2026-06-09T17:06:07.128904Z","steps":["trace[1057972827] 'read index received' (duration: 60.049794ms)","trace[1057972827] 'applied index is now lower than readState.Index' (duration: 280.987506ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:07.129014Z","caller":"traceutil/trace.go:171","msg":"trace[1169388567] transaction","detail":"{read_only:false; response_revision:8284; number_of_response:1; }","duration":"456.521614ms","start":"2026-06-09T17:06:06.672453Z","end":"2026-06-09T17:06:07.128974Z","steps":["trace[1169388567] 'process raft request' (duration: 456.222115ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:07.129223Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.347138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pzjn9\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-06-09T17:06:07.129292Z","caller":"traceutil/trace.go:171","msg":"trace[349807982] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pzjn9; range_end:; response_count:1; response_revision:8284; }","duration":"341.477361ms","start":"2026-06-09T17:06:06.7878Z","end":"2026-06-09T17:06:07.129278Z","steps":["trace[349807982] 'agreement among raft nodes before linearized reading' (duration: 341.260455ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:07.129321Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:06:06.787775Z","time spent":"341.541283ms","remote":"127.0.0.1:50562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":43,"response count":1,"response size":6471,"request content":"key:\"/registry/pods/openstack/keepalived-pzjn9\" "} {"level":"warn","ts":"2026-06-09T17:06:07.129289Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:06:06.672431Z","time spent":"456.72571ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:06:12.904645Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6122} {"level":"info","ts":"2026-06-09T17:06:13.018907Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6122,"took":"112.230772ms","hash":3677435332,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":23789568,"current-db-size-in-use":"24 MB"} {"level":"info","ts":"2026-06-09T17:06:13.019018Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3677435332,"revision":6122,"compact-revision":3015} {"level":"info","ts":"2026-06-09T17:06:19.038736Z","caller":"traceutil/trace.go:171","msg":"trace[2134030420] linearizableReadLoop","detail":"{readStateIndex:8641; appliedIndex:8640; }","duration":"133.529306ms","start":"2026-06-09T17:06:18.905178Z","end":"2026-06-09T17:06:19.038708Z","steps":["trace[2134030420] 'read index received' (duration: 133.210207ms)","trace[2134030420] 'applied index is now lower than readState.Index' (duration: 318.469µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:19.038864Z","caller":"traceutil/trace.go:171","msg":"trace[1957851459] transaction","detail":"{read_only:false; response_revision:8345; number_of_response:1; }","duration":"146.995805ms","start":"2026-06-09T17:06:18.891839Z","end":"2026-06-09T17:06:19.038835Z","steps":["trace[1957851459] 'process raft request' (duration: 146.544262ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:19.038944Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.732682ms","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-06-09T17:06:19.038969Z","caller":"traceutil/trace.go:171","msg":"trace[480880304] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8345; }","duration":"133.801573ms","start":"2026-06-09T17:06:18.90516Z","end":"2026-06-09T17:06:19.038961Z","steps":["trace[480880304] 'agreement among raft nodes before linearized reading' (duration: 133.664139ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:19.994417Z","caller":"traceutil/trace.go:171","msg":"trace[1148312780] linearizableReadLoop","detail":"{readStateIndex:8646; appliedIndex:8645; }","duration":"113.776346ms","start":"2026-06-09T17:06:19.880612Z","end":"2026-06-09T17:06:19.994388Z","steps":["trace[1148312780] 'read index received' (duration: 113.655302ms)","trace[1148312780] 'applied index is now lower than readState.Index' (duration: 119.644µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:06:19.994463Z","caller":"traceutil/trace.go:171","msg":"trace[952474226] transaction","detail":"{read_only:false; response_revision:8350; number_of_response:1; }","duration":"240.163235ms","start":"2026-06-09T17:06:19.754264Z","end":"2026-06-09T17:06:19.994427Z","steps":["trace[952474226] 'process raft request' (duration: 239.913878ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:19.994614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.979931ms","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-06-09T17:06:19.994663Z","caller":"traceutil/trace.go:171","msg":"trace[1538681497] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/backendtlspolicies/; range_end:/registry/gateway.networking.k8s.io/backendtlspolicies0; response_count:0; response_revision:8350; }","duration":"114.131475ms","start":"2026-06-09T17:06:19.880517Z","end":"2026-06-09T17:06:19.994648Z","steps":["trace[1538681497] 'agreement among raft nodes before linearized reading' (duration: 114.023342ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:20.016762Z","caller":"traceutil/trace.go:171","msg":"trace[1919304470] transaction","detail":"{read_only:false; response_revision:8351; number_of_response:1; }","duration":"109.089061ms","start":"2026-06-09T17:06:19.907642Z","end":"2026-06-09T17:06:20.016731Z","steps":["trace[1919304470] 'process raft request' (duration: 108.879185ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:48.379065Z","caller":"traceutil/trace.go:171","msg":"trace[1934104536] transaction","detail":"{read_only:false; response_revision:8554; number_of_response:1; }","duration":"266.050453ms","start":"2026-06-09T17:06:48.11299Z","end":"2026-06-09T17:06:48.37904Z","steps":["trace[1934104536] 'process raft request' (duration: 265.913899ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:48.379613Z","caller":"traceutil/trace.go:171","msg":"trace[2060817015] linearizableReadLoop","detail":"{readStateIndex:8858; appliedIndex:8858; }","duration":"254.83971ms","start":"2026-06-09T17:06:48.12476Z","end":"2026-06-09T17:06:48.3796Z","steps":["trace[2060817015] 'read index received' (duration: 254.83418ms)","trace[2060817015] 'applied index is now lower than readState.Index' (duration: 4.66µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:06:48.379765Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.988244ms","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-06-09T17:06:48.379796Z","caller":"traceutil/trace.go:171","msg":"trace[2119975509] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8554; }","duration":"255.072146ms","start":"2026-06-09T17:06:48.124714Z","end":"2026-06-09T17:06:48.379786Z","steps":["trace[2119975509] 'agreement among raft nodes before linearized reading' (duration: 254.951013ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:48.444062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.458779ms","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-06-09T17:06:48.444151Z","caller":"traceutil/trace.go:171","msg":"trace[1534764921] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8555; }","duration":"182.586202ms","start":"2026-06-09T17:06:48.261546Z","end":"2026-06-09T17:06:48.444132Z","steps":["trace[1534764921] 'agreement among raft nodes before linearized reading' (duration: 182.333555ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:48.444279Z","caller":"traceutil/trace.go:171","msg":"trace[74666624] transaction","detail":"{read_only:false; response_revision:8555; number_of_response:1; }","duration":"146.787739ms","start":"2026-06-09T17:06:48.297463Z","end":"2026-06-09T17:06:48.444251Z","steps":["trace[74666624] 'process raft request' (duration: 143.954617ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:52.643082Z","caller":"traceutil/trace.go:171","msg":"trace[1433518710] transaction","detail":"{read_only:false; response_revision:8578; number_of_response:1; }","duration":"151.876885ms","start":"2026-06-09T17:06:52.491179Z","end":"2026-06-09T17:06:52.643056Z","steps":["trace[1433518710] 'process raft request' (duration: 151.713881ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:52.66011Z","caller":"traceutil/trace.go:171","msg":"trace[1092011661] transaction","detail":"{read_only:false; response_revision:8579; number_of_response:1; }","duration":"165.52824ms","start":"2026-06-09T17:06:52.494518Z","end":"2026-06-09T17:06:52.660046Z","steps":["trace[1092011661] 'process raft request' (duration: 165.263702ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:52.660312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.138384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:06:52.660379Z","caller":"traceutil/trace.go:171","msg":"trace[32173593] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:8579; }","duration":"143.288868ms","start":"2026-06-09T17:06:52.517075Z","end":"2026-06-09T17:06:52.660364Z","steps":["trace[32173593] 'agreement among raft nodes before linearized reading' (duration: 143.177585ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:06:52.660201Z","caller":"traceutil/trace.go:171","msg":"trace[315245589] linearizableReadLoop","detail":"{readStateIndex:8884; appliedIndex:8882; }","duration":"142.786423ms","start":"2026-06-09T17:06:52.517156Z","end":"2026-06-09T17:06:52.659943Z","steps":["trace[315245589] 'read index received' (duration: 125.757191ms)","trace[315245589] 'applied index is now lower than readState.Index' (duration: 17.028122ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:06:52.660887Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.746033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"info","ts":"2026-06-09T17:06:52.660923Z","caller":"traceutil/trace.go:171","msg":"trace[1125095547] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8579; }","duration":"132.811715ms","start":"2026-06-09T17:06:52.5281Z","end":"2026-06-09T17:06:52.660912Z","steps":["trace[1125095547] 'agreement among raft nodes before linearized reading' (duration: 132.678211ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:06:52.660984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.938067ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-06-09T17:06:52.66104Z","caller":"traceutil/trace.go:171","msg":"trace[1924137204] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8579; }","duration":"117.009949ms","start":"2026-06-09T17:06:52.544014Z","end":"2026-06-09T17:06:52.661024Z","steps":["trace[1924137204] 'agreement among raft nodes before linearized reading' (duration: 116.806153ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:02.057619Z","caller":"traceutil/trace.go:171","msg":"trace[67482123] linearizableReadLoop","detail":"{readStateIndex:8983; appliedIndex:8982; }","duration":"139.607511ms","start":"2026-06-09T17:07:01.917987Z","end":"2026-06-09T17:07:02.057594Z","steps":["trace[67482123] 'read index received' (duration: 139.390125ms)","trace[67482123] 'applied index is now lower than readState.Index' (duration: 216.166µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:07:02.057684Z","caller":"traceutil/trace.go:171","msg":"trace[2032564470] transaction","detail":"{read_only:false; response_revision:8673; number_of_response:1; }","duration":"194.425494ms","start":"2026-06-09T17:07:01.863234Z","end":"2026-06-09T17:07:02.057659Z","steps":["trace[2032564470] 'process raft request' (duration: 194.126435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:07:02.057789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.787337ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-06-09T17:07:02.057824Z","caller":"traceutil/trace.go:171","msg":"trace[811041882] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:8673; }","duration":"139.929411ms","start":"2026-06-09T17:07:01.917885Z","end":"2026-06-09T17:07:02.057814Z","steps":["trace[811041882] 'agreement among raft nodes before linearized reading' (duration: 139.791036ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:07:04.688414Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-000000000000232e.wal"} {"level":"info","ts":"2026-06-09T17:08:51.980382Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"be773dfc027af3eb","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T17:08:51.994673Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-06-09T17:08:51.994812Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-06-09T17:11:12.91242Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8313} {"level":"info","ts":"2026-06-09T17:11:12.998747Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8313,"took":"84.564213ms","hash":821867534,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":25022464,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-06-09T17:11:12.998858Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":821867534,"revision":8313,"compact-revision":6122} {"level":"info","ts":"2026-06-09T17:14:04.030469Z","caller":"traceutil/trace.go:171","msg":"trace[365519672] linearizableReadLoop","detail":"{readStateIndex:12305; appliedIndex:12304; }","duration":"114.356531ms","start":"2026-06-09T17:14:03.916083Z","end":"2026-06-09T17:14:04.03044Z","steps":["trace[365519672] 'read index received' (duration: 101.529211ms)","trace[365519672] 'applied index is now lower than readState.Index' (duration: 12.82596ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:14:04.030472Z","caller":"traceutil/trace.go:171","msg":"trace[999654429] transaction","detail":"{read_only:false; response_revision:11893; number_of_response:1; }","duration":"185.131314ms","start":"2026-06-09T17:14:03.84531Z","end":"2026-06-09T17:14:04.030441Z","steps":["trace[999654429] 'process raft request' (duration: 172.421388ms)","trace[999654429] 'compare' (duration: 12.297464ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:14:04.030628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.524246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-06-09T17:14:04.030662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.509836ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:14:04.03069Z","caller":"traceutil/trace.go:171","msg":"trace[932263610] range","detail":"{range_begin:/registry/jobs/openstack/cinder-rabbit-init; range_end:; response_count:0; response_revision:11893; }","duration":"114.641299ms","start":"2026-06-09T17:14:03.916034Z","end":"2026-06-09T17:14:04.030675Z","steps":["trace[932263610] 'agreement among raft nodes before linearized reading' (duration: 114.516036ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:14:04.030696Z","caller":"traceutil/trace.go:171","msg":"trace[989631996] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-user; range_end:; response_count:0; response_revision:11893; }","duration":"114.589218ms","start":"2026-06-09T17:14:03.916096Z","end":"2026-06-09T17:14:04.030685Z","steps":["trace[989631996] 'agreement among raft nodes before linearized reading' (duration: 114.528626ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:14:04.030822Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.820605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-db-sync\" ","response":"range_response_count:1 size:5004"} {"level":"info","ts":"2026-06-09T17:14:04.030918Z","caller":"traceutil/trace.go:171","msg":"trace[638215051] range","detail":"{range_begin:/registry/jobs/openstack/cinder-db-sync; range_end:; response_count:1; response_revision:11893; }","duration":"113.933517ms","start":"2026-06-09T17:14:03.916957Z","end":"2026-06-09T17:14:04.030891Z","steps":["trace[638215051] 'agreement among raft nodes before linearized reading' (duration: 113.701251ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:14:04.03121Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.814994ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-storage-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:14:04.031286Z","caller":"traceutil/trace.go:171","msg":"trace[144806773] range","detail":"{range_begin:/registry/jobs/openstack/cinder-storage-init; range_end:; response_count:0; response_revision:11893; }","duration":"114.911037ms","start":"2026-06-09T17:14:03.916356Z","end":"2026-06-09T17:14:04.031267Z","steps":["trace[144806773] 'agreement among raft nodes before linearized reading' (duration: 114.778673ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:14:04.031363Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.919357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/cinder-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-06-09T17:14:04.0315Z","caller":"traceutil/trace.go:171","msg":"trace[2100161950] range","detail":"{range_begin:/registry/services/endpoints/openstack/cinder-api; range_end:; response_count:1; response_revision:11893; }","duration":"115.086752ms","start":"2026-06-09T17:14:03.916381Z","end":"2026-06-09T17:14:04.031467Z","steps":["trace[2100161950] 'agreement among raft nodes before linearized reading' (duration: 114.648768ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:14:04.031577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.873445ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/cinder-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:14:04.031632Z","caller":"traceutil/trace.go:171","msg":"trace[667582292] range","detail":"{range_begin:/registry/jobs/openstack/cinder-ks-endpoints; range_end:; response_count:0; response_revision:11893; }","duration":"114.993169ms","start":"2026-06-09T17:14:03.916629Z","end":"2026-06-09T17:14:04.031622Z","steps":["trace[667582292] 'agreement among raft nodes before linearized reading' (duration: 114.236277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:14:04.739604Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.318063ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-06-09T17:14:04.739734Z","caller":"traceutil/trace.go:171","msg":"trace[1127393179] linearizableReadLoop","detail":"{readStateIndex:12307; appliedIndex:12306; }","duration":"370.424811ms","start":"2026-06-09T17:14:04.36929Z","end":"2026-06-09T17:14:04.739715Z","steps":["trace[1127393179] 'read index received' (duration: 56.81924ms)","trace[1127393179] 'applied index is now lower than readState.Index' (duration: 313.604111ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:14:04.739965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"370.672589ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:14:04.74005Z","caller":"traceutil/trace.go:171","msg":"trace[927780850] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:11895; }","duration":"370.811293ms","start":"2026-06-09T17:14:04.369216Z","end":"2026-06-09T17:14:04.740027Z","steps":["trace[927780850] 'agreement among raft nodes before linearized reading' (duration: 370.656049ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:14:04.740074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.894991ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:530"} {"level":"warn","ts":"2026-06-09T17:14:04.740109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:14:04.369186Z","time spent":"370.909576ms","remote":"127.0.0.1:50332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-06-09T17:14:04.740139Z","caller":"traceutil/trace.go:171","msg":"trace[1525722961] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:11895; }","duration":"312.996484ms","start":"2026-06-09T17:14:04.427125Z","end":"2026-06-09T17:14:04.740122Z","steps":["trace[1525722961] 'agreement among raft nodes before linearized reading' (duration: 312.782678ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:14:04.740195Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:14:04.427104Z","time spent":"313.074357ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":554,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"info","ts":"2026-06-09T17:14:04.740171Z","caller":"traceutil/trace.go:171","msg":"trace[660828238] transaction","detail":"{read_only:false; response_revision:11895; number_of_response:1; }","duration":"399.375457ms","start":"2026-06-09T17:14:04.34075Z","end":"2026-06-09T17:14:04.740125Z","steps":["trace[660828238] 'process raft request' (duration: 85.412745ms)","trace[660828238] 'compare' (duration: 313.098437ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:14:04.74033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.275581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-06-09T17:14:04.740347Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.876022ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-pzjn9\" ","response":"range_response_count:1 size:6447"} {"level":"info","ts":"2026-06-09T17:14:04.740456Z","caller":"traceutil/trace.go:171","msg":"trace[1930403074] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:11895; }","duration":"129.436517ms","start":"2026-06-09T17:14:04.610966Z","end":"2026-06-09T17:14:04.740403Z","steps":["trace[1930403074] 'agreement among raft nodes before linearized reading' (duration: 129.216901ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:14:04.740521Z","caller":"traceutil/trace.go:171","msg":"trace[1223260576] range","detail":"{range_begin:/registry/pods/openstack/keepalived-pzjn9; range_end:; response_count:1; response_revision:11895; }","duration":"110.080368ms","start":"2026-06-09T17:14:04.630417Z","end":"2026-06-09T17:14:04.740497Z","steps":["trace[1223260576] 'agreement among raft nodes before linearized reading' (duration: 109.760399ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:14:04.740356Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:14:04.340713Z","time spent":"399.541192ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:14:05.461491Z","caller":"traceutil/trace.go:171","msg":"trace[616171698] transaction","detail":"{read_only:false; response_revision:11901; number_of_response:1; }","duration":"105.252517ms","start":"2026-06-09T17:14:05.3562Z","end":"2026-06-09T17:14:05.461452Z","steps":["trace[616171698] 'process raft request' (duration: 105.02278ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:16:12.919296Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10543} {"level":"info","ts":"2026-06-09T17:16:13.002327Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10543,"took":"79.64865ms","hash":3518894081,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":26013696,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-06-09T17:16:13.002391Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3518894081,"revision":10543,"compact-revision":8313} {"level":"warn","ts":"2026-06-09T17:20:47.567234Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.774566ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:20:47.567434Z","caller":"traceutil/trace.go:171","msg":"trace[647881981] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:14580; }","duration":"198.022903ms","start":"2026-06-09T17:20:47.369389Z","end":"2026-06-09T17:20:47.567412Z","steps":["trace[647881981] 'range keys from in-memory index tree' (duration: 197.664804ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:20:47.56767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.001126ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-gmxpm\" ","response":"range_response_count:1 size:11624"} {"level":"warn","ts":"2026-06-09T17:20:47.567313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"313.843969ms","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-06-09T17:20:47.567725Z","caller":"traceutil/trace.go:171","msg":"trace[1386349075] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-gmxpm; range_end:; response_count:1; response_revision:14580; }","duration":"186.052048ms","start":"2026-06-09T17:20:47.381657Z","end":"2026-06-09T17:20:47.567709Z","steps":["trace[1386349075] 'range keys from in-memory index tree' (duration: 185.862733ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:20:47.567851Z","caller":"traceutil/trace.go:171","msg":"trace[1534765262] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:14580; }","duration":"314.345193ms","start":"2026-06-09T17:20:47.253411Z","end":"2026-06-09T17:20:47.567757Z","steps":["trace[1534765262] 'range keys from in-memory index tree' (duration: 313.617233ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:20:47.568022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:20:47.253386Z","time spent":"314.58695ms","remote":"127.0.0.1:50622","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":"info","ts":"2026-06-09T17:21:09.855596Z","caller":"traceutil/trace.go:171","msg":"trace[1752942062] transaction","detail":"{read_only:false; response_revision:14891; number_of_response:1; }","duration":"167.760182ms","start":"2026-06-09T17:21:09.687803Z","end":"2026-06-09T17:21:09.855564Z","steps":["trace[1752942062] 'process raft request' (duration: 167.481874ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:21:12.926884Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12763} {"level":"info","ts":"2026-06-09T17:21:13.004453Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12763,"took":"74.793463ms","hash":3069541193,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":28057600,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-06-09T17:21:13.004525Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3069541193,"revision":12763,"compact-revision":10543} {"level":"warn","ts":"2026-06-09T17:22:36.664558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.665699ms","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-06-09T17:22:36.664713Z","caller":"traceutil/trace.go:171","msg":"trace[111401534] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:15704; }","duration":"192.838664ms","start":"2026-06-09T17:22:36.471854Z","end":"2026-06-09T17:22:36.664693Z","steps":["trace[111401534] 'range keys from in-memory index tree' (duration: 192.647969ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:22:36.665237Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.984769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:22:36.665317Z","caller":"traceutil/trace.go:171","msg":"trace[1614235282] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:15704; }","duration":"183.105643ms","start":"2026-06-09T17:22:36.482194Z","end":"2026-06-09T17:22:36.665299Z","steps":["trace[1614235282] 'range keys from in-memory index tree' (duration: 182.897277ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:22:36.665475Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.690632ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:22:36.665524Z","caller":"traceutil/trace.go:171","msg":"trace[1938769438] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15704; }","duration":"182.745343ms","start":"2026-06-09T17:22:36.482764Z","end":"2026-06-09T17:22:36.66551Z","steps":["trace[1938769438] 'range keys from in-memory index tree' (duration: 182.657211ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:22:36.665722Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.005581ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:22:36.665767Z","caller":"traceutil/trace.go:171","msg":"trace[157207002] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:15704; }","duration":"183.083103ms","start":"2026-06-09T17:22:36.482673Z","end":"2026-06-09T17:22:36.665756Z","steps":["trace[157207002] 'range keys from in-memory index tree' (duration: 182.886817ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:22:36.666082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.716464ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5448"} {"level":"info","ts":"2026-06-09T17:22:36.666133Z","caller":"traceutil/trace.go:171","msg":"trace[177619252] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15704; }","duration":"181.806536ms","start":"2026-06-09T17:22:36.484314Z","end":"2026-06-09T17:22:36.66612Z","steps":["trace[177619252] 'range keys from in-memory index tree' (duration: 181.519348ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:22:36.666576Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.117984ms","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-06-09T17:22:36.666652Z","caller":"traceutil/trace.go:171","msg":"trace[2130707696] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:15704; }","duration":"133.227248ms","start":"2026-06-09T17:22:36.533401Z","end":"2026-06-09T17:22:36.666629Z","steps":["trace[2130707696] 'range keys from in-memory index tree' (duration: 132.951029ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:22:36.940333Z","caller":"traceutil/trace.go:171","msg":"trace[529510993] linearizableReadLoop","detail":"{readStateIndex:16253; appliedIndex:16252; }","duration":"148.708453ms","start":"2026-06-09T17:22:36.791602Z","end":"2026-06-09T17:22:36.94031Z","steps":["trace[529510993] 'read index received' (duration: 148.344722ms)","trace[529510993] 'applied index is now lower than readState.Index' (duration: 362.771µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:22:36.940411Z","caller":"traceutil/trace.go:171","msg":"trace[1302467883] transaction","detail":"{read_only:false; response_revision:15706; number_of_response:1; }","duration":"149.555637ms","start":"2026-06-09T17:22:36.79083Z","end":"2026-06-09T17:22:36.940386Z","steps":["trace[1302467883] 'process raft request' (duration: 149.258468ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:22:36.94049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.872599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:22:36.94053Z","caller":"traceutil/trace.go:171","msg":"trace[1129187717] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:15706; }","duration":"148.964202ms","start":"2026-06-09T17:22:36.791555Z","end":"2026-06-09T17:22:36.940519Z","steps":["trace[1129187717] 'agreement among raft nodes before linearized reading' (duration: 148.835807ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:22:37.211091Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.204005ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-06-09T17:22:37.211183Z","caller":"traceutil/trace.go:171","msg":"trace[655521710] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:15706; }","duration":"126.341389ms","start":"2026-06-09T17:22:37.08482Z","end":"2026-06-09T17:22:37.211161Z","steps":["trace[655521710] 'count revisions from in-memory index tree' (duration: 126.119042ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:22:37.211329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.329648ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-6c6qx\" ","response":"range_response_count:1 size:9708"} {"level":"info","ts":"2026-06-09T17:22:37.211378Z","caller":"traceutil/trace.go:171","msg":"trace[1447417888] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-6c6qx; range_end:; response_count:1; response_revision:15706; }","duration":"160.43106ms","start":"2026-06-09T17:22:37.050934Z","end":"2026-06-09T17:22:37.211365Z","steps":["trace[1447417888] 'range keys from in-memory index tree' (duration: 160.169182ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:26:12.951823Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14910} {"level":"info","ts":"2026-06-09T17:26:13.061237Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14910,"took":"104.293393ms","hash":623936670,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":30384128,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-09T17:26:13.06131Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":623936670,"revision":14910,"compact-revision":12763} {"level":"info","ts":"2026-06-09T17:28:40.131508Z","caller":"traceutil/trace.go:171","msg":"trace[1802652205] linearizableReadLoop","detail":"{readStateIndex:19005; appliedIndex:19004; }","duration":"403.853031ms","start":"2026-06-09T17:28:39.727629Z","end":"2026-06-09T17:28:40.131482Z","steps":["trace[1802652205] 'read index received' (duration: 403.762338ms)","trace[1802652205] 'applied index is now lower than readState.Index' (duration: 89.413µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:28:40.13162Z","caller":"traceutil/trace.go:171","msg":"trace[591046847] transaction","detail":"{read_only:false; response_revision:18371; number_of_response:1; }","duration":"409.980287ms","start":"2026-06-09T17:28:39.72161Z","end":"2026-06-09T17:28:40.13159Z","steps":["trace[591046847] 'process raft request' (duration: 409.712859ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:28:40.131688Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"404.026576ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:28:40.131731Z","caller":"traceutil/trace.go:171","msg":"trace[1554086108] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:18371; }","duration":"404.105939ms","start":"2026-06-09T17:28:39.727612Z","end":"2026-06-09T17:28:40.131717Z","steps":["trace[1554086108] 'agreement among raft nodes before linearized reading' (duration: 403.989695ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:28:40.131756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:28:39.721581Z","time spent":"410.105961ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:28:40.131776Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:28:39.727602Z","time spent":"404.16216ms","remote":"127.0.0.1:39200","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":30,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:28:40.142119Z","caller":"traceutil/trace.go:171","msg":"trace[512955227] transaction","detail":"{read_only:false; response_revision:18373; number_of_response:1; }","duration":"203.466771ms","start":"2026-06-09T17:28:39.938626Z","end":"2026-06-09T17:28:40.142093Z","steps":["trace[512955227] 'process raft request' (duration: 203.423239ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:28:40.142166Z","caller":"traceutil/trace.go:171","msg":"trace[1479293150] transaction","detail":"{read_only:false; response_revision:18372; number_of_response:1; }","duration":"408.131445ms","start":"2026-06-09T17:28:39.734006Z","end":"2026-06-09T17:28:40.142137Z","steps":["trace[1479293150] 'process raft request' (duration: 407.860037ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:28:40.142305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:28:39.733972Z","time spent":"408.258049ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:30:14.730833Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"be773dfc027af3eb","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T17:30:14.7354Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-06-09T17:30:14.7355Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-06-09T17:31:12.961678Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17381} {"level":"info","ts":"2026-06-09T17:31:13.062241Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17381,"took":"95.679435ms","hash":3808699840,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":34177024,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-06-09T17:31:13.062305Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3808699840,"revision":17381,"compact-revision":14910} {"level":"info","ts":"2026-06-09T17:34:22.467838Z","caller":"traceutil/trace.go:171","msg":"trace[1422420427] transaction","detail":"{read_only:false; response_revision:21037; number_of_response:1; }","duration":"155.55648ms","start":"2026-06-09T17:34:22.31224Z","end":"2026-06-09T17:34:22.467796Z","steps":["trace[1422420427] 'process raft request' (duration: 155.286952ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:34:38.667901Z","caller":"traceutil/trace.go:171","msg":"trace[484758967] linearizableReadLoop","detail":"{readStateIndex:21849; appliedIndex:21848; }","duration":"102.879912ms","start":"2026-06-09T17:34:38.564991Z","end":"2026-06-09T17:34:38.667871Z","steps":["trace[484758967] 'read index received' (duration: 102.575634ms)","trace[484758967] 'applied index is now lower than readState.Index' (duration: 303.008µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:34:38.667994Z","caller":"traceutil/trace.go:171","msg":"trace[1071658776] transaction","detail":"{read_only:false; response_revision:21122; number_of_response:1; }","duration":"146.269633ms","start":"2026-06-09T17:34:38.52167Z","end":"2026-06-09T17:34:38.66794Z","steps":["trace[1071658776] 'process raft request' (duration: 146.010306ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:34:38.668266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.248564ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-09T17:34:38.668366Z","caller":"traceutil/trace.go:171","msg":"trace[233394936] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:21122; }","duration":"103.403348ms","start":"2026-06-09T17:34:38.564948Z","end":"2026-06-09T17:34:38.668351Z","steps":["trace[233394936] 'agreement among raft nodes before linearized reading' (duration: 103.114489ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:34:38.822281Z","caller":"traceutil/trace.go:171","msg":"trace[575270395] transaction","detail":"{read_only:false; response_revision:21123; number_of_response:1; }","duration":"148.953369ms","start":"2026-06-09T17:34:38.673292Z","end":"2026-06-09T17:34:38.822246Z","steps":["trace[575270395] 'process raft request' (duration: 117.148523ms)","trace[575270395] 'compare' (duration: 31.672582ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:34:39.633366Z","caller":"traceutil/trace.go:171","msg":"trace[1006901275] transaction","detail":"{read_only:false; response_revision:21124; number_of_response:1; }","duration":"146.108929ms","start":"2026-06-09T17:34:39.487152Z","end":"2026-06-09T17:34:39.633261Z","steps":["trace[1006901275] 'process raft request' (duration: 145.849382ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:34:39.691603Z","caller":"traceutil/trace.go:171","msg":"trace[1860246319] transaction","detail":"{read_only:false; response_revision:21125; number_of_response:1; }","duration":"131.559009ms","start":"2026-06-09T17:34:39.560016Z","end":"2026-06-09T17:34:39.691575Z","steps":["trace[1860246319] 'process raft request' (duration: 131.409055ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:36:12.96969Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19697} {"level":"info","ts":"2026-06-09T17:36:13.079618Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19697,"took":"107.131385ms","hash":3790575503,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":29528064,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-09T17:36:13.079941Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3790575503,"revision":19697,"compact-revision":17381} {"level":"info","ts":"2026-06-09T17:38:42.759583Z","caller":"traceutil/trace.go:171","msg":"trace[1038500568] transaction","detail":"{read_only:false; response_revision:23623; number_of_response:1; }","duration":"185.918515ms","start":"2026-06-09T17:38:42.573632Z","end":"2026-06-09T17:38:42.75955Z","steps":["trace[1038500568] 'process raft request' (duration: 185.73998ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:38:42.882277Z","caller":"traceutil/trace.go:171","msg":"trace[471491158] transaction","detail":"{read_only:false; response_revision:23624; number_of_response:1; }","duration":"290.196738ms","start":"2026-06-09T17:38:42.592029Z","end":"2026-06-09T17:38:42.882225Z","steps":["trace[471491158] 'process raft request' (duration: 234.789202ms)","trace[471491158] 'compare' (duration: 55.129277ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:38:42.882281Z","caller":"traceutil/trace.go:171","msg":"trace[1126512163] transaction","detail":"{read_only:false; response_revision:23625; number_of_response:1; }","duration":"204.555121ms","start":"2026-06-09T17:38:42.677683Z","end":"2026-06-09T17:38:42.882238Z","steps":["trace[1126512163] 'process raft request' (duration: 204.444068ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:39:39.315069Z","caller":"traceutil/trace.go:171","msg":"trace[2084204720] transaction","detail":"{read_only:false; response_revision:24295; number_of_response:1; }","duration":"143.771961ms","start":"2026-06-09T17:39:39.171264Z","end":"2026-06-09T17:39:39.315036Z","steps":["trace[2084204720] 'process raft request' (duration: 143.580575ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:40:03.646358Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.619872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:40:03.646423Z","caller":"traceutil/trace.go:171","msg":"trace[1681369083] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:24512; }","duration":"264.751296ms","start":"2026-06-09T17:40:03.381656Z","end":"2026-06-09T17:40:03.646407Z","steps":["trace[1681369083] 'count revisions from in-memory index tree' (duration: 264.514858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:40:03.646359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.684416ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:40:03.646542Z","caller":"traceutil/trace.go:171","msg":"trace[1420637701] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:24512; }","duration":"312.918013ms","start":"2026-06-09T17:40:03.333597Z","end":"2026-06-09T17:40:03.646515Z","steps":["trace[1420637701] 'count revisions from in-memory index tree' (duration: 312.566953ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:40:03.646615Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:40:03.333568Z","time spent":"313.025716ms","remote":"127.0.0.1:37820","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":30,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:40:03.792684Z","caller":"traceutil/trace.go:171","msg":"trace[1938615656] transaction","detail":"{read_only:false; response_revision:24513; number_of_response:1; }","duration":"380.314994ms","start":"2026-06-09T17:40:03.412342Z","end":"2026-06-09T17:40:03.792657Z","steps":["trace[1938615656] 'process raft request' (duration: 380.144329ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:40:03.792848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:40:03.412321Z","time spent":"380.444137ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:40:03.799933Z","caller":"traceutil/trace.go:171","msg":"trace[1755873665] linearizableReadLoop","detail":"{readStateIndex:25340; appliedIndex:25338; }","duration":"306.116646ms","start":"2026-06-09T17:40:03.493796Z","end":"2026-06-09T17:40:03.799913Z","steps":["trace[1755873665] 'read index received' (duration: 298.761435ms)","trace[1755873665] 'applied index is now lower than readState.Index' (duration: 7.354481ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:40:03.799971Z","caller":"traceutil/trace.go:171","msg":"trace[249780245] transaction","detail":"{read_only:false; response_revision:24514; number_of_response:1; }","duration":"386.81595ms","start":"2026-06-09T17:40:03.413126Z","end":"2026-06-09T17:40:03.799942Z","steps":["trace[249780245] 'process raft request' (duration: 386.627275ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:40:03.800111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"306.305281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-09T17:40:03.800143Z","caller":"traceutil/trace.go:171","msg":"trace[1148590107] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24515; }","duration":"306.391484ms","start":"2026-06-09T17:40:03.493744Z","end":"2026-06-09T17:40:03.800136Z","steps":["trace[1148590107] 'agreement among raft nodes before linearized reading' (duration: 306.26453ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:40:03.800171Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:40:03.493714Z","time spent":"306.450785ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T17:40:03.800159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.223522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs/\" range_end:\"/registry/runtime.cluster.x-k8s.io/extensionconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:40:03.800236Z","caller":"traceutil/trace.go:171","msg":"trace[1278522585] range","detail":"{range_begin:/registry/runtime.cluster.x-k8s.io/extensionconfigs/; range_end:/registry/runtime.cluster.x-k8s.io/extensionconfigs0; response_count:0; response_revision:24515; }","duration":"129.372947ms","start":"2026-06-09T17:40:03.67084Z","end":"2026-06-09T17:40:03.800213Z","steps":["trace[1278522585] 'agreement among raft nodes before linearized reading' (duration: 129.230392ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:40:03.800353Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:40:03.413109Z","time spent":"387.067238ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:40:04.116635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.79659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-09T17:40:04.116703Z","caller":"traceutil/trace.go:171","msg":"trace[1464089308] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24519; }","duration":"102.912794ms","start":"2026-06-09T17:40:04.013776Z","end":"2026-06-09T17:40:04.116689Z","steps":["trace[1464089308] 'agreement among raft nodes before linearized reading' (duration: 102.744139ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:41:12.978356Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21962} {"level":"info","ts":"2026-06-09T17:41:13.089707Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21962,"took":"107.448644ms","hash":159914950,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":43307008,"current-db-size-in-use":"43 MB"} {"level":"info","ts":"2026-06-09T17:41:13.089784Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":159914950,"revision":21962,"compact-revision":19697} {"level":"warn","ts":"2026-06-09T17:42:08.767026Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.46703ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:42:08.767229Z","caller":"traceutil/trace.go:171","msg":"trace[1463547315] transaction","detail":"{read_only:false; response_revision:25721; number_of_response:1; }","duration":"214.59958ms","start":"2026-06-09T17:42:08.552605Z","end":"2026-06-09T17:42:08.767204Z","steps":["trace[1463547315] 'process raft request' (duration: 102.855392ms)","trace[1463547315] 'compare' (duration: 111.355157ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:08.805484Z","caller":"traceutil/trace.go:171","msg":"trace[1871552828] transaction","detail":"{read_only:false; response_revision:25722; number_of_response:1; }","duration":"191.235328ms","start":"2026-06-09T17:42:08.614216Z","end":"2026-06-09T17:42:08.805452Z","steps":["trace[1871552828] 'process raft request' (duration: 191.107134ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:14.13889Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.623966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:14.138979Z","caller":"traceutil/trace.go:171","msg":"trace[838515001] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachines/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachines0; response_count:0; response_revision:25758; }","duration":"126.806081ms","start":"2026-06-09T17:42:14.012148Z","end":"2026-06-09T17:42:14.138954Z","steps":["trace[838515001] 'count revisions from in-memory index tree' (duration: 126.532344ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:14.631039Z","caller":"traceutil/trace.go:171","msg":"trace[802829992] transaction","detail":"{read_only:false; response_revision:25759; number_of_response:1; }","duration":"290.982151ms","start":"2026-06-09T17:42:14.340034Z","end":"2026-06-09T17:42:14.631016Z","steps":["trace[802829992] 'process raft request' (duration: 290.683911ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:14.630948Z","caller":"traceutil/trace.go:171","msg":"trace[649931709] linearizableReadLoop","detail":"{readStateIndex:26620; appliedIndex:26619; }","duration":"290.314312ms","start":"2026-06-09T17:42:14.340598Z","end":"2026-06-09T17:42:14.630912Z","steps":["trace[649931709] 'read index received' (duration: 290.022933ms)","trace[649931709] 'applied index is now lower than readState.Index' (duration: 290.109µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:14.631965Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.33086ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:90 size:118782"} {"level":"info","ts":"2026-06-09T17:42:14.632072Z","caller":"traceutil/trace.go:171","msg":"trace[1861977269] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:90; response_revision:25759; }","duration":"291.516416ms","start":"2026-06-09T17:42:14.340533Z","end":"2026-06-09T17:42:14.632049Z","steps":["trace[1861977269] 'agreement among raft nodes before linearized reading' (duration: 290.58897ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:14.959803Z","caller":"traceutil/trace.go:171","msg":"trace[2000205140] transaction","detail":"{read_only:false; response_revision:25761; number_of_response:1; }","duration":"552.366339ms","start":"2026-06-09T17:42:14.407401Z","end":"2026-06-09T17:42:14.959768Z","steps":["trace[2000205140] 'process raft request' (duration: 552.252595ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:14.959924Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"591.725122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:14.959978Z","caller":"traceutil/trace.go:171","msg":"trace[309887140] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25762; }","duration":"591.839685ms","start":"2026-06-09T17:42:14.368118Z","end":"2026-06-09T17:42:14.959958Z","steps":["trace[309887140] 'agreement among raft nodes before linearized reading' (duration: 591.746222ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:14.959987Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:14.407176Z","time spent":"552.712178ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:42:14.959997Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"454.346765ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-09T17:42:14.960079Z","caller":"traceutil/trace.go:171","msg":"trace[596225681] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25762; }","duration":"454.473709ms","start":"2026-06-09T17:42:14.505588Z","end":"2026-06-09T17:42:14.960061Z","steps":["trace[596225681] 'agreement among raft nodes before linearized reading' (duration: 454.199501ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:14.96012Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:14.505572Z","time spent":"454.53976ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-06-09T17:42:14.960165Z","caller":"traceutil/trace.go:171","msg":"trace[525753208] transaction","detail":"{read_only:false; response_revision:25762; number_of_response:1; }","duration":"552.711529ms","start":"2026-06-09T17:42:14.407446Z","end":"2026-06-09T17:42:14.960158Z","steps":["trace[525753208] 'process raft request' (duration: 552.247076ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:14.960213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"577.07292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129462"} {"level":"info","ts":"2026-06-09T17:42:14.960246Z","caller":"traceutil/trace.go:171","msg":"trace[421131768] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25762; }","duration":"577.140382ms","start":"2026-06-09T17:42:14.383097Z","end":"2026-06-09T17:42:14.960238Z","steps":["trace[421131768] 'agreement among raft nodes before linearized reading' (duration: 576.947106ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:14.960025Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:14.368098Z","time spent":"591.917538ms","remote":"127.0.0.1:50332","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:42:14.960248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:14.407412Z","time spent":"552.802291ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:42:14.960271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:14.38308Z","time spent":"577.185394ms","remote":"127.0.0.1:50396","response type":"/etcdserverpb.KV/Range","request count":0,"request size":29,"response count":1,"response size":129486,"request content":"key:\"/registry/ranges/serviceips\" "} {"level":"info","ts":"2026-06-09T17:42:14.960131Z","caller":"traceutil/trace.go:171","msg":"trace[907048396] transaction","detail":"{read_only:false; response_revision:25760; number_of_response:1; }","duration":"611.481011ms","start":"2026-06-09T17:42:14.34864Z","end":"2026-06-09T17:42:14.960121Z","steps":["trace[907048396] 'process raft request' (duration: 610.828492ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:14.960382Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:14.348614Z","time spent":"611.740498ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:42:14.959768Z","caller":"traceutil/trace.go:171","msg":"trace[60502797] linearizableReadLoop","detail":"{readStateIndex:26623; appliedIndex:26620; }","duration":"328.180302ms","start":"2026-06-09T17:42:14.631556Z","end":"2026-06-09T17:42:14.959736Z","steps":["trace[60502797] 'read index received' (duration: 327.853722ms)","trace[60502797] 'applied index is now lower than readState.Index' (duration: 325.47µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:14.98023Z","caller":"traceutil/trace.go:171","msg":"trace[85235240] transaction","detail":"{read_only:false; response_revision:25763; number_of_response:1; }","duration":"191.562237ms","start":"2026-06-09T17:42:14.788635Z","end":"2026-06-09T17:42:14.980198Z","steps":["trace[85235240] 'process raft request' (duration: 190.96522ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:14.980442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.708152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-06-09T17:42:14.980519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.351261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/servicenodeports\" ","response":"range_response_count:1 size:119"} {"level":"info","ts":"2026-06-09T17:42:14.980506Z","caller":"traceutil/trace.go:171","msg":"trace[1529291980] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:25763; }","duration":"232.835186ms","start":"2026-06-09T17:42:14.747653Z","end":"2026-06-09T17:42:14.980488Z","steps":["trace[1529291980] 'agreement among raft nodes before linearized reading' (duration: 232.720433ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:14.980611Z","caller":"traceutil/trace.go:171","msg":"trace[1693561986] range","detail":"{range_begin:/registry/ranges/servicenodeports; range_end:; response_count:1; response_revision:25763; }","duration":"341.481845ms","start":"2026-06-09T17:42:14.639109Z","end":"2026-06-09T17:42:14.980591Z","steps":["trace[1693561986] 'agreement among raft nodes before linearized reading' (duration: 341.178236ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:14.980658Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:14.639093Z","time spent":"341.556938ms","remote":"127.0.0.1:50408","response type":"/etcdserverpb.KV/Range","request count":0,"request size":35,"response count":1,"response size":143,"request content":"key:\"/registry/ranges/servicenodeports\" "} {"level":"warn","ts":"2026-06-09T17:42:15.263069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.802366ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:42:15.263168Z","caller":"traceutil/trace.go:171","msg":"trace[1734497495] linearizableReadLoop","detail":"{readStateIndex:26625; appliedIndex:26624; }","duration":"283.123225ms","start":"2026-06-09T17:42:14.980012Z","end":"2026-06-09T17:42:15.263135Z","steps":["trace[1734497495] 'read index received' (duration: 143.189414ms)","trace[1734497495] 'applied index is now lower than readState.Index' (duration: 139.932901ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:15.263232Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"301.234675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:15.263248Z","caller":"traceutil/trace.go:171","msg":"trace[1670621494] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25764; }","duration":"301.293808ms","start":"2026-06-09T17:42:14.961948Z","end":"2026-06-09T17:42:15.263242Z","steps":["trace[1670621494] 'agreement among raft nodes before linearized reading' (duration: 301.241766ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:15.263222Z","caller":"traceutil/trace.go:171","msg":"trace[1778784860] transaction","detail":"{read_only:false; response_revision:25764; number_of_response:1; }","duration":"296.928301ms","start":"2026-06-09T17:42:14.966268Z","end":"2026-06-09T17:42:15.263196Z","steps":["trace[1778784860] 'process raft request' (duration: 156.913709ms)","trace[1778784860] 'compare' (duration: 139.59874ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:15.263272Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:14.96189Z","time spent":"301.376379ms","remote":"127.0.0.1:50342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:42:15.263827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.149604ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" ","response":"range_response_count:90 size:118782"} {"level":"info","ts":"2026-06-09T17:42:15.263848Z","caller":"traceutil/trace.go:171","msg":"trace[1609166690] range","detail":"{range_begin:/registry/services/specs/; range_end:/registry/services/specs0; response_count:90; response_revision:25764; }","duration":"300.200727ms","start":"2026-06-09T17:42:14.963643Z","end":"2026-06-09T17:42:15.263843Z","steps":["trace[1609166690] 'agreement among raft nodes before linearized reading' (duration: 299.742813ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:15.263863Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:14.963619Z","time spent":"300.239388ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":90,"response size":118806,"request content":"key:\"/registry/services/specs/\" range_end:\"/registry/services/specs0\" "} {"level":"info","ts":"2026-06-09T17:42:15.429678Z","caller":"traceutil/trace.go:171","msg":"trace[1155382880] transaction","detail":"{read_only:false; response_revision:25765; number_of_response:1; }","duration":"444.693528ms","start":"2026-06-09T17:42:14.984951Z","end":"2026-06-09T17:42:15.429645Z","steps":["trace[1155382880] 'process raft request' (duration: 414.57479ms)","trace[1155382880] 'compare' (duration: 29.936273ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:15.42986Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:14.98492Z","time spent":"444.852692ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:42:15.430133Z","caller":"traceutil/trace.go:171","msg":"trace[1087527542] transaction","detail":"{read_only:false; response_revision:25766; number_of_response:1; }","duration":"443.572325ms","start":"2026-06-09T17:42:14.986529Z","end":"2026-06-09T17:42:15.430101Z","steps":["trace[1087527542] 'process raft request' (duration: 443.069831ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:15.430296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:14.986507Z","time spent":"443.700699ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:42:15.43033Z","caller":"traceutil/trace.go:171","msg":"trace[532043131] linearizableReadLoop","detail":"{readStateIndex:26629; appliedIndex:26625; }","duration":"161.43249ms","start":"2026-06-09T17:42:15.268879Z","end":"2026-06-09T17:42:15.430312Z","steps":["trace[532043131] 'read index received' (duration: 130.577431ms)","trace[532043131] 'applied index is now lower than readState.Index' (duration: 30.854179ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:15.430393Z","caller":"traceutil/trace.go:171","msg":"trace[1679568182] transaction","detail":"{read_only:false; response_revision:25767; number_of_response:1; }","duration":"443.566175ms","start":"2026-06-09T17:42:14.986787Z","end":"2026-06-09T17:42:15.430353Z","steps":["trace[1679568182] 'process raft request' (duration: 443.061421ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:15.430442Z","caller":"traceutil/trace.go:171","msg":"trace[1190927692] transaction","detail":"{read_only:false; response_revision:25768; number_of_response:1; }","duration":"337.720127ms","start":"2026-06-09T17:42:15.092666Z","end":"2026-06-09T17:42:15.430386Z","steps":["trace[1190927692] 'process raft request' (duration: 337.565313ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:15.430534Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.772533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:15.430575Z","caller":"traceutil/trace.go:171","msg":"trace[384339281] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:25768; }","duration":"125.843745ms","start":"2026-06-09T17:42:15.304718Z","end":"2026-06-09T17:42:15.430562Z","steps":["trace[384339281] 'agreement among raft nodes before linearized reading' (duration: 125.779823ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:15.43058Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"161.700227ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ranges/serviceips\" ","response":"range_response_count:1 size:129462"} {"level":"warn","ts":"2026-06-09T17:42:15.430582Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:14.98678Z","time spent":"443.698589ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:42:15.430618Z","caller":"traceutil/trace.go:171","msg":"trace[1525434073] range","detail":"{range_begin:/registry/ranges/serviceips; range_end:; response_count:1; response_revision:25768; }","duration":"161.770119ms","start":"2026-06-09T17:42:15.268837Z","end":"2026-06-09T17:42:15.430607Z","steps":["trace[1525434073] 'agreement among raft nodes before linearized reading' (duration: 161.576704ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:15.430923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.721211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-06-09T17:42:15.43105Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:15.092642Z","time spent":"337.919153ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:42:15.431072Z","caller":"traceutil/trace.go:171","msg":"trace[631939301] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25768; }","duration":"124.894386ms","start":"2026-06-09T17:42:15.306139Z","end":"2026-06-09T17:42:15.431034Z","steps":["trace[631939301] 'agreement among raft nodes before linearized reading' (duration: 124.357121ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:15.792581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.849078ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:42:15.792905Z","caller":"traceutil/trace.go:171","msg":"trace[320765780] transaction","detail":"{read_only:false; response_revision:25770; number_of_response:1; }","duration":"268.817472ms","start":"2026-06-09T17:42:15.524072Z","end":"2026-06-09T17:42:15.792889Z","steps":["trace[320765780] 'process raft request' (duration: 268.603566ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:15.793329Z","caller":"traceutil/trace.go:171","msg":"trace[708264116] transaction","detail":"{read_only:false; response_revision:25769; number_of_response:1; }","duration":"357.719693ms","start":"2026-06-09T17:42:15.435599Z","end":"2026-06-09T17:42:15.793318Z","steps":["trace[708264116] 'process raft request' (duration: 134.039671ms)","trace[708264116] 'compare' (duration: 222.387264ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:15.79343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:15.435582Z","time spent":"357.799845ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:42:16.443606Z","caller":"traceutil/trace.go:171","msg":"trace[2006830410] transaction","detail":"{read_only:false; response_revision:25775; number_of_response:1; }","duration":"133.211857ms","start":"2026-06-09T17:42:16.31036Z","end":"2026-06-09T17:42:16.443572Z","steps":["trace[2006830410] 'process raft request' (duration: 132.518046ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:17.185952Z","caller":"traceutil/trace.go:171","msg":"trace[2076087755] transaction","detail":"{read_only:false; response_revision:25776; number_of_response:1; }","duration":"218.164264ms","start":"2026-06-09T17:42:16.967763Z","end":"2026-06-09T17:42:17.185927Z","steps":["trace[2076087755] 'process raft request' (duration: 217.994549ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:17.242704Z","caller":"traceutil/trace.go:171","msg":"trace[63666371] linearizableReadLoop","detail":"{readStateIndex:26641; appliedIndex:26637; }","duration":"115.870737ms","start":"2026-06-09T17:42:17.12681Z","end":"2026-06-09T17:42:17.242681Z","steps":["trace[63666371] 'read index received' (duration: 59.045401ms)","trace[63666371] 'applied index is now lower than readState.Index' (duration: 56.824386ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:17.243082Z","caller":"traceutil/trace.go:171","msg":"trace[1502525349] transaction","detail":"{read_only:false; response_revision:25777; number_of_response:1; }","duration":"273.131306ms","start":"2026-06-09T17:42:16.96994Z","end":"2026-06-09T17:42:17.243071Z","steps":["trace[1502525349] 'process raft request' (duration: 272.397595ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:17.243222Z","caller":"traceutil/trace.go:171","msg":"trace[1769674569] transaction","detail":"{read_only:false; response_revision:25778; number_of_response:1; }","duration":"270.316995ms","start":"2026-06-09T17:42:16.972898Z","end":"2026-06-09T17:42:17.243215Z","steps":["trace[1769674569] 'process raft request' (duration: 269.611285ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:17.243313Z","caller":"traceutil/trace.go:171","msg":"trace[703052908] transaction","detail":"{read_only:false; response_revision:25779; number_of_response:1; }","duration":"256.384144ms","start":"2026-06-09T17:42:16.986923Z","end":"2026-06-09T17:42:17.243307Z","steps":["trace[703052908] 'process raft request' (duration: 255.701894ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:17.243434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.623459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:17.243468Z","caller":"traceutil/trace.go:171","msg":"trace[631541284] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:25779; }","duration":"116.735563ms","start":"2026-06-09T17:42:17.12672Z","end":"2026-06-09T17:42:17.243456Z","steps":["trace[631541284] 'agreement among raft nodes before linearized reading' (duration: 116.665741ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:17.496348Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.677006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:17.496441Z","caller":"traceutil/trace.go:171","msg":"trace[228147784] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25779; }","duration":"127.824681ms","start":"2026-06-09T17:42:17.368596Z","end":"2026-06-09T17:42:17.496421Z","steps":["trace[228147784] 'range keys from in-memory index tree' (duration: 127.608105ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:17.496706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.545303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-06-09T17:42:17.496299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.520252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:17.496765Z","caller":"traceutil/trace.go:171","msg":"trace[1625421266] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25779; }","duration":"228.634566ms","start":"2026-06-09T17:42:17.268115Z","end":"2026-06-09T17:42:17.49675Z","steps":["trace[1625421266] 'range keys from in-memory index tree' (duration: 228.387758ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:17.496824Z","caller":"traceutil/trace.go:171","msg":"trace[1510411294] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25779; }","duration":"170.066698ms","start":"2026-06-09T17:42:17.326715Z","end":"2026-06-09T17:42:17.496782Z","steps":["trace[1510411294] 'range keys from in-memory index tree' (duration: 169.419139ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:17.691681Z","caller":"traceutil/trace.go:171","msg":"trace[1467656979] transaction","detail":"{read_only:false; response_revision:25781; number_of_response:1; }","duration":"190.374903ms","start":"2026-06-09T17:42:17.501262Z","end":"2026-06-09T17:42:17.691637Z","steps":["trace[1467656979] 'process raft request' (duration: 189.961641ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:17.69172Z","caller":"traceutil/trace.go:171","msg":"trace[1070186186] linearizableReadLoop","detail":"{readStateIndex:26646; appliedIndex:26642; }","duration":"161.557733ms","start":"2026-06-09T17:42:17.530129Z","end":"2026-06-09T17:42:17.691687Z","steps":["trace[1070186186] 'read index received' (duration: 160.933136ms)","trace[1070186186] 'applied index is now lower than readState.Index' (duration: 623.637µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:17.691868Z","caller":"traceutil/trace.go:171","msg":"trace[1003722984] transaction","detail":"{read_only:false; response_revision:25782; number_of_response:1; }","duration":"190.202308ms","start":"2026-06-09T17:42:17.501649Z","end":"2026-06-09T17:42:17.691851Z","steps":["trace[1003722984] 'process raft request' (duration: 189.836538ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:17.691991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.451553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machines/\" range_end:\"/registry/cluster.x-k8s.io/machines0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:42:17.692039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.159965ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:17.692061Z","caller":"traceutil/trace.go:171","msg":"trace[1344036681] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machines/; range_end:/registry/cluster.x-k8s.io/machines0; response_count:0; response_revision:25784; }","duration":"167.565936ms","start":"2026-06-09T17:42:17.524474Z","end":"2026-06-09T17:42:17.69204Z","steps":["trace[1344036681] 'agreement among raft nodes before linearized reading' (duration: 167.436353ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:17.692075Z","caller":"traceutil/trace.go:171","msg":"trace[1411610061] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25784; }","duration":"191.251388ms","start":"2026-06-09T17:42:17.500811Z","end":"2026-06-09T17:42:17.692063Z","steps":["trace[1411610061] 'agreement among raft nodes before linearized reading' (duration: 191.183386ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:17.69212Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.441313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:17.69224Z","caller":"traceutil/trace.go:171","msg":"trace[1746713615] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25784; }","duration":"142.598378ms","start":"2026-06-09T17:42:17.54962Z","end":"2026-06-09T17:42:17.692219Z","steps":["trace[1746713615] 'agreement among raft nodes before linearized reading' (duration: 142.434523ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:17.692329Z","caller":"traceutil/trace.go:171","msg":"trace[1907076623] transaction","detail":"{read_only:false; response_revision:25783; number_of_response:1; }","duration":"190.530848ms","start":"2026-06-09T17:42:17.501759Z","end":"2026-06-09T17:42:17.69229Z","steps":["trace[1907076623] 'process raft request' (duration: 189.836208ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:17.692554Z","caller":"traceutil/trace.go:171","msg":"trace[262599020] transaction","detail":"{read_only:false; response_revision:25784; number_of_response:1; }","duration":"190.199087ms","start":"2026-06-09T17:42:17.50234Z","end":"2026-06-09T17:42:17.692539Z","steps":["trace[262599020] 'process raft request' (duration: 189.301791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:18.039999Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.347741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-09T17:42:18.04009Z","caller":"traceutil/trace.go:171","msg":"trace[1577845688] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25784; }","duration":"241.503247ms","start":"2026-06-09T17:42:17.798564Z","end":"2026-06-09T17:42:18.040068Z","steps":["trace[1577845688] 'range keys from in-memory index tree' (duration: 241.110854ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:18.040406Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.922336ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:42:18.040542Z","caller":"traceutil/trace.go:171","msg":"trace[1771024512] transaction","detail":"{read_only:false; response_revision:25785; number_of_response:1; }","duration":"228.359928ms","start":"2026-06-09T17:42:17.812163Z","end":"2026-06-09T17:42:18.040523Z","steps":["trace[1771024512] 'process raft request' (duration: 101.216805ms)","trace[1771024512] 'compare' (duration: 126.737811ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:18.243286Z","caller":"traceutil/trace.go:171","msg":"trace[27690786] transaction","detail":"{read_only:false; response_revision:25786; number_of_response:1; }","duration":"415.97489ms","start":"2026-06-09T17:42:17.827275Z","end":"2026-06-09T17:42:18.24325Z","steps":["trace[27690786] 'process raft request' (duration: 337.795878ms)","trace[27690786] 'compare' (duration: 77.818272ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:18.243495Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:17.82724Z","time spent":"416.144395ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:42:18.243523Z","caller":"traceutil/trace.go:171","msg":"trace[1178406959] transaction","detail":"{read_only:false; response_revision:25788; number_of_response:1; }","duration":"199.240398ms","start":"2026-06-09T17:42:18.044262Z","end":"2026-06-09T17:42:18.243502Z","steps":["trace[1178406959] 'process raft request' (duration: 199.142155ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:18.243516Z","caller":"traceutil/trace.go:171","msg":"trace[1564234881] transaction","detail":"{read_only:false; response_revision:25787; number_of_response:1; }","duration":"415.626481ms","start":"2026-06-09T17:42:17.827856Z","end":"2026-06-09T17:42:18.243482Z","steps":["trace[1564234881] 'process raft request' (duration: 415.278401ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:18.243581Z","caller":"traceutil/trace.go:171","msg":"trace[788796207] linearizableReadLoop","detail":"{readStateIndex:26649; appliedIndex:26647; }","duration":"242.826924ms","start":"2026-06-09T17:42:18.000728Z","end":"2026-06-09T17:42:18.243555Z","steps":["trace[788796207] 'read index received' (duration: 164.287552ms)","trace[788796207] 'applied index is now lower than readState.Index' (duration: 78.536922ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:18.243704Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:17.827838Z","time spent":"415.764484ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:42:18.243742Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.280032ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:42:18.243736Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"242.999069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/\" range_end:\"/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:18.243782Z","caller":"traceutil/trace.go:171","msg":"trace[1998282421] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:25788; }","duration":"131.361734ms","start":"2026-06-09T17:42:18.112407Z","end":"2026-06-09T17:42:18.243769Z","steps":["trace[1998282421] 'agreement among raft nodes before linearized reading' (duration: 131.283302ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:18.243795Z","caller":"traceutil/trace.go:171","msg":"trace[2143671113] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies/; range_end:/registry/gateway.networking.x-k8s.io/xbackendtrafficpolicies0; response_count:0; response_revision:25788; }","duration":"243.103272ms","start":"2026-06-09T17:42:18.000675Z","end":"2026-06-09T17:42:18.243778Z","steps":["trace[2143671113] 'agreement among raft nodes before linearized reading' (duration: 242.973268ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:19.354822Z","caller":"traceutil/trace.go:171","msg":"trace[1024951431] transaction","detail":"{read_only:false; response_revision:25790; number_of_response:1; }","duration":"105.993093ms","start":"2026-06-09T17:42:19.248811Z","end":"2026-06-09T17:42:19.354804Z","steps":["trace[1024951431] 'process raft request' (duration: 105.774597ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:19.35487Z","caller":"traceutil/trace.go:171","msg":"trace[2056384469] transaction","detail":"{read_only:false; response_revision:25791; number_of_response:1; }","duration":"105.794778ms","start":"2026-06-09T17:42:19.249069Z","end":"2026-06-09T17:42:19.354864Z","steps":["trace[2056384469] 'process raft request' (duration: 105.661994ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:19.35482Z","caller":"traceutil/trace.go:171","msg":"trace[1907876107] transaction","detail":"{read_only:false; response_revision:25792; number_of_response:1; }","duration":"104.043147ms","start":"2026-06-09T17:42:19.250754Z","end":"2026-06-09T17:42:19.354798Z","steps":["trace[1907876107] 'process raft request' (duration: 104.010836ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:19.75653Z","caller":"traceutil/trace.go:171","msg":"trace[1620316182] transaction","detail":"{read_only:false; response_revision:25793; number_of_response:1; }","duration":"219.854452ms","start":"2026-06-09T17:42:19.536645Z","end":"2026-06-09T17:42:19.7565Z","steps":["trace[1620316182] 'process raft request' (duration: 219.662926ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:19.864247Z","caller":"traceutil/trace.go:171","msg":"trace[711609444] linearizableReadLoop","detail":"{readStateIndex:26656; appliedIndex:26654; }","duration":"168.139182ms","start":"2026-06-09T17:42:19.696075Z","end":"2026-06-09T17:42:19.864214Z","steps":["trace[711609444] 'read index received' (duration: 60.293276ms)","trace[711609444] 'applied index is now lower than readState.Index' (duration: 107.844236ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:19.8645Z","caller":"traceutil/trace.go:171","msg":"trace[1493898088] transaction","detail":"{read_only:false; response_revision:25794; number_of_response:1; }","duration":"227.951615ms","start":"2026-06-09T17:42:19.636538Z","end":"2026-06-09T17:42:19.86449Z","steps":["trace[1493898088] 'process raft request' (duration: 214.726224ms)","trace[1493898088] 'compare' (duration: 12.414607ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:19.864753Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.656447ms","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-06-09T17:42:19.864779Z","caller":"traceutil/trace.go:171","msg":"trace[2012814055] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25794; }","duration":"168.739869ms","start":"2026-06-09T17:42:19.696033Z","end":"2026-06-09T17:42:19.864773Z","steps":["trace[2012814055] 'agreement among raft nodes before linearized reading' (duration: 168.609636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:19.864895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.242626ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-09T17:42:19.86492Z","caller":"traceutil/trace.go:171","msg":"trace[308794606] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25794; }","duration":"168.286956ms","start":"2026-06-09T17:42:19.696626Z","end":"2026-06-09T17:42:19.864913Z","steps":["trace[308794606] 'agreement among raft nodes before linearized reading' (duration: 168.230525ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:19.865069Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.019349ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-09T17:42:19.865091Z","caller":"traceutil/trace.go:171","msg":"trace[1260402501] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25794; }","duration":"168.0561ms","start":"2026-06-09T17:42:19.69703Z","end":"2026-06-09T17:42:19.865086Z","steps":["trace[1260402501] 'agreement among raft nodes before linearized reading' (duration: 168.004279ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:19.865184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.171903ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-09T17:42:19.865205Z","caller":"traceutil/trace.go:171","msg":"trace[290540830] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25794; }","duration":"168.209104ms","start":"2026-06-09T17:42:19.69699Z","end":"2026-06-09T17:42:19.865199Z","steps":["trace[290540830] 'agreement among raft nodes before linearized reading' (duration: 168.160283ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:20.123926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.023197ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:42:20.124028Z","caller":"traceutil/trace.go:171","msg":"trace[511948508] transaction","detail":"{read_only:false; response_revision:25795; number_of_response:1; }","duration":"245.883451ms","start":"2026-06-09T17:42:19.878125Z","end":"2026-06-09T17:42:20.124009Z","steps":["trace[511948508] 'process raft request' (duration: 126.671608ms)","trace[511948508] 'compare' (duration: 118.832712ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:20.21854Z","caller":"traceutil/trace.go:171","msg":"trace[1819527208] transaction","detail":"{read_only:false; response_revision:25796; number_of_response:1; }","duration":"333.580687ms","start":"2026-06-09T17:42:19.88493Z","end":"2026-06-09T17:42:20.21851Z","steps":["trace[1819527208] 'process raft request' (duration: 333.33308ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:20.218717Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:19.884896Z","time spent":"333.723291ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:42:20.219011Z","caller":"traceutil/trace.go:171","msg":"trace[1249802893] transaction","detail":"{read_only:false; response_revision:25797; number_of_response:1; }","duration":"333.433713ms","start":"2026-06-09T17:42:19.885544Z","end":"2026-06-09T17:42:20.218978Z","steps":["trace[1249802893] 'process raft request' (duration: 332.923909ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:20.219176Z","caller":"traceutil/trace.go:171","msg":"trace[10015581] linearizableReadLoop","detail":"{readStateIndex:26660; appliedIndex:26656; }","duration":"227.40182ms","start":"2026-06-09T17:42:19.991763Z","end":"2026-06-09T17:42:20.219165Z","steps":["trace[10015581] 'read index received' (duration: 12.758388ms)","trace[10015581] 'applied index is now lower than readState.Index' (duration: 214.642392ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:20.219204Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:19.885527Z","time spent":"333.568047ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:42:20.219308Z","caller":"traceutil/trace.go:171","msg":"trace[1603407085] transaction","detail":"{read_only:false; response_revision:25798; number_of_response:1; }","duration":"333.159904ms","start":"2026-06-09T17:42:19.886139Z","end":"2026-06-09T17:42:20.219299Z","steps":["trace[1603407085] 'process raft request' (duration: 332.726422ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:20.219358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:19.886107Z","time spent":"333.225737ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:42:20.219469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.717148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectzones/\" range_end:\"/registry/ceph.rook.io/cephobjectzones0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:20.219502Z","caller":"traceutil/trace.go:171","msg":"trace[1530498964] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectzones/; range_end:/registry/ceph.rook.io/cephobjectzones0; response_count:0; response_revision:25798; }","duration":"227.799132ms","start":"2026-06-09T17:42:19.991692Z","end":"2026-06-09T17:42:20.219491Z","steps":["trace[1530498964] 'agreement among raft nodes before linearized reading' (duration: 227.742259ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:20.364431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.951038ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:42:20.364547Z","caller":"traceutil/trace.go:171","msg":"trace[680306165] linearizableReadLoop","detail":"{readStateIndex:26661; appliedIndex:26660; }","duration":"145.325745ms","start":"2026-06-09T17:42:20.219203Z","end":"2026-06-09T17:42:20.364529Z","steps":["trace[680306165] 'read index received' (duration: 12.193212ms)","trace[680306165] 'applied index is now lower than readState.Index' (duration: 133.130843ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:20.364573Z","caller":"traceutil/trace.go:171","msg":"trace[587461292] transaction","detail":"{read_only:false; response_revision:25799; number_of_response:1; }","duration":"315.296601ms","start":"2026-06-09T17:42:20.049237Z","end":"2026-06-09T17:42:20.364533Z","steps":["trace[587461292] 'process raft request' (duration: 182.136696ms)","trace[587461292] 'compare' (duration: 132.685001ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:20.364748Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.322303ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/neutron-l3-agent-default-s77g7.18b779e4c70ac052\" ","response":"range_response_count:1 size:949"} {"level":"info","ts":"2026-06-09T17:42:20.36479Z","caller":"traceutil/trace.go:171","msg":"trace[1782570966] range","detail":"{range_begin:/registry/events/openstack/neutron-l3-agent-default-s77g7.18b779e4c70ac052; range_end:; response_count:1; response_revision:25799; }","duration":"280.409216ms","start":"2026-06-09T17:42:20.084367Z","end":"2026-06-09T17:42:20.364776Z","steps":["trace[1782570966] 'agreement among raft nodes before linearized reading' (duration: 280.237032ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:20.365031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:20.0492Z","time spent":"315.476436ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:42:20.374101Z","caller":"traceutil/trace.go:171","msg":"trace[1129276353] transaction","detail":"{read_only:false; response_revision:25800; number_of_response:1; }","duration":"124.084745ms","start":"2026-06-09T17:42:20.249977Z","end":"2026-06-09T17:42:20.374062Z","steps":["trace[1129276353] 'process raft request' (duration: 123.845858ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:20.375126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.73114ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-09T17:42:20.375195Z","caller":"traceutil/trace.go:171","msg":"trace[1119894503] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25802; }","duration":"126.860273ms","start":"2026-06-09T17:42:20.248317Z","end":"2026-06-09T17:42:20.375178Z","steps":["trace[1119894503] 'agreement among raft nodes before linearized reading' (duration: 126.674498ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:20.375186Z","caller":"traceutil/trace.go:171","msg":"trace[1705366410] transaction","detail":"{read_only:false; response_revision:25801; number_of_response:1; }","duration":"123.301522ms","start":"2026-06-09T17:42:20.25185Z","end":"2026-06-09T17:42:20.375151Z","steps":["trace[1705366410] 'process raft request' (duration: 122.759336ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:20.687301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.949854ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:42:20.687613Z","caller":"traceutil/trace.go:171","msg":"trace[506799210] transaction","detail":"{read_only:false; response_revision:25804; number_of_response:1; }","duration":"306.550439ms","start":"2026-06-09T17:42:20.381039Z","end":"2026-06-09T17:42:20.687589Z","steps":["trace[506799210] 'process raft request' (duration: 306.448237ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:20.687721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:20.381022Z","time spent":"306.653673ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":544,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:42:20.68783Z","caller":"traceutil/trace.go:171","msg":"trace[1635001445] transaction","detail":"{read_only:false; response_revision:25803; number_of_response:1; }","duration":"307.587089ms","start":"2026-06-09T17:42:20.38002Z","end":"2026-06-09T17:42:20.687607Z","steps":["trace[1635001445] 'process raft request' (duration: 162.205242ms)","trace[1635001445] 'compare' (duration: 144.759068ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:20.688267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:20.379994Z","time spent":"308.052532ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:42:21.332989Z","caller":"traceutil/trace.go:171","msg":"trace[233124681] linearizableReadLoop","detail":"{readStateIndex:26668; appliedIndex:26667; }","duration":"147.777746ms","start":"2026-06-09T17:42:21.185176Z","end":"2026-06-09T17:42:21.332954Z","steps":["trace[233124681] 'read index received' (duration: 48.118496ms)","trace[233124681] 'applied index is now lower than readState.Index' (duration: 99.65564ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:21.333301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.485942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheusagents/\" range_end:\"/registry/monitoring.coreos.com/prometheusagents0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:42:21.333331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.712112ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/superstreams/\" range_end:\"/registry/rabbitmq.com/superstreams0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:21.333369Z","caller":"traceutil/trace.go:171","msg":"trace[406333433] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheusagents/; range_end:/registry/monitoring.coreos.com/prometheusagents0; response_count:0; response_revision:25806; }","duration":"143.625727ms","start":"2026-06-09T17:42:21.189728Z","end":"2026-06-09T17:42:21.333354Z","steps":["trace[406333433] 'agreement among raft nodes before linearized reading' (duration: 143.430331ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:21.333419Z","caller":"traceutil/trace.go:171","msg":"trace[131620987] range","detail":"{range_begin:/registry/rabbitmq.com/superstreams/; range_end:/registry/rabbitmq.com/superstreams0; response_count:0; response_revision:25806; }","duration":"107.853556ms","start":"2026-06-09T17:42:21.225543Z","end":"2026-06-09T17:42:21.333397Z","steps":["trace[131620987] 'agreement among raft nodes before linearized reading' (duration: 107.683911ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:21.333365Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.164748ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/objectbucket.io/objectbuckets/\" range_end:\"/registry/objectbucket.io/objectbuckets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:21.333614Z","caller":"traceutil/trace.go:171","msg":"trace[1732452882] range","detail":"{range_begin:/registry/objectbucket.io/objectbuckets/; range_end:/registry/objectbucket.io/objectbuckets0; response_count:0; response_revision:25806; }","duration":"148.424104ms","start":"2026-06-09T17:42:21.185103Z","end":"2026-06-09T17:42:21.333527Z","steps":["trace[1732452882] 'agreement among raft nodes before linearized reading' (duration: 148.008653ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:26.972445Z","caller":"traceutil/trace.go:171","msg":"trace[1006828740] transaction","detail":"{read_only:false; response_revision:25849; number_of_response:1; }","duration":"256.040434ms","start":"2026-06-09T17:42:26.716377Z","end":"2026-06-09T17:42:26.972417Z","steps":["trace[1006828740] 'process raft request' (duration: 255.849698ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:27.473365Z","caller":"traceutil/trace.go:171","msg":"trace[802769192] linearizableReadLoop","detail":"{readStateIndex:26714; appliedIndex:26713; }","duration":"105.9012ms","start":"2026-06-09T17:42:27.367441Z","end":"2026-06-09T17:42:27.473342Z","steps":["trace[802769192] 'read index received' (duration: 105.834388ms)","trace[802769192] 'applied index is now lower than readState.Index' (duration: 65.982µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:27.473537Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.071056ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:27.473593Z","caller":"traceutil/trace.go:171","msg":"trace[205256180] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25850; }","duration":"106.172308ms","start":"2026-06-09T17:42:27.367407Z","end":"2026-06-09T17:42:27.473579Z","steps":["trace[205256180] 'agreement among raft nodes before linearized reading' (duration: 106.063685ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:27.474022Z","caller":"traceutil/trace.go:171","msg":"trace[1991385305] transaction","detail":"{read_only:false; response_revision:25850; number_of_response:1; }","duration":"123.100946ms","start":"2026-06-09T17:42:27.350902Z","end":"2026-06-09T17:42:27.474003Z","steps":["trace[1991385305] 'process raft request' (duration: 122.296853ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:27.845667Z","caller":"traceutil/trace.go:171","msg":"trace[1609459670] transaction","detail":"{read_only:false; response_revision:25851; number_of_response:1; }","duration":"463.890081ms","start":"2026-06-09T17:42:27.381748Z","end":"2026-06-09T17:42:27.845638Z","steps":["trace[1609459670] 'process raft request' (duration: 448.799205ms)","trace[1609459670] 'compare' (duration: 14.52994ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:27.845797Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:27.381733Z","time spent":"463.992434ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:42:27.845795Z","caller":"traceutil/trace.go:171","msg":"trace[266476741] transaction","detail":"{read_only:false; response_revision:25852; number_of_response:1; }","duration":"462.305495ms","start":"2026-06-09T17:42:27.38345Z","end":"2026-06-09T17:42:27.845756Z","steps":["trace[266476741] 'process raft request' (duration: 462.100719ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:27.846077Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:27.383442Z","time spent":"462.435468ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:42:27.846119Z","caller":"traceutil/trace.go:171","msg":"trace[2049047793] linearizableReadLoop","detail":"{readStateIndex:26717; appliedIndex:26714; }","duration":"372.586521ms","start":"2026-06-09T17:42:27.47352Z","end":"2026-06-09T17:42:27.846107Z","steps":["trace[2049047793] 'read index received' (duration: 357.040773ms)","trace[2049047793] 'applied index is now lower than readState.Index' (duration: 15.544498ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:27.846182Z","caller":"traceutil/trace.go:171","msg":"trace[951476920] transaction","detail":"{read_only:false; response_revision:25853; number_of_response:1; }","duration":"461.150922ms","start":"2026-06-09T17:42:27.385019Z","end":"2026-06-09T17:42:27.84617Z","steps":["trace[951476920] 'process raft request' (duration: 460.670728ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:27.846248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:27.385011Z","time spent":"461.203194ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:42:27.846446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"471.52698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:27.846552Z","caller":"traceutil/trace.go:171","msg":"trace[1542605892] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinedeployments/; range_end:/registry/cluster.x-k8s.io/machinedeployments0; response_count:0; response_revision:25853; }","duration":"471.641303ms","start":"2026-06-09T17:42:27.374882Z","end":"2026-06-09T17:42:27.846523Z","steps":["trace[1542605892] 'agreement among raft nodes before linearized reading' (duration: 471.290223ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:27.846683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:27.374842Z","time spent":"471.790868ms","remote":"127.0.0.1:48492","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinedeployments/\" range_end:\"/registry/cluster.x-k8s.io/machinedeployments0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:42:27.8477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"372.243841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:27.847785Z","caller":"traceutil/trace.go:171","msg":"trace[1987383868] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25854; }","duration":"372.356624ms","start":"2026-06-09T17:42:27.475404Z","end":"2026-06-09T17:42:27.847761Z","steps":["trace[1987383868] 'agreement among raft nodes before linearized reading' (duration: 372.224119ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:27.847844Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:27.475382Z","time spent":"372.446667ms","remote":"127.0.0.1:50342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:42:27.848093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.46938ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:27.848603Z","caller":"traceutil/trace.go:171","msg":"trace[1037943529] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25854; }","duration":"130.023165ms","start":"2026-06-09T17:42:27.718557Z","end":"2026-06-09T17:42:27.84858Z","steps":["trace[1037943529] 'agreement among raft nodes before linearized reading' (duration: 129.471929ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:28.144139Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.933362ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5652"} {"level":"info","ts":"2026-06-09T17:42:28.144284Z","caller":"traceutil/trace.go:171","msg":"trace[1484663838] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:25854; }","duration":"197.123447ms","start":"2026-06-09T17:42:27.94712Z","end":"2026-06-09T17:42:28.144244Z","steps":["trace[1484663838] 'range keys from in-memory index tree' (duration: 196.336535ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:28.391044Z","caller":"traceutil/trace.go:171","msg":"trace[769058057] linearizableReadLoop","detail":"{readStateIndex:26719; appliedIndex:26718; }","duration":"137.15597ms","start":"2026-06-09T17:42:28.253864Z","end":"2026-06-09T17:42:28.39102Z","steps":["trace[769058057] 'read index received' (duration: 136.911463ms)","trace[769058057] 'applied index is now lower than readState.Index' (duration: 243.707µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:28.391181Z","caller":"traceutil/trace.go:171","msg":"trace[337141106] transaction","detail":"{read_only:false; response_revision:25855; number_of_response:1; }","duration":"228.487521ms","start":"2026-06-09T17:42:28.16267Z","end":"2026-06-09T17:42:28.391158Z","steps":["trace[337141106] 'process raft request' (duration: 228.205332ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:28.391413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.916803ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-09T17:42:28.391413Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.484631ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-09T17:42:28.391464Z","caller":"traceutil/trace.go:171","msg":"trace[1027884900] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25855; }","duration":"129.978775ms","start":"2026-06-09T17:42:28.261472Z","end":"2026-06-09T17:42:28.391451Z","steps":["trace[1027884900] 'agreement among raft nodes before linearized reading' (duration: 129.842561ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:28.391444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.161853ms","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-06-09T17:42:28.391517Z","caller":"traceutil/trace.go:171","msg":"trace[1238377385] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25855; }","duration":"135.293497ms","start":"2026-06-09T17:42:28.256205Z","end":"2026-06-09T17:42:28.391498Z","steps":["trace[1238377385] 'agreement among raft nodes before linearized reading' (duration: 135.072921ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:28.39152Z","caller":"traceutil/trace.go:171","msg":"trace[1273661334] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25855; }","duration":"137.677666ms","start":"2026-06-09T17:42:28.253821Z","end":"2026-06-09T17:42:28.391499Z","steps":["trace[1273661334] 'agreement among raft nodes before linearized reading' (duration: 137.341166ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:28.560275Z","caller":"traceutil/trace.go:171","msg":"trace[1344480351] transaction","detail":"{read_only:false; response_revision:25857; number_of_response:1; }","duration":"164.152418ms","start":"2026-06-09T17:42:28.39609Z","end":"2026-06-09T17:42:28.560242Z","steps":["trace[1344480351] 'process raft request' (duration: 163.807948ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:28.560542Z","caller":"traceutil/trace.go:171","msg":"trace[724020992] transaction","detail":"{read_only:false; response_revision:25859; number_of_response:1; }","duration":"149.850296ms","start":"2026-06-09T17:42:28.410679Z","end":"2026-06-09T17:42:28.560529Z","steps":["trace[724020992] 'process raft request' (duration: 149.710162ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:28.560539Z","caller":"traceutil/trace.go:171","msg":"trace[495089358] transaction","detail":"{read_only:false; response_revision:25858; number_of_response:1; }","duration":"164.389105ms","start":"2026-06-09T17:42:28.396114Z","end":"2026-06-09T17:42:28.560503Z","steps":["trace[495089358] 'process raft request' (duration: 164.068456ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:28.560572Z","caller":"traceutil/trace.go:171","msg":"trace[536449219] transaction","detail":"{read_only:false; response_revision:25860; number_of_response:1; }","duration":"149.010081ms","start":"2026-06-09T17:42:28.411556Z","end":"2026-06-09T17:42:28.560566Z","steps":["trace[536449219] 'process raft request' (duration: 148.887087ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:28.560545Z","caller":"traceutil/trace.go:171","msg":"trace[674721528] transaction","detail":"{read_only:false; response_revision:25861; number_of_response:1; }","duration":"148.855057ms","start":"2026-06-09T17:42:28.411674Z","end":"2026-06-09T17:42:28.56053Z","steps":["trace[674721528] 'process raft request' (duration: 148.818956ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:37.711639Z","caller":"traceutil/trace.go:171","msg":"trace[379092330] linearizableReadLoop","detail":"{readStateIndex:26848; appliedIndex:26847; }","duration":"114.120478ms","start":"2026-06-09T17:42:37.597496Z","end":"2026-06-09T17:42:37.711616Z","steps":["trace[379092330] 'read index received' (duration: 113.959493ms)","trace[379092330] 'applied index is now lower than readState.Index' (duration: 160.055µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:37.711806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.293942ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/roles/\" range_end:\"/registry/openstack.k-orc.cloud/roles0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:37.71177Z","caller":"traceutil/trace.go:171","msg":"trace[1504186999] transaction","detail":"{read_only:false; response_revision:25982; number_of_response:1; }","duration":"133.083004ms","start":"2026-06-09T17:42:37.578646Z","end":"2026-06-09T17:42:37.711729Z","steps":["trace[1504186999] 'process raft request' (duration: 132.806626ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:37.711834Z","caller":"traceutil/trace.go:171","msg":"trace[819789364] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/roles/; range_end:/registry/openstack.k-orc.cloud/roles0; response_count:0; response_revision:25982; }","duration":"114.382234ms","start":"2026-06-09T17:42:37.597442Z","end":"2026-06-09T17:42:37.711824Z","steps":["trace[819789364] 'agreement among raft nodes before linearized reading' (duration: 114.254321ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.108577Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.481099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:38.108689Z","caller":"traceutil/trace.go:171","msg":"trace[648568927] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:25982; }","duration":"229.635114ms","start":"2026-06-09T17:42:37.879026Z","end":"2026-06-09T17:42:38.108662Z","steps":["trace[648568927] 'range keys from in-memory index tree' (duration: 229.412727ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.108682Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"332.276029ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bff4e754-89e8-408f-aba8-51505b1330cd\" ","response":"range_response_count:1 size:3643"} {"level":"info","ts":"2026-06-09T17:42:38.108792Z","caller":"traceutil/trace.go:171","msg":"trace[2002274301] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bff4e754-89e8-408f-aba8-51505b1330cd; range_end:; response_count:1; response_revision:25982; }","duration":"332.454444ms","start":"2026-06-09T17:42:37.776312Z","end":"2026-06-09T17:42:38.108766Z","steps":["trace[2002274301] 'range keys from in-memory index tree' (duration: 332.002931ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.108848Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:37.776295Z","time spent":"332.539867ms","remote":"127.0.0.1:50562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3667,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bff4e754-89e8-408f-aba8-51505b1330cd\" "} {"level":"warn","ts":"2026-06-09T17:42:38.108865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.202574ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:42:38.108997Z","caller":"traceutil/trace.go:171","msg":"trace[1316396595] transaction","detail":"{read_only:false; response_revision:25983; number_of_response:1; }","duration":"213.787057ms","start":"2026-06-09T17:42:37.895186Z","end":"2026-06-09T17:42:38.108973Z","steps":["trace[1316396595] 'process raft request' (duration: 112.359736ms)","trace[1316396595] 'compare' (duration: 100.904366ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:38.413853Z","caller":"traceutil/trace.go:171","msg":"trace[1834890846] transaction","detail":"{read_only:false; response_revision:25985; number_of_response:1; }","duration":"516.194756ms","start":"2026-06-09T17:42:37.897634Z","end":"2026-06-09T17:42:38.413829Z","steps":["trace[1834890846] 'process raft request' (duration: 516.060933ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.414039Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:37.897612Z","time spent":"516.342881ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:42:38.414055Z","caller":"traceutil/trace.go:171","msg":"trace[252910105] linearizableReadLoop","detail":"{readStateIndex:26852; appliedIndex:26849; }","duration":"310.988057ms","start":"2026-06-09T17:42:38.103046Z","end":"2026-06-09T17:42:38.414034Z","steps":["trace[252910105] 'read index received' (duration: 304.639565ms)","trace[252910105] 'applied index is now lower than readState.Index' (duration: 6.347332ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:38.414165Z","caller":"traceutil/trace.go:171","msg":"trace[198452885] transaction","detail":"{read_only:false; response_revision:25986; number_of_response:1; }","duration":"515.504077ms","start":"2026-06-09T17:42:37.89865Z","end":"2026-06-09T17:42:38.414154Z","steps":["trace[198452885] 'process raft request' (duration: 515.139456ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:38.41415Z","caller":"traceutil/trace.go:171","msg":"trace[1545186313] transaction","detail":"{read_only:false; response_revision:25984; number_of_response:1; }","duration":"516.454434ms","start":"2026-06-09T17:42:37.897594Z","end":"2026-06-09T17:42:38.414048Z","steps":["trace[1545186313] 'process raft request' (duration: 510.07113ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.414309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:37.898642Z","time spent":"515.553909ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:42:38.414373Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:37.897571Z","time spent":"516.687291ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:42:38.414203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.154782ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:38.414501Z","caller":"traceutil/trace.go:171","msg":"trace[1850576112] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters0; response_count:0; response_revision:25986; }","duration":"311.498511ms","start":"2026-06-09T17:42:38.102985Z","end":"2026-06-09T17:42:38.414484Z","steps":["trace[1850576112] 'agreement among raft nodes before linearized reading' (duration: 311.181722ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.414556Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:38.102957Z","time spent":"311.583595ms","remote":"127.0.0.1:58858","response type":"/etcdserverpb.KV/Range","request count":0,"request size":126,"response count":0,"response size":30,"request content":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:42:38.745725Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.941796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:42:38.74591Z","caller":"traceutil/trace.go:171","msg":"trace[252258112] transaction","detail":"{read_only:false; response_revision:25987; number_of_response:1; }","duration":"626.454523ms","start":"2026-06-09T17:42:38.11941Z","end":"2026-06-09T17:42:38.745865Z","steps":["trace[252258112] 'process raft request' (duration: 449.2711ms)","trace[252258112] 'compare' (duration: 176.40007ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:38.746033Z","caller":"traceutil/trace.go:171","msg":"trace[272076356] transaction","detail":"{read_only:false; response_revision:25989; number_of_response:1; }","duration":"148.832696ms","start":"2026-06-09T17:42:38.597181Z","end":"2026-06-09T17:42:38.746014Z","steps":["trace[272076356] 'process raft request' (duration: 148.784065ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.746072Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:38.119387Z","time spent":"626.593297ms","remote":"127.0.0.1:50562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3742,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:42:38.746189Z","caller":"traceutil/trace.go:171","msg":"trace[1325626547] transaction","detail":"{read_only:false; response_revision:25988; number_of_response:1; }","duration":"149.002302ms","start":"2026-06-09T17:42:38.597178Z","end":"2026-06-09T17:42:38.746181Z","steps":["trace[1325626547] 'process raft request' (duration: 148.656572ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:38.746268Z","caller":"traceutil/trace.go:171","msg":"trace[965233708] linearizableReadLoop","detail":"{readStateIndex:26853; appliedIndex:26852; }","duration":"332.095265ms","start":"2026-06-09T17:42:38.414117Z","end":"2026-06-09T17:42:38.746213Z","steps":["trace[965233708] 'read index received' (duration: 154.576592ms)","trace[965233708] 'applied index is now lower than readState.Index' (duration: 177.509292ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:38.746469Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"631.491987ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:38.74655Z","caller":"traceutil/trace.go:171","msg":"trace[1856847920] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25989; }","duration":"631.640632ms","start":"2026-06-09T17:42:38.114888Z","end":"2026-06-09T17:42:38.746529Z","steps":["trace[1856847920] 'agreement among raft nodes before linearized reading' (duration: 631.484977ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.746596Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:38.114862Z","time spent":"631.723553ms","remote":"127.0.0.1:50572","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":30,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"warn","ts":"2026-06-09T17:42:38.747336Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"495.654275ms","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-06-09T17:42:38.747397Z","caller":"traceutil/trace.go:171","msg":"trace[543607950] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25989; }","duration":"495.759398ms","start":"2026-06-09T17:42:38.251624Z","end":"2026-06-09T17:42:38.747384Z","steps":["trace[543607950] 'agreement among raft nodes before linearized reading' (duration: 494.619636ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.747435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:38.251602Z","time spent":"495.82276ms","remote":"127.0.0.1:50562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12237,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-06-09T17:42:38.977991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.251001ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:42:38.978145Z","caller":"traceutil/trace.go:171","msg":"trace[294375355] linearizableReadLoop","detail":"{readStateIndex:26856; appliedIndex:26855; }","duration":"231.760655ms","start":"2026-06-09T17:42:38.74637Z","end":"2026-06-09T17:42:38.978131Z","steps":["trace[294375355] 'read index received' (duration: 117.269418ms)","trace[294375355] 'applied index is now lower than readState.Index' (duration: 114.490057ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:38.978221Z","caller":"traceutil/trace.go:171","msg":"trace[387756059] transaction","detail":"{read_only:false; response_revision:25990; number_of_response:1; }","duration":"377.284196ms","start":"2026-06-09T17:42:38.600925Z","end":"2026-06-09T17:42:38.978209Z","steps":["trace[387756059] 'process raft request' (duration: 262.710846ms)","trace[387756059] 'compare' (duration: 114.070866ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:38.978281Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:38.600895Z","time spent":"377.354039ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:42:38.978453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.001522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" ","response":"range_response_count:1 size:1850"} {"level":"info","ts":"2026-06-09T17:42:38.978489Z","caller":"traceutil/trace.go:171","msg":"trace[1275438400] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0; range_end:; response_count:1; response_revision:25991; }","duration":"349.077944ms","start":"2026-06-09T17:42:38.629396Z","end":"2026-06-09T17:42:38.978474Z","steps":["trace[1275438400] 'agreement among raft nodes before linearized reading' (duration: 348.94811ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.978519Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:38.629362Z","time spent":"349.148316ms","remote":"127.0.0.1:50524","response type":"/etcdserverpb.KV/Range","request count":0,"request size":79,"response count":1,"response size":1874,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-nova-server-0\" "} {"level":"warn","ts":"2026-06-09T17:42:38.978717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.412058ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-09T17:42:38.978749Z","caller":"traceutil/trace.go:171","msg":"trace[926221652] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:25991; }","duration":"371.48046ms","start":"2026-06-09T17:42:38.60726Z","end":"2026-06-09T17:42:38.978741Z","steps":["trace[926221652] 'agreement among raft nodes before linearized reading' (duration: 371.391357ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.978778Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:38.607242Z","time spent":"371.52886ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-06-09T17:42:38.97879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.326854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-06-09T17:42:38.978824Z","caller":"traceutil/trace.go:171","msg":"trace[38366779] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:25991; }","duration":"538.407507ms","start":"2026-06-09T17:42:38.440407Z","end":"2026-06-09T17:42:38.978815Z","steps":["trace[38366779] 'agreement among raft nodes before linearized reading' (duration: 538.315034ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.97885Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:38.440389Z","time spent":"538.453867ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":506,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-06-09T17:42:38.978953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"549.509647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:38.978986Z","caller":"traceutil/trace.go:171","msg":"trace[193097925] range","detail":"{range_begin:/registry/cluster.x-k8s.io/machinepools/; range_end:/registry/cluster.x-k8s.io/machinepools0; response_count:0; response_revision:25991; }","duration":"549.608259ms","start":"2026-06-09T17:42:38.429369Z","end":"2026-06-09T17:42:38.978977Z","steps":["trace[193097925] 'agreement among raft nodes before linearized reading' (duration: 549.526077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.979013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:38.429346Z","time spent":"549.65836ms","remote":"127.0.0.1:48422","response type":"/etcdserverpb.KV/Range","request count":0,"request size":86,"response count":0,"response size":30,"request content":"key:\"/registry/cluster.x-k8s.io/machinepools/\" range_end:\"/registry/cluster.x-k8s.io/machinepools0\" count_only:true "} {"level":"warn","ts":"2026-06-09T17:42:38.979033Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.989841ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-09T17:42:38.979061Z","caller":"traceutil/trace.go:171","msg":"trace[1324072898] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:25991; }","duration":"374.047353ms","start":"2026-06-09T17:42:38.605005Z","end":"2026-06-09T17:42:38.979052Z","steps":["trace[1324072898] 'agreement among raft nodes before linearized reading' (duration: 373.967181ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.979088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:38.604994Z","time spent":"374.087814ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":532,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-06-09T17:42:38.979243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.703828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"warn","ts":"2026-06-09T17:42:38.979258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.152848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bff4e754-89e8-408f-aba8-51505b1330cd\" ","response":"range_response_count:1 size:3760"} {"level":"info","ts":"2026-06-09T17:42:38.979275Z","caller":"traceutil/trace.go:171","msg":"trace[1504691893] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25991; }","duration":"227.797001ms","start":"2026-06-09T17:42:38.751469Z","end":"2026-06-09T17:42:38.979266Z","steps":["trace[1504691893] 'agreement among raft nodes before linearized reading' (duration: 227.704828ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:38.979284Z","caller":"traceutil/trace.go:171","msg":"trace[865260312] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bff4e754-89e8-408f-aba8-51505b1330cd; range_end:; response_count:1; response_revision:25991; }","duration":"406.21791ms","start":"2026-06-09T17:42:38.573058Z","end":"2026-06-09T17:42:38.979276Z","steps":["trace[865260312] 'agreement among raft nodes before linearized reading' (duration: 406.134937ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.979309Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:38.573029Z","time spent":"406.272382ms","remote":"127.0.0.1:50562","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3784,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bff4e754-89e8-408f-aba8-51505b1330cd\" "} {"level":"warn","ts":"2026-06-09T17:42:38.97942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"426.811353ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" ","response":"range_response_count:1 size:3309"} {"level":"info","ts":"2026-06-09T17:42:38.979458Z","caller":"traceutil/trace.go:171","msg":"trace[1921198329] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila; range_end:; response_count:1; response_revision:25991; }","duration":"426.881035ms","start":"2026-06-09T17:42:38.552566Z","end":"2026-06-09T17:42:38.979447Z","steps":["trace[1921198329] 'agreement among raft nodes before linearized reading' (duration: 426.791472ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.979484Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:38.552544Z","time spent":"426.932286ms","remote":"127.0.0.1:43634","response type":"/etcdserverpb.KV/Range","request count":0,"request size":67,"response count":1,"response size":3333,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-manila\" "} {"level":"warn","ts":"2026-06-09T17:42:38.979594Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.829985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:38.979634Z","caller":"traceutil/trace.go:171","msg":"trace[776094128] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25991; }","duration":"224.889497ms","start":"2026-06-09T17:42:38.754736Z","end":"2026-06-09T17:42:38.979625Z","steps":["trace[776094128] 'agreement among raft nodes before linearized reading' (duration: 224.827435ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.97968Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"461.408038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-06-09T17:42:38.979709Z","caller":"traceutil/trace.go:171","msg":"trace[1344399272] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:25991; }","duration":"461.4743ms","start":"2026-06-09T17:42:38.518226Z","end":"2026-06-09T17:42:38.9797Z","steps":["trace[1344399272] 'agreement among raft nodes before linearized reading' (duration: 461.396128ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:38.979733Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:42:38.518208Z","time spent":"461.519382ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":465,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-06-09T17:42:39.160015Z","caller":"traceutil/trace.go:171","msg":"trace[922853044] linearizableReadLoop","detail":"{readStateIndex:26861; appliedIndex:26858; }","duration":"153.425209ms","start":"2026-06-09T17:42:39.00656Z","end":"2026-06-09T17:42:39.159985Z","steps":["trace[922853044] 'read index received' (duration: 135.192863ms)","trace[922853044] 'applied index is now lower than readState.Index' (duration: 18.230986ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:39.160405Z","caller":"traceutil/trace.go:171","msg":"trace[1811135964] transaction","detail":"{read_only:false; response_revision:25994; number_of_response:1; }","duration":"175.123734ms","start":"2026-06-09T17:42:38.985249Z","end":"2026-06-09T17:42:39.160373Z","steps":["trace[1811135964] 'process raft request' (duration: 174.62581ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:39.160397Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.277009ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:39.160432Z","caller":"traceutil/trace.go:171","msg":"trace[1429074711] transaction","detail":"{read_only:false; response_revision:25995; number_of_response:1; }","duration":"173.873107ms","start":"2026-06-09T17:42:38.98652Z","end":"2026-06-09T17:42:39.160393Z","steps":["trace[1429074711] 'process raft request' (duration: 173.403514ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:39.1605Z","caller":"traceutil/trace.go:171","msg":"trace[412558591] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25995; }","duration":"174.416843ms","start":"2026-06-09T17:42:38.986061Z","end":"2026-06-09T17:42:39.160478Z","steps":["trace[412558591] 'agreement among raft nodes before linearized reading' (duration: 174.217077ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:39.160495Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"174.430324ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/rabbitmq-manila-default-user\" ","response":"range_response_count:1 size:1312"} {"level":"info","ts":"2026-06-09T17:42:39.160549Z","caller":"traceutil/trace.go:171","msg":"trace[1669085571] range","detail":"{range_begin:/registry/secrets/openstack/rabbitmq-manila-default-user; range_end:; response_count:1; response_revision:25995; }","duration":"174.508575ms","start":"2026-06-09T17:42:38.986023Z","end":"2026-06-09T17:42:39.160532Z","steps":["trace[1669085571] 'agreement among raft nodes before linearized reading' (duration: 174.3185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:39.160557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.460371ms","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-06-09T17:42:39.160607Z","caller":"traceutil/trace.go:171","msg":"trace[877951459] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25995; }","duration":"119.567954ms","start":"2026-06-09T17:42:39.041021Z","end":"2026-06-09T17:42:39.160589Z","steps":["trace[877951459] 'agreement among raft nodes before linearized reading' (duration: 119.395959ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:39.160581Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.162558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:39.16063Z","caller":"traceutil/trace.go:171","msg":"trace[1041000249] transaction","detail":"{read_only:false; response_revision:25993; number_of_response:1; }","duration":"175.875786ms","start":"2026-06-09T17:42:38.984715Z","end":"2026-06-09T17:42:39.160591Z","steps":["trace[1041000249] 'process raft request' (duration: 156.971911ms)","trace[1041000249] 'compare' (duration: 17.797113ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:39.160675Z","caller":"traceutil/trace.go:171","msg":"trace[1469337344] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25995; }","duration":"106.322052ms","start":"2026-06-09T17:42:39.054331Z","end":"2026-06-09T17:42:39.160653Z","steps":["trace[1469337344] 'agreement among raft nodes before linearized reading' (duration: 106.161607ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:39.435818Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.149878ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:42:39.435956Z","caller":"traceutil/trace.go:171","msg":"trace[1784295504] linearizableReadLoop","detail":"{readStateIndex:26862; appliedIndex:26861; }","duration":"270.426218ms","start":"2026-06-09T17:42:39.165509Z","end":"2026-06-09T17:42:39.435935Z","steps":["trace[1784295504] 'read index received' (duration: 106.832897ms)","trace[1784295504] 'applied index is now lower than readState.Index' (duration: 163.591321ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:39.435995Z","caller":"traceutil/trace.go:171","msg":"trace[558783284] transaction","detail":"{read_only:false; response_revision:25996; number_of_response:1; }","duration":"270.848761ms","start":"2026-06-09T17:42:39.165102Z","end":"2026-06-09T17:42:39.435951Z","steps":["trace[558783284] 'process raft request' (duration: 107.460136ms)","trace[558783284] 'compare' (duration: 162.926332ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:39.436116Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.584002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:39.436181Z","caller":"traceutil/trace.go:171","msg":"trace[254792199] range","detail":"{range_begin:/registry/services/specs/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25996; }","duration":"270.699915ms","start":"2026-06-09T17:42:39.165464Z","end":"2026-06-09T17:42:39.436164Z","steps":["trace[254792199] 'agreement among raft nodes before linearized reading' (duration: 270.555791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:39.436282Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"270.375617ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/openstack/rabbitmq-manila-nodes\" ","response":"range_response_count:1 size:1258"} {"level":"info","ts":"2026-06-09T17:42:39.436379Z","caller":"traceutil/trace.go:171","msg":"trace[282055394] range","detail":"{range_begin:/registry/services/specs/openstack/rabbitmq-manila-nodes; range_end:; response_count:1; response_revision:25996; }","duration":"270.535711ms","start":"2026-06-09T17:42:39.165823Z","end":"2026-06-09T17:42:39.436359Z","steps":["trace[282055394] 'agreement among raft nodes before linearized reading' (duration: 270.250133ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:41.101688Z","caller":"traceutil/trace.go:171","msg":"trace[1410432083] linearizableReadLoop","detail":"{readStateIndex:26875; appliedIndex:26874; }","duration":"108.290689ms","start":"2026-06-09T17:42:40.993361Z","end":"2026-06-09T17:42:41.101652Z","steps":["trace[1410432083] 'read index received' (duration: 108.048033ms)","trace[1410432083] 'applied index is now lower than readState.Index' (duration: 241.046µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:41.101824Z","caller":"traceutil/trace.go:171","msg":"trace[2041129053] transaction","detail":"{read_only:false; response_revision:26009; number_of_response:1; }","duration":"116.62161ms","start":"2026-06-09T17:42:40.98514Z","end":"2026-06-09T17:42:41.101762Z","steps":["trace[2041129053] 'process raft request' (duration: 116.28843ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:41.102215Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.811154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-bff4e754-89e8-408f-aba8-51505b1330cd\" ","response":"range_response_count:1 size:3946"} {"level":"info","ts":"2026-06-09T17:42:41.102289Z","caller":"traceutil/trace.go:171","msg":"trace[1907928867] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-bff4e754-89e8-408f-aba8-51505b1330cd; range_end:; response_count:1; response_revision:26009; }","duration":"108.939948ms","start":"2026-06-09T17:42:40.993329Z","end":"2026-06-09T17:42:41.102268Z","steps":["trace[1907928867] 'agreement among raft nodes before linearized reading' (duration: 108.515726ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:42:46.978937Z","caller":"traceutil/trace.go:171","msg":"trace[559131727] transaction","detail":"{read_only:false; response_revision:26079; number_of_response:1; }","duration":"203.316047ms","start":"2026-06-09T17:42:46.775589Z","end":"2026-06-09T17:42:46.978905Z","steps":["trace[559131727] 'process raft request' (duration: 131.437796ms)","trace[559131727] 'compare' (duration: 71.674755ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:51.39991Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.187152ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:30"} {"level":"info","ts":"2026-06-09T17:42:51.401601Z","caller":"traceutil/trace.go:171","msg":"trace[593244119] linearizableReadLoop","detail":"{readStateIndex:26979; appliedIndex:26977; }","duration":"140.730263ms","start":"2026-06-09T17:42:51.260849Z","end":"2026-06-09T17:42:51.40158Z","steps":["trace[593244119] 'read index received' (duration: 54.781µs)","trace[593244119] 'applied index is now lower than readState.Index' (duration: 140.674242ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:51.401763Z","caller":"traceutil/trace.go:171","msg":"trace[624806409] transaction","detail":"{read_only:false; response_revision:26110; number_of_response:1; }","duration":"247.57556ms","start":"2026-06-09T17:42:51.154169Z","end":"2026-06-09T17:42:51.401745Z","steps":["trace[624806409] 'process raft request' (duration: 245.948594ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:42:51.469024Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.159551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:51.469092Z","caller":"traceutil/trace.go:171","msg":"trace[446537628] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26110; }","duration":"153.268535ms","start":"2026-06-09T17:42:51.315805Z","end":"2026-06-09T17:42:51.469074Z","steps":["trace[446537628] 'agreement among raft nodes before linearized reading' (duration: 86.153621ms)","trace[446537628] 'range keys from in-memory index tree' (duration: 67.014259ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:51.469267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.470069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-06-09T17:42:51.469288Z","caller":"traceutil/trace.go:171","msg":"trace[531602201] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:26110; }","duration":"205.50457ms","start":"2026-06-09T17:42:51.263778Z","end":"2026-06-09T17:42:51.469282Z","steps":["trace[531602201] 'agreement among raft nodes before linearized reading' (duration: 138.18506ms)","trace[531602201] 'range keys from in-memory index tree' (duration: 67.242757ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:51.46938Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.705231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:51.469426Z","caller":"traceutil/trace.go:171","msg":"trace[370001788] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26110; }","duration":"100.792193ms","start":"2026-06-09T17:42:51.368621Z","end":"2026-06-09T17:42:51.469413Z","steps":["trace[370001788] 'agreement among raft nodes before linearized reading' (duration: 33.371481ms)","trace[370001788] 'range keys from in-memory index tree' (duration: 67.360891ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:51.469527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.885495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:436"} {"level":"warn","ts":"2026-06-09T17:42:51.46959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.347317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephfilesystems/\" range_end:\"/registry/ceph.rook.io/cephfilesystems0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:42:51.469594Z","caller":"traceutil/trace.go:171","msg":"trace[1864536999] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26110; }","duration":"201.970937ms","start":"2026-06-09T17:42:51.267603Z","end":"2026-06-09T17:42:51.469574Z","steps":["trace[1864536999] 'agreement among raft nodes before linearized reading' (duration: 134.37393ms)","trace[1864536999] 'range keys from in-memory index tree' (duration: 67.437533ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:42:51.469619Z","caller":"traceutil/trace.go:171","msg":"trace[1241535175] range","detail":"{range_begin:/registry/ceph.rook.io/cephfilesystems/; range_end:/registry/ceph.rook.io/cephfilesystems0; response_count:0; response_revision:26110; }","duration":"203.381409ms","start":"2026-06-09T17:42:51.266226Z","end":"2026-06-09T17:42:51.469607Z","steps":["trace[1241535175] 'agreement among raft nodes before linearized reading' (duration: 135.760071ms)","trace[1241535175] 'count revisions from in-memory index tree' (duration: 67.169425ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:51.469717Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.868846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-06-09T17:42:51.469738Z","caller":"traceutil/trace.go:171","msg":"trace[57694203] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26110; }","duration":"208.923397ms","start":"2026-06-09T17:42:51.260808Z","end":"2026-06-09T17:42:51.469731Z","steps":["trace[57694203] 'agreement among raft nodes before linearized reading' (duration: 141.167896ms)","trace[57694203] 'range keys from in-memory index tree' (duration: 67.70267ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:42:51.469527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.353788ms","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-06-09T17:42:51.469799Z","caller":"traceutil/trace.go:171","msg":"trace[920121176] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:26110; }","duration":"203.672957ms","start":"2026-06-09T17:42:51.266119Z","end":"2026-06-09T17:42:51.469792Z","steps":["trace[920121176] 'agreement among raft nodes before linearized reading' (duration: 135.873604ms)","trace[920121176] 'range keys from in-memory index tree' (duration: 67.421182ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:43:09.403894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.105261ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:43:09.404005Z","caller":"traceutil/trace.go:171","msg":"trace[140075778] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:26256; }","duration":"169.267575ms","start":"2026-06-09T17:43:09.234707Z","end":"2026-06-09T17:43:09.403975Z","steps":["trace[140075778] 'range keys from in-memory index tree' (duration: 169.029249ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:09.628077Z","caller":"traceutil/trace.go:171","msg":"trace[1522465535] transaction","detail":"{read_only:false; response_revision:26257; number_of_response:1; }","duration":"158.856346ms","start":"2026-06-09T17:43:09.469189Z","end":"2026-06-09T17:43:09.628045Z","steps":["trace[1522465535] 'process raft request' (duration: 158.700531ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:09.859331Z","caller":"traceutil/trace.go:171","msg":"trace[351607556] transaction","detail":"{read_only:false; response_revision:26260; number_of_response:1; }","duration":"226.02812ms","start":"2026-06-09T17:43:09.633273Z","end":"2026-06-09T17:43:09.859301Z","steps":["trace[351607556] 'process raft request' (duration: 225.914726ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:09.859542Z","caller":"traceutil/trace.go:171","msg":"trace[847397136] transaction","detail":"{read_only:false; response_revision:26259; number_of_response:1; }","duration":"226.287087ms","start":"2026-06-09T17:43:09.633238Z","end":"2026-06-09T17:43:09.859525Z","steps":["trace[847397136] 'process raft request' (duration: 182.141166ms)","trace[847397136] 'compare' (duration: 43.673017ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:43:09.859726Z","caller":"traceutil/trace.go:171","msg":"trace[1450610735] transaction","detail":"{read_only:false; response_revision:26261; number_of_response:1; }","duration":"226.010859ms","start":"2026-06-09T17:43:09.633692Z","end":"2026-06-09T17:43:09.859703Z","steps":["trace[1450610735] 'process raft request' (duration: 225.558706ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:09.859755Z","caller":"traceutil/trace.go:171","msg":"trace[855538547] transaction","detail":"{read_only:false; response_revision:26262; number_of_response:1; }","duration":"224.790224ms","start":"2026-06-09T17:43:09.634932Z","end":"2026-06-09T17:43:09.859722Z","steps":["trace[855538547] 'process raft request' (duration: 224.659051ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:09.859761Z","caller":"traceutil/trace.go:171","msg":"trace[170570148] transaction","detail":"{read_only:false; response_revision:26263; number_of_response:1; }","duration":"160.629976ms","start":"2026-06-09T17:43:09.699109Z","end":"2026-06-09T17:43:09.859739Z","steps":["trace[170570148] 'process raft request' (duration: 160.582365ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:09.859768Z","caller":"traceutil/trace.go:171","msg":"trace[1455189056] linearizableReadLoop","detail":"{readStateIndex:27135; appliedIndex:27131; }","duration":"166.863646ms","start":"2026-06-09T17:43:09.692875Z","end":"2026-06-09T17:43:09.859738Z","steps":["trace[1455189056] 'read index received' (duration: 122.52043ms)","trace[1455189056] 'applied index is now lower than readState.Index' (duration: 44.339736ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:43:09.859888Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.982389ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/openstack/\" range_end:\"/registry/resourcequotas/openstack0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:43:09.859947Z","caller":"traceutil/trace.go:171","msg":"trace[1879151882] range","detail":"{range_begin:/registry/resourcequotas/openstack/; range_end:/registry/resourcequotas/openstack0; response_count:0; response_revision:26263; }","duration":"167.074481ms","start":"2026-06-09T17:43:09.692857Z","end":"2026-06-09T17:43:09.859931Z","steps":["trace[1879151882] 'agreement among raft nodes before linearized reading' (duration: 166.955498ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:43:09.860434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.643345ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/\" range_end:\"/registry/deployments/openstack0\" ","response":"range_response_count:32 size:273205"} {"level":"info","ts":"2026-06-09T17:43:09.860504Z","caller":"traceutil/trace.go:171","msg":"trace[707902963] range","detail":"{range_begin:/registry/deployments/openstack/; range_end:/registry/deployments/openstack0; response_count:32; response_revision:26263; }","duration":"145.759518ms","start":"2026-06-09T17:43:09.714727Z","end":"2026-06-09T17:43:09.860486Z","steps":["trace[707902963] 'agreement among raft nodes before linearized reading' (duration: 145.217452ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:43:10.136681Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.308789ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-06-09T17:43:10.136774Z","caller":"traceutil/trace.go:171","msg":"trace[1796786215] linearizableReadLoop","detail":"{readStateIndex:27137; appliedIndex:27136; }","duration":"161.928634ms","start":"2026-06-09T17:43:09.974831Z","end":"2026-06-09T17:43:10.13676Z","steps":["trace[1796786215] 'read index received' (duration: 37.265724ms)","trace[1796786215] 'applied index is now lower than readState.Index' (duration: 124.66145ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:43:10.13685Z","caller":"traceutil/trace.go:171","msg":"trace[1418156855] transaction","detail":"{read_only:false; response_revision:26264; number_of_response:1; }","duration":"183.3405ms","start":"2026-06-09T17:43:09.953499Z","end":"2026-06-09T17:43:10.13684Z","steps":["trace[1418156855] 'process raft request' (duration: 58.608938ms)","trace[1418156855] 'compare' (duration: 123.95784ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:43:10.137148Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.304485ms","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-06-09T17:43:10.137201Z","caller":"traceutil/trace.go:171","msg":"trace[1606789618] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:26264; }","duration":"162.400178ms","start":"2026-06-09T17:43:09.974788Z","end":"2026-06-09T17:43:10.137189Z","steps":["trace[1606789618] 'agreement among raft nodes before linearized reading' (duration: 162.236023ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:10.574384Z","caller":"traceutil/trace.go:171","msg":"trace[1629572602] linearizableReadLoop","detail":"{readStateIndex:27138; appliedIndex:27137; }","duration":"204.046336ms","start":"2026-06-09T17:43:10.370315Z","end":"2026-06-09T17:43:10.574361Z","steps":["trace[1629572602] 'read index received' (duration: 203.925192ms)","trace[1629572602] 'applied index is now lower than readState.Index' (duration: 119.944µs)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:43:10.574501Z","caller":"traceutil/trace.go:171","msg":"trace[1704535241] transaction","detail":"{read_only:false; response_revision:26265; number_of_response:1; }","duration":"223.181258ms","start":"2026-06-09T17:43:10.351286Z","end":"2026-06-09T17:43:10.574468Z","steps":["trace[1704535241] 'process raft request' (duration: 222.90997ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:43:10.574568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.229952ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:43:10.574601Z","caller":"traceutil/trace.go:171","msg":"trace[1329827670] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26265; }","duration":"204.308304ms","start":"2026-06-09T17:43:10.370281Z","end":"2026-06-09T17:43:10.574589Z","steps":["trace[1329827670] 'agreement among raft nodes before linearized reading' (duration: 204.173469ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:11.043228Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a06.wal"} {"level":"info","ts":"2026-06-09T17:43:11.043856Z","caller":"traceutil/trace.go:171","msg":"trace[1842824612] transaction","detail":"{read_only:false; response_revision:26266; number_of_response:1; }","duration":"500.924688ms","start":"2026-06-09T17:43:10.542895Z","end":"2026-06-09T17:43:11.043819Z","steps":["trace[1842824612] 'process raft request' (duration: 500.594758ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:43:11.044046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:43:10.542862Z","time spent":"501.083531ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:43:11.044707Z","caller":"traceutil/trace.go:171","msg":"trace[1661363135] transaction","detail":"{read_only:false; response_revision:26267; number_of_response:1; }","duration":"501.579256ms","start":"2026-06-09T17:43:10.543114Z","end":"2026-06-09T17:43:11.044693Z","steps":["trace[1661363135] 'process raft request' (duration: 500.624849ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:43:11.044784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:43:10.543101Z","time spent":"501.644358ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T17:43:11.044951Z","caller":"traceutil/trace.go:171","msg":"trace[1863726669] transaction","detail":"{read_only:false; response_revision:26268; number_of_response:1; }","duration":"499.850105ms","start":"2026-06-09T17:43:10.545073Z","end":"2026-06-09T17:43:11.044924Z","steps":["trace[1863726669] 'process raft request' (duration: 499.561377ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:11.045007Z","caller":"traceutil/trace.go:171","msg":"trace[1518798802] linearizableReadLoop","detail":"{readStateIndex:27141; appliedIndex:27138; }","duration":"469.426419ms","start":"2026-06-09T17:43:10.575569Z","end":"2026-06-09T17:43:11.044995Z","steps":["trace[1518798802] 'read index received' (duration: 467.861525ms)","trace[1518798802] 'applied index is now lower than readState.Index' (duration: 1.563274ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:43:11.045093Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:43:10.545018Z","time spent":"499.99794ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-06-09T17:43:11.045204Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.626006ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T17:43:11.045253Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.020815ms","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:6"} {"level":"info","ts":"2026-06-09T17:43:11.045244Z","caller":"traceutil/trace.go:171","msg":"trace[630099107] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:26269; }","duration":"469.686108ms","start":"2026-06-09T17:43:10.575547Z","end":"2026-06-09T17:43:11.045233Z","steps":["trace[630099107] 'agreement among raft nodes before linearized reading' (duration: 469.531173ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:11.045299Z","caller":"traceutil/trace.go:171","msg":"trace[215194035] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/httproutes/; range_end:/registry/gateway.networking.k8s.io/httproutes0; response_count:0; response_revision:26269; }","duration":"264.125658ms","start":"2026-06-09T17:43:10.781163Z","end":"2026-06-09T17:43:11.045289Z","steps":["trace[215194035] 'agreement among raft nodes before linearized reading' (duration: 264.027195ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:43:11.045311Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:43:10.575533Z","time spent":"469.7699ms","remote":"127.0.0.1:50342","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":30,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-06-09T17:43:11.045315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.020231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T17:43:11.045379Z","caller":"traceutil/trace.go:171","msg":"trace[819801714] range","detail":"{range_begin:/registry/ceph.rook.io/cephclusters/; range_end:/registry/ceph.rook.io/cephclusters0; response_count:0; response_revision:26269; }","duration":"317.197227ms","start":"2026-06-09T17:43:10.728167Z","end":"2026-06-09T17:43:11.045364Z","steps":["trace[819801714] 'agreement among raft nodes before linearized reading' (duration: 317.017272ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:43:11.045424Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T17:43:10.728139Z","time spent":"317.275708ms","remote":"127.0.0.1:36228","response type":"/etcdserverpb.KV/Range","request count":0,"request size":78,"response count":1,"response size":32,"request content":"key:\"/registry/ceph.rook.io/cephclusters/\" range_end:\"/registry/ceph.rook.io/cephclusters0\" count_only:true "} {"level":"info","ts":"2026-06-09T17:43:13.753397Z","caller":"traceutil/trace.go:171","msg":"trace[298802266] transaction","detail":"{read_only:false; response_revision:26285; number_of_response:1; }","duration":"112.475159ms","start":"2026-06-09T17:43:13.640898Z","end":"2026-06-09T17:43:13.753373Z","steps":["trace[298802266] 'process raft request' (duration: 111.886762ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:13.893877Z","caller":"traceutil/trace.go:171","msg":"trace[487618094] transaction","detail":"{read_only:false; response_revision:26286; number_of_response:1; }","duration":"134.777852ms","start":"2026-06-09T17:43:13.759075Z","end":"2026-06-09T17:43:13.893853Z","steps":["trace[487618094] 'process raft request' (duration: 66.444974ms)","trace[487618094] 'compare' (duration: 67.836014ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:43:14.123048Z","caller":"traceutil/trace.go:171","msg":"trace[574486347] transaction","detail":"{read_only:false; response_revision:26289; number_of_response:1; }","duration":"224.350402ms","start":"2026-06-09T17:43:13.898652Z","end":"2026-06-09T17:43:14.123003Z","steps":["trace[574486347] 'process raft request' (duration: 193.761371ms)","trace[574486347] 'compare' (duration: 30.152869ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:43:14.123467Z","caller":"traceutil/trace.go:171","msg":"trace[755523552] transaction","detail":"{read_only:false; response_revision:26290; number_of_response:1; }","duration":"224.713302ms","start":"2026-06-09T17:43:13.898736Z","end":"2026-06-09T17:43:14.123449Z","steps":["trace[755523552] 'process raft request' (duration: 224.141265ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:14.12372Z","caller":"traceutil/trace.go:171","msg":"trace[314013594] transaction","detail":"{read_only:false; response_revision:26291; number_of_response:1; }","duration":"224.594418ms","start":"2026-06-09T17:43:13.899097Z","end":"2026-06-09T17:43:14.123691Z","steps":["trace[314013594] 'process raft request' (duration: 224.108725ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:15.219184Z","caller":"traceutil/trace.go:171","msg":"trace[955117803] transaction","detail":"{read_only:false; response_revision:26294; number_of_response:1; }","duration":"159.821653ms","start":"2026-06-09T17:43:15.059336Z","end":"2026-06-09T17:43:15.219157Z","steps":["trace[955117803] 'process raft request' (duration: 159.653558ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:15.219508Z","caller":"traceutil/trace.go:171","msg":"trace[1112419427] transaction","detail":"{read_only:false; response_revision:26296; number_of_response:1; }","duration":"159.100412ms","start":"2026-06-09T17:43:15.060383Z","end":"2026-06-09T17:43:15.219483Z","steps":["trace[1112419427] 'process raft request' (duration: 158.983869ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:15.219566Z","caller":"traceutil/trace.go:171","msg":"trace[1933793476] transaction","detail":"{read_only:false; response_revision:26295; number_of_response:1; }","duration":"159.344529ms","start":"2026-06-09T17:43:15.060211Z","end":"2026-06-09T17:43:15.219555Z","steps":["trace[1933793476] 'process raft request' (duration: 158.914836ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:15.257857Z","caller":"traceutil/trace.go:171","msg":"trace[863556729] transaction","detail":"{read_only:false; response_revision:26297; number_of_response:1; }","duration":"115.057274ms","start":"2026-06-09T17:43:15.142768Z","end":"2026-06-09T17:43:15.257826Z","steps":["trace[863556729] 'process raft request' (duration: 114.347013ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:16.064427Z","caller":"traceutil/trace.go:171","msg":"trace[233133844] transaction","detail":"{read_only:false; response_revision:26301; number_of_response:1; }","duration":"162.067708ms","start":"2026-06-09T17:43:15.902312Z","end":"2026-06-09T17:43:16.06438Z","steps":["trace[233133844] 'process raft request' (duration: 126.202975ms)","trace[233133844] 'compare' (duration: 35.658887ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:43:16.205567Z","caller":"traceutil/trace.go:171","msg":"trace[2132560330] transaction","detail":"{read_only:false; response_revision:26302; number_of_response:1; }","duration":"135.317368ms","start":"2026-06-09T17:43:16.070214Z","end":"2026-06-09T17:43:16.205532Z","steps":["trace[2132560330] 'process raft request' (duration: 135.066161ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:16.273595Z","caller":"traceutil/trace.go:171","msg":"trace[24238704] transaction","detail":"{read_only:false; response_revision:26303; number_of_response:1; }","duration":"109.610316ms","start":"2026-06-09T17:43:16.163956Z","end":"2026-06-09T17:43:16.273566Z","steps":["trace[24238704] 'process raft request' (duration: 109.38353ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:43:16.274287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.963076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"warn","ts":"2026-06-09T17:43:16.274327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.178982ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-06-09T17:43:16.274359Z","caller":"traceutil/trace.go:171","msg":"trace[1603348353] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:26303; }","duration":"144.104129ms","start":"2026-06-09T17:43:16.130237Z","end":"2026-06-09T17:43:16.274341Z","steps":["trace[1603348353] 'agreement among raft nodes before linearized reading' (duration: 143.812911ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:16.274387Z","caller":"traceutil/trace.go:171","msg":"trace[281974396] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:26303; }","duration":"144.272055ms","start":"2026-06-09T17:43:16.130091Z","end":"2026-06-09T17:43:16.274363Z","steps":["trace[281974396] 'agreement among raft nodes before linearized reading' (duration: 144.021408ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:16.373228Z","caller":"traceutil/trace.go:171","msg":"trace[349465887] transaction","detail":"{read_only:false; response_revision:26304; number_of_response:1; }","duration":"160.928895ms","start":"2026-06-09T17:43:16.212252Z","end":"2026-06-09T17:43:16.373181Z","steps":["trace[349465887] 'process raft request' (duration: 93.612746ms)","trace[349465887] 'compare' (duration: 67.139914ms)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:43:16.373582Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.985028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-06-09T17:43:16.373615Z","caller":"traceutil/trace.go:171","msg":"trace[1837137698] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:26305; }","duration":"152.092021ms","start":"2026-06-09T17:43:16.221513Z","end":"2026-06-09T17:43:16.373605Z","steps":["trace[1837137698] 'agreement among raft nodes before linearized reading' (duration: 151.675838ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:37.313056Z","caller":"traceutil/trace.go:171","msg":"trace[1680011260] transaction","detail":"{read_only:false; response_revision:26469; number_of_response:1; }","duration":"108.446792ms","start":"2026-06-09T17:43:37.204581Z","end":"2026-06-09T17:43:37.313028Z","steps":["trace[1680011260] 'process raft request' (duration: 108.245556ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:37.589489Z","caller":"traceutil/trace.go:171","msg":"trace[326401910] transaction","detail":"{read_only:false; response_revision:26470; number_of_response:1; }","duration":"250.65477ms","start":"2026-06-09T17:43:37.338782Z","end":"2026-06-09T17:43:37.589437Z","steps":["trace[326401910] 'process raft request' (duration: 170.378577ms)","trace[326401910] 'compare' (duration: 80.054596ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:43:37.597024Z","caller":"traceutil/trace.go:171","msg":"trace[831172100] transaction","detail":"{read_only:false; response_revision:26471; number_of_response:1; }","duration":"254.098709ms","start":"2026-06-09T17:43:37.342907Z","end":"2026-06-09T17:43:37.597006Z","steps":["trace[831172100] 'process raft request' (duration: 253.968675ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:37.597354Z","caller":"traceutil/trace.go:171","msg":"trace[1484252746] linearizableReadLoop","detail":"{readStateIndex:27352; appliedIndex:27348; }","duration":"104.815629ms","start":"2026-06-09T17:43:37.492515Z","end":"2026-06-09T17:43:37.597331Z","steps":["trace[1484252746] 'read index received' (duration: 16.660649ms)","trace[1484252746] 'applied index is now lower than readState.Index' (duration: 88.15421ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:43:37.597379Z","caller":"traceutil/trace.go:171","msg":"trace[977381971] transaction","detail":"{read_only:false; response_revision:26472; number_of_response:1; }","duration":"253.80001ms","start":"2026-06-09T17:43:37.343474Z","end":"2026-06-09T17:43:37.597274Z","steps":["trace[977381971] 'process raft request' (duration: 253.485151ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:37.597441Z","caller":"traceutil/trace.go:171","msg":"trace[1348119342] transaction","detail":"{read_only:false; response_revision:26473; number_of_response:1; }","duration":"253.110261ms","start":"2026-06-09T17:43:37.344286Z","end":"2026-06-09T17:43:37.597397Z","steps":["trace[1348119342] 'process raft request' (duration: 252.932596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T17:43:37.597527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.984344ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cluster.x-k8s.io/clusterclasses/\" range_end:\"/registry/cluster.x-k8s.io/clusterclasses0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:43:37.597587Z","caller":"traceutil/trace.go:171","msg":"trace[1583009063] range","detail":"{range_begin:/registry/cluster.x-k8s.io/clusterclasses/; range_end:/registry/cluster.x-k8s.io/clusterclasses0; response_count:0; response_revision:26473; }","duration":"105.097758ms","start":"2026-06-09T17:43:37.492472Z","end":"2026-06-09T17:43:37.597569Z","steps":["trace[1583009063] 'agreement among raft nodes before linearized reading' (duration: 104.949034ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:38.058831Z","caller":"traceutil/trace.go:171","msg":"trace[789485956] linearizableReadLoop","detail":"{readStateIndex:27354; appliedIndex:27353; }","duration":"136.557852ms","start":"2026-06-09T17:43:37.922245Z","end":"2026-06-09T17:43:38.058803Z","steps":["trace[789485956] 'read index received' (duration: 136.47518ms)","trace[789485956] 'applied index is now lower than readState.Index' (duration: 81.442µs)"],"step_count":2} {"level":"warn","ts":"2026-06-09T17:43:38.059092Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"136.749269ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-06-09T17:43:38.05916Z","caller":"traceutil/trace.go:171","msg":"trace[188352523] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigtemplates0; response_count:0; response_revision:26475; }","duration":"136.967395ms","start":"2026-06-09T17:43:37.922176Z","end":"2026-06-09T17:43:38.059144Z","steps":["trace[188352523] 'agreement among raft nodes before linearized reading' (duration: 136.715666ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:43:38.059181Z","caller":"traceutil/trace.go:171","msg":"trace[1907721168] transaction","detail":"{read_only:false; response_revision:26475; number_of_response:1; }","duration":"173.325812ms","start":"2026-06-09T17:43:37.88578Z","end":"2026-06-09T17:43:38.059106Z","steps":["trace[1907721168] 'process raft request' (duration: 172.837767ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T17:46:12.984578Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":25154} {"level":"info","ts":"2026-06-09T17:46:13.128919Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":25154,"took":"139.94361ms","hash":508470283,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":36495360,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-06-09T17:46:13.129075Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":508470283,"revision":25154,"compact-revision":21962} {"level":"info","ts":"2026-06-09T17:47:17.675797Z","caller":"traceutil/trace.go:171","msg":"trace[425268996] transaction","detail":"{read_only:false; response_revision:28639; number_of_response:1; }","duration":"113.496269ms","start":"2026-06-09T17:47:17.562273Z","end":"2026-06-09T17:47:17.675769Z","steps":["trace[425268996] 'process raft request' (duration: 90.725333ms)","trace[425268996] 'compare' (duration: 22.273022ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T17:47:59.752362Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"be773dfc027af3eb","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T17:47:59.759781Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-06-09T17:47:59.7599Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-06-09T17:51:12.992331Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":27955} {"level":"info","ts":"2026-06-09T17:51:13.136687Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":27955,"took":"140.420444ms","hash":3471999453,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":33001472,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-06-09T17:51:13.136797Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3471999453,"revision":27955,"compact-revision":25154} {"level":"info","ts":"2026-06-09T17:56:13.003205Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":30523} {"level":"info","ts":"2026-06-09T17:56:13.144092Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":30523,"took":"137.489922ms","hash":532000163,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":30326784,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-09T17:56:13.144169Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":532000163,"revision":30523,"compact-revision":27955} {"level":"info","ts":"2026-06-09T18:00:39.947737Z","caller":"traceutil/trace.go:171","msg":"trace[118390197] transaction","detail":"{read_only:false; response_revision:34831; number_of_response:1; }","duration":"301.855669ms","start":"2026-06-09T18:00:39.645861Z","end":"2026-06-09T18:00:39.947717Z","steps":["trace[118390197] 'process raft request' (duration: 301.255791ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:39.947872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T18:00:39.645834Z","time spent":"301.963942ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T18:00:39.948067Z","caller":"traceutil/trace.go:171","msg":"trace[12449691] transaction","detail":"{read_only:false; response_revision:34832; number_of_response:1; }","duration":"302.187229ms","start":"2026-06-09T18:00:39.645868Z","end":"2026-06-09T18:00:39.948056Z","steps":["trace[12449691] 'process raft request' (duration: 301.366185ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:39.948109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-06-09T18:00:39.645836Z","time spent":"302.249681ms","remote":"127.0.0.1:50622","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":42,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-06-09T18:00:39.948166Z","caller":"traceutil/trace.go:171","msg":"trace[1124867180] linearizableReadLoop","detail":"{readStateIndex:35940; appliedIndex:35938; }","duration":"273.797021ms","start":"2026-06-09T18:00:39.674363Z","end":"2026-06-09T18:00:39.94816Z","steps":["trace[1124867180] 'read index received' (duration: 272.684798ms)","trace[1124867180] 'applied index is now lower than readState.Index' (duration: 1.111673ms)"],"step_count":2} {"level":"info","ts":"2026-06-09T18:00:39.948214Z","caller":"traceutil/trace.go:171","msg":"trace[487686055] transaction","detail":"{read_only:false; response_revision:34833; number_of_response:1; }","duration":"268.953751ms","start":"2026-06-09T18:00:39.679253Z","end":"2026-06-09T18:00:39.948207Z","steps":["trace[487686055] 'process raft request' (duration: 268.017034ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:39.9483Z","caller":"traceutil/trace.go:171","msg":"trace[614333789] transaction","detail":"{read_only:false; response_revision:34834; number_of_response:1; }","duration":"136.163444ms","start":"2026-06-09T18:00:39.81212Z","end":"2026-06-09T18:00:39.948284Z","steps":["trace[614333789] 'process raft request' (duration: 135.180596ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:39.94844Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"274.084159ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/subnets.openstack.k-orc.cloud\" ","response":"range_response_count:1 size:21532"} {"level":"info","ts":"2026-06-09T18:00:39.948461Z","caller":"traceutil/trace.go:171","msg":"trace[27913001] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/subnets.openstack.k-orc.cloud; range_end:; response_count:1; response_revision:34836; }","duration":"274.146481ms","start":"2026-06-09T18:00:39.674307Z","end":"2026-06-09T18:00:39.948454Z","steps":["trace[27913001] 'agreement among raft nodes before linearized reading' (duration: 274.044018ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:39.948588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.907824ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-06-09T18:00:39.948603Z","caller":"traceutil/trace.go:171","msg":"trace[188180423] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:34836; }","duration":"273.924345ms","start":"2026-06-09T18:00:39.674673Z","end":"2026-06-09T18:00:39.948598Z","steps":["trace[188180423] 'agreement among raft nodes before linearized reading' (duration: 273.879674ms)"],"step_count":1} {"level":"warn","ts":"2026-06-09T18:00:39.948678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.170354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-06-09T18:00:39.94869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.987907ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-06-09T18:00:39.948704Z","caller":"traceutil/trace.go:171","msg":"trace[2070915287] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:34836; }","duration":"274.002817ms","start":"2026-06-09T18:00:39.674697Z","end":"2026-06-09T18:00:39.9487Z","steps":["trace[2070915287] 'agreement among raft nodes before linearized reading' (duration: 273.958496ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:00:39.948709Z","caller":"traceutil/trace.go:171","msg":"trace[363986794] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:34836; }","duration":"272.214045ms","start":"2026-06-09T18:00:39.676485Z","end":"2026-06-09T18:00:39.948699Z","steps":["trace[363986794] 'agreement among raft nodes before linearized reading' (duration: 272.162004ms)"],"step_count":1} {"level":"info","ts":"2026-06-09T18:01:13.010713Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":32784} {"level":"info","ts":"2026-06-09T18:01:13.153966Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":32784,"took":"139.299504ms","hash":389884673,"current-db-size-bytes":58019840,"current-db-size":"58 MB","current-db-size-in-use-bytes":30314496,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-09T18:01:13.154051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":389884673,"revision":32784,"compact-revision":30523} {"level":"info","ts":"2026-06-09T18:06:13.019498Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35078} {"level":"info","ts":"2026-06-09T18:06:13.168881Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35078,"took":"142.846707ms","hash":1081803755,"current-db-size-bytes":58441728,"current-db-size":"58 MB","current-db-size-in-use-bytes":31498240,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-09T18:06:13.168957Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1081803755,"revision":35078,"compact-revision":32784} {"level":"info","ts":"2026-06-09T18:09:15.749956Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"be773dfc027af3eb","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-06-09T18:09:15.756213Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-06-09T18:09:15.756313Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004} {"level":"info","ts":"2026-06-09T18:11:13.034463Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":37403} {"level":"info","ts":"2026-06-09T18:11:13.159435Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":37403,"took":"120.368309ms","hash":4056990013,"current-db-size-bytes":60977152,"current-db-size":"61 MB","current-db-size-in-use-bytes":32333824,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-06-09T18:11:13.159508Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4056990013,"revision":37403,"compact-revision":35078} {"level":"info","ts":"2026-06-09T18:16:13.04556Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":39681} {"level":"info","ts":"2026-06-09T18:16:13.17471Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":39681,"took":"124.372557ms","hash":2327515133,"current-db-size-bytes":60977152,"current-db-size":"61 MB","current-db-size-in-use-bytes":30396416,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-06-09T18:16:13.174777Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2327515133,"revision":39681,"compact-revision":37403}