{"level":"warn","ts":"2026-09-03T16:22:09.998829Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-03T16:22:09.998941Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.85: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://162.253.55.85:2380","--initial-cluster=instance=https://162.253.55.85:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.85:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.85: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-09-03T16:22:09.999023Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-03T16:22:09.999035Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.85:2380"]} {"level":"info","ts":"2026-09-03T16:22:09.9991Z","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-09-03T16:22:09.999988Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"]} {"level":"info","ts":"2026-09-03T16:22:10.000101Z","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://162.253.55.85:2380"],"listen-peer-urls":["https://162.253.55.85:2380"],"advertise-client-urls":["https://162.253.55.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.85: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-09-03T16:22:10.005914Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"5.267075ms"} {"level":"info","ts":"2026-09-03T16:22:10.018351Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"427150911cb3975a","cluster-id":"f76f6804bd8187de"} {"level":"info","ts":"2026-09-03T16:22:10.018449Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=()"} {"level":"info","ts":"2026-09-03T16:22:10.018472Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became follower at term 0"} {"level":"info","ts":"2026-09-03T16:22:10.01849Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 427150911cb3975a [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-03T16:22:10.018506Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became follower at term 1"} {"level":"info","ts":"2026-09-03T16:22:10.018566Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=(4787696463053559642)"} {"level":"warn","ts":"2026-09-03T16:22:10.029039Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-03T16:22:10.032598Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-03T16:22:10.034552Z","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-09-03T16:22:10.03744Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"427150911cb3975a","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-03T16:22:10.03763Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"427150911cb3975a","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-03T16:22:10.037906Z","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-09-03T16:22:10.037988Z","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-09-03T16:22:10.038006Z","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-09-03T16:22:10.038777Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a switched to configuration voters=(4787696463053559642)"} {"level":"info","ts":"2026-09-03T16:22:10.03902Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f76f6804bd8187de","local-member-id":"427150911cb3975a","added-peer-id":"427150911cb3975a","added-peer-peer-urls":["https://162.253.55.85:2380"]} {"level":"info","ts":"2026-09-03T16:22:10.042784Z","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-09-03T16:22:10.042966Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.85:2380"} {"level":"info","ts":"2026-09-03T16:22:10.04309Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.85:2380"} {"level":"info","ts":"2026-09-03T16:22:10.044243Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"427150911cb3975a","initial-advertise-peer-urls":["https://162.253.55.85:2380"],"listen-peer-urls":["https://162.253.55.85:2380"],"advertise-client-urls":["https://162.253.55.85:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.85:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-03T16:22:10.044305Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-03T16:22:10.81943Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a is starting a new election at term 1"} {"level":"info","ts":"2026-09-03T16:22:10.819512Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became pre-candidate at term 1"} {"level":"info","ts":"2026-09-03T16:22:10.819572Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a received MsgPreVoteResp from 427150911cb3975a at term 1"} {"level":"info","ts":"2026-09-03T16:22:10.819605Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became candidate at term 2"} {"level":"info","ts":"2026-09-03T16:22:10.819621Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a received MsgVoteResp from 427150911cb3975a at term 2"} {"level":"info","ts":"2026-09-03T16:22:10.819645Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"427150911cb3975a became leader at term 2"} {"level":"info","ts":"2026-09-03T16:22:10.819666Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 427150911cb3975a elected leader 427150911cb3975a at term 2"} {"level":"info","ts":"2026-09-03T16:22:10.820809Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-03T16:22:10.82101Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"427150911cb3975a","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.85:2379]}","request-path":"/0/members/427150911cb3975a/attributes","cluster-id":"f76f6804bd8187de","publish-timeout":"7s"} {"level":"info","ts":"2026-09-03T16:22:10.821091Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-03T16:22:10.821746Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-03T16:22:10.821764Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f76f6804bd8187de","local-member-id":"427150911cb3975a","cluster-version":"3.5"} {"level":"info","ts":"2026-09-03T16:22:10.821889Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-03T16:22:10.82193Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-03T16:22:10.82334Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-03T16:22:10.82341Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-03T16:22:10.825Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.85:2379"} {"level":"info","ts":"2026-09-03T16:22:10.825069Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-03T16:23:05.573535Z","caller":"traceutil/trace.go:171","msg":"trace[447848846] transaction","detail":"{read_only:false; response_revision:621; number_of_response:1; }","duration":"192.360083ms","start":"2026-09-03T16:23:05.381145Z","end":"2026-09-03T16:23:05.573506Z","steps":["trace[447848846] 'process raft request' (duration: 179.038957ms)","trace[447848846] 'compare' (duration: 13.181164ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:23:06.118415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.724606ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-03T16:23:06.118553Z","caller":"traceutil/trace.go:171","msg":"trace[188665374] linearizableReadLoop","detail":"{readStateIndex:645; appliedIndex:644; }","duration":"422.928593ms","start":"2026-09-03T16:23:05.695607Z","end":"2026-09-03T16:23:06.118536Z","steps":["trace[188665374] 'read index received' (duration: 200.601871ms)","trace[188665374] 'applied index is now lower than readState.Index' (duration: 222.325022ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:23:06.11863Z","caller":"traceutil/trace.go:171","msg":"trace[1428023475] transaction","detail":"{read_only:false; response_revision:623; number_of_response:1; }","duration":"497.938694ms","start":"2026-09-03T16:23:05.62068Z","end":"2026-09-03T16:23:06.118619Z","steps":["trace[1428023475] 'process raft request' (duration: 275.615253ms)","trace[1428023475] 'compare' (duration: 220.46156ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:23:06.1187Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T16:23:05.620664Z","time spent":"497.996064ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-03T16:23:06.119013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.404754ms","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-09-03T16:23:06.119092Z","caller":"traceutil/trace.go:171","msg":"trace[926244108] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:623; }","duration":"423.520465ms","start":"2026-09-03T16:23:05.695554Z","end":"2026-09-03T16:23:06.119074Z","steps":["trace[926244108] 'agreement among raft nodes before linearized reading' (duration: 423.368052ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:23:06.119149Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T16:23:05.695533Z","time spent":"423.601878ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-09-03T16:23:20.912607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.292798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T16:23:20.912734Z","caller":"traceutil/trace.go:171","msg":"trace[1081363206] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:775; }","duration":"114.470442ms","start":"2026-09-03T16:23:20.798237Z","end":"2026-09-03T16:23:20.912707Z","steps":["trace[1081363206] 'range keys from in-memory index tree' (duration: 114.205426ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:23:20.912759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.019913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T16:23:20.912894Z","caller":"traceutil/trace.go:171","msg":"trace[463527584] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:775; }","duration":"115.223817ms","start":"2026-09-03T16:23:20.797654Z","end":"2026-09-03T16:23:20.912877Z","steps":["trace[463527584] 'range keys from in-memory index tree' (duration: 114.87878ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:23:20.91292Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.133526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backends/\" range_end:\"/registry/gateway.envoyproxy.io/backends0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T16:23:20.912996Z","caller":"traceutil/trace.go:171","msg":"trace[1141351125] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backends/; range_end:/registry/gateway.envoyproxy.io/backends0; response_count:0; response_revision:775; }","duration":"115.247118ms","start":"2026-09-03T16:23:20.797728Z","end":"2026-09-03T16:23:20.912975Z","steps":["trace[1141351125] 'count revisions from in-memory index tree' (duration: 115.021684ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:24:44.03884Z","caller":"traceutil/trace.go:171","msg":"trace[793214043] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"128.450984ms","start":"2026-09-03T16:24:43.910343Z","end":"2026-09-03T16:24:44.038794Z","steps":["trace[793214043] 'process raft request' (duration: 66.997517ms)","trace[793214043] 'compare' (duration: 61.15432ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:24:44.484558Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.740234ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-03T16:24:44.484731Z","caller":"traceutil/trace.go:171","msg":"trace[1867365039] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1952; }","duration":"169.02093ms","start":"2026-09-03T16:24:44.315692Z","end":"2026-09-03T16:24:44.484713Z","steps":["trace[1867365039] 'read index received' (duration: 78.192329ms)","trace[1867365039] 'applied index is now lower than readState.Index' (duration: 90.827761ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:24:44.484861Z","caller":"traceutil/trace.go:171","msg":"trace[1587653823] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"352.172125ms","start":"2026-09-03T16:24:44.132591Z","end":"2026-09-03T16:24:44.484763Z","steps":["trace[1587653823] 'process raft request' (duration: 352.061743ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:24:44.484845Z","caller":"traceutil/trace.go:171","msg":"trace[379168577] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"352.756977ms","start":"2026-09-03T16:24:44.132Z","end":"2026-09-03T16:24:44.484757Z","steps":["trace[379168577] 'process raft request' (duration: 64.476532ms)","trace[379168577] 'compare' (duration: 287.610511ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:24:44.484897Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.197734ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.85\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-03T16:24:44.485056Z","caller":"traceutil/trace.go:171","msg":"trace[645792975] range","detail":"{range_begin:/registry/masterleases/162.253.55.85; range_end:; response_count:1; response_revision:1883; }","duration":"169.405529ms","start":"2026-09-03T16:24:44.315636Z","end":"2026-09-03T16:24:44.485042Z","steps":["trace[645792975] 'agreement among raft nodes before linearized reading' (duration: 169.123622ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:24:44.485074Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T16:24:44.131982Z","time spent":"352.987583ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T16:24:44.485092Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T16:24:44.132531Z","time spent":"352.422181ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T16:24:44.933554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.880307ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-03T16:24:44.933657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.664513ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-8e99de8f-0ec9-4c4a-be7c-c9dee8ccfa41\" ","response":"range_response_count:1 size:3626"} {"level":"info","ts":"2026-09-03T16:24:44.933741Z","caller":"traceutil/trace.go:171","msg":"trace[1881833949] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1884; }","duration":"225.0164ms","start":"2026-09-03T16:24:44.708604Z","end":"2026-09-03T16:24:44.93362Z","steps":["trace[1881833949] 'range keys from in-memory index tree' (duration: 224.687273ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:24:44.933754Z","caller":"traceutil/trace.go:171","msg":"trace[464823205] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-8e99de8f-0ec9-4c4a-be7c-c9dee8ccfa41; range_end:; response_count:1; response_revision:1884; }","duration":"231.811287ms","start":"2026-09-03T16:24:44.701918Z","end":"2026-09-03T16:24:44.93373Z","steps":["trace[464823205] 'range keys from in-memory index tree' (duration: 231.372857ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:25:02.209168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.950863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/securitypolicies/\" range_end:\"/registry/gateway.envoyproxy.io/securitypolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T16:25:02.209289Z","caller":"traceutil/trace.go:171","msg":"trace[370707508] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/securitypolicies/; range_end:/registry/gateway.envoyproxy.io/securitypolicies0; response_count:0; response_revision:2027; }","duration":"284.142057ms","start":"2026-09-03T16:25:01.925129Z","end":"2026-09-03T16:25:02.209271Z","steps":["trace[370707508] 'count revisions from in-memory index tree' (duration: 283.83886ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:25:02.2094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.823541ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T16:25:02.209462Z","caller":"traceutil/trace.go:171","msg":"trace[143978009] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2027; }","duration":"112.930014ms","start":"2026-09-03T16:25:02.096518Z","end":"2026-09-03T16:25:02.209448Z","steps":["trace[143978009] 'range keys from in-memory index tree' (duration: 112.73817ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:25:35.385692Z","caller":"traceutil/trace.go:171","msg":"trace[138884440] transaction","detail":"{read_only:false; response_revision:2207; number_of_response:1; }","duration":"158.799997ms","start":"2026-09-03T16:25:35.226864Z","end":"2026-09-03T16:25:35.385664Z","steps":["trace[138884440] 'process raft request' (duration: 158.647594ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:25:35.387521Z","caller":"traceutil/trace.go:171","msg":"trace[1502305850] transaction","detail":"{read_only:false; response_revision:2208; number_of_response:1; }","duration":"156.427456ms","start":"2026-09-03T16:25:35.231051Z","end":"2026-09-03T16:25:35.387479Z","steps":["trace[1502305850] 'process raft request' (duration: 156.277903ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:25:41.012858Z","caller":"traceutil/trace.go:171","msg":"trace[318979524] linearizableReadLoop","detail":"{readStateIndex:2321; appliedIndex:2320; }","duration":"155.378784ms","start":"2026-09-03T16:25:40.857453Z","end":"2026-09-03T16:25:41.012832Z","steps":["trace[318979524] 'read index received' (duration: 155.169499ms)","trace[318979524] 'applied index is now lower than readState.Index' (duration: 208.425µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:25:41.01294Z","caller":"traceutil/trace.go:171","msg":"trace[204044265] transaction","detail":"{read_only:false; response_revision:2236; number_of_response:1; }","duration":"164.771795ms","start":"2026-09-03T16:25:40.848137Z","end":"2026-09-03T16:25:41.012909Z","steps":["trace[204044265] 'process raft request' (duration: 164.53614ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:25:41.013071Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.379456ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/\" range_end:\"/registry/services/endpoints0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-03T16:25:41.013106Z","caller":"traceutil/trace.go:171","msg":"trace[327115766] range","detail":"{range_begin:/registry/services/endpoints/; range_end:/registry/services/endpoints0; response_count:0; response_revision:2236; }","duration":"116.502939ms","start":"2026-09-03T16:25:40.896593Z","end":"2026-09-03T16:25:41.013096Z","steps":["trace[327115766] 'agreement among raft nodes before linearized reading' (duration: 116.382377ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:25:41.013536Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.044858ms","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-09-03T16:25:41.013589Z","caller":"traceutil/trace.go:171","msg":"trace[942720924] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2236; }","duration":"156.151499ms","start":"2026-09-03T16:25:40.857425Z","end":"2026-09-03T16:25:41.013576Z","steps":["trace[942720924] 'agreement among raft nodes before linearized reading' (duration: 155.539576ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:25:41.363297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.546715ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-03T16:25:41.363412Z","caller":"traceutil/trace.go:171","msg":"trace[40929563] linearizableReadLoop","detail":"{readStateIndex:2322; appliedIndex:2321; }","duration":"267.407807ms","start":"2026-09-03T16:25:41.095988Z","end":"2026-09-03T16:25:41.363396Z","steps":["trace[40929563] 'read index received' (duration: 78.651468ms)","trace[40929563] 'applied index is now lower than readState.Index' (duration: 188.754749ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:25:41.363503Z","caller":"traceutil/trace.go:171","msg":"trace[2072865148] transaction","detail":"{read_only:false; response_revision:2237; number_of_response:1; }","duration":"345.433391ms","start":"2026-09-03T16:25:41.018005Z","end":"2026-09-03T16:25:41.363438Z","steps":["trace[2072865148] 'process raft request' (duration: 156.655861ms)","trace[2072865148] 'compare' (duration: 187.82592ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:25:41.36359Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.58152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T16:25:41.363709Z","caller":"traceutil/trace.go:171","msg":"trace[138350831] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2237; }","duration":"267.736664ms","start":"2026-09-03T16:25:41.095943Z","end":"2026-09-03T16:25:41.363679Z","steps":["trace[138350831] 'agreement among raft nodes before linearized reading' (duration: 267.525879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:25:41.363729Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T16:25:41.017983Z","time spent":"345.622145ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T16:25:41.628441Z","caller":"traceutil/trace.go:171","msg":"trace[1134761369] linearizableReadLoop","detail":"{readStateIndex:2323; appliedIndex:2322; }","duration":"217.528078ms","start":"2026-09-03T16:25:41.410888Z","end":"2026-09-03T16:25:41.628416Z","steps":["trace[1134761369] 'read index received' (duration: 158.030021ms)","trace[1134761369] 'applied index is now lower than readState.Index' (duration: 59.496727ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:25:41.628671Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.776063ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-09-03T16:25:41.628712Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.020469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-09-03T16:25:41.628744Z","caller":"traceutil/trace.go:171","msg":"trace[760028712] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2238; }","duration":"217.857374ms","start":"2026-09-03T16:25:41.410869Z","end":"2026-09-03T16:25:41.628727Z","steps":["trace[760028712] 'agreement among raft nodes before linearized reading' (duration: 217.67524ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:25:41.628765Z","caller":"traceutil/trace.go:171","msg":"trace[1543825662] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2238; }","duration":"109.128342ms","start":"2026-09-03T16:25:41.519615Z","end":"2026-09-03T16:25:41.628743Z","steps":["trace[1543825662] 'agreement among raft nodes before linearized reading' (duration: 108.942088ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:25:41.628928Z","caller":"traceutil/trace.go:171","msg":"trace[197014097] transaction","detail":"{read_only:false; response_revision:2238; number_of_response:1; }","duration":"222.125016ms","start":"2026-09-03T16:25:41.406698Z","end":"2026-09-03T16:25:41.628823Z","steps":["trace[197014097] 'process raft request' (duration: 162.247492ms)","trace[197014097] 'compare' (duration: 59.105068ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:25:41.813348Z","caller":"traceutil/trace.go:171","msg":"trace[1269096773] transaction","detail":"{read_only:false; response_revision:2239; number_of_response:1; }","duration":"179.933811ms","start":"2026-09-03T16:25:41.633382Z","end":"2026-09-03T16:25:41.813316Z","steps":["trace[1269096773] 'process raft request' (duration: 174.865762ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:26:28.20198Z","caller":"traceutil/trace.go:171","msg":"trace[803422817] transaction","detail":"{read_only:false; response_revision:2668; number_of_response:1; }","duration":"147.032305ms","start":"2026-09-03T16:26:28.054921Z","end":"2026-09-03T16:26:28.201954Z","steps":["trace[803422817] 'process raft request' (duration: 140.363163ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:26:28.202169Z","caller":"traceutil/trace.go:171","msg":"trace[1007195274] linearizableReadLoop","detail":"{readStateIndex:2770; appliedIndex:2768; }","duration":"107.225464ms","start":"2026-09-03T16:26:28.094912Z","end":"2026-09-03T16:26:28.202138Z","steps":["trace[1007195274] 'read index received' (duration: 100.48866ms)","trace[1007195274] 'applied index is now lower than readState.Index' (duration: 6.734984ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:26:28.202218Z","caller":"traceutil/trace.go:171","msg":"trace[1739490440] transaction","detail":"{read_only:false; response_revision:2669; number_of_response:1; }","duration":"143.923288ms","start":"2026-09-03T16:26:28.058284Z","end":"2026-09-03T16:26:28.202207Z","steps":["trace[1739490440] 'process raft request' (duration: 143.628382ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:26:28.202338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.405897ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T16:26:28.202471Z","caller":"traceutil/trace.go:171","msg":"trace[1967202648] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2669; }","duration":"107.573471ms","start":"2026-09-03T16:26:28.094871Z","end":"2026-09-03T16:26:28.202444Z","steps":["trace[1967202648] 'agreement among raft nodes before linearized reading' (duration: 107.367387ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:27:03.677095Z","caller":"traceutil/trace.go:171","msg":"trace[605273164] transaction","detail":"{read_only:false; response_revision:2880; number_of_response:1; }","duration":"208.880708ms","start":"2026-09-03T16:27:03.468186Z","end":"2026-09-03T16:27:03.677067Z","steps":["trace[605273164] 'process raft request' (duration: 208.676804ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:31:50.032908Z","caller":"traceutil/trace.go:171","msg":"trace[2141453539] transaction","detail":"{read_only:false; response_revision:5086; number_of_response:1; }","duration":"134.744193ms","start":"2026-09-03T16:31:49.89813Z","end":"2026-09-03T16:31:50.032875Z","steps":["trace[2141453539] 'process raft request' (duration: 99.577177ms)","trace[2141453539] 'compare' (duration: 34.964651ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:31:50.033416Z","caller":"traceutil/trace.go:171","msg":"trace[1824096597] linearizableReadLoop","detail":"{readStateIndex:5280; appliedIndex:5278; }","duration":"116.079433ms","start":"2026-09-03T16:31:49.917318Z","end":"2026-09-03T16:31:50.033398Z","steps":["trace[1824096597] 'read index received' (duration: 80.377196ms)","trace[1824096597] 'applied index is now lower than readState.Index' (duration: 35.701527ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:31:50.03346Z","caller":"traceutil/trace.go:171","msg":"trace[266007347] transaction","detail":"{read_only:false; response_revision:5087; number_of_response:1; }","duration":"130.830649ms","start":"2026-09-03T16:31:49.902618Z","end":"2026-09-03T16:31:50.033448Z","steps":["trace[266007347] 'process raft request' (duration: 130.682196ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:31:50.033784Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.636698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/local-path-storage/local-path-provisioner\" ","response":"range_response_count:1 size:897"} {"level":"info","ts":"2026-09-03T16:31:50.03382Z","caller":"traceutil/trace.go:171","msg":"trace[891880592] range","detail":"{range_begin:/registry/serviceaccounts/local-path-storage/local-path-provisioner; range_end:; response_count:1; response_revision:5087; }","duration":"112.681479ms","start":"2026-09-03T16:31:49.921128Z","end":"2026-09-03T16:31:50.033809Z","steps":["trace[891880592] 'agreement among raft nodes before linearized reading' (duration: 112.534656ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:31:50.03403Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.527522ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus\" ","response":"range_response_count:1 size:7464"} {"level":"info","ts":"2026-09-03T16:31:50.034084Z","caller":"traceutil/trace.go:171","msg":"trace[811314066] range","detail":"{range_begin:/registry/monitoring.coreos.com/prometheuses/monitoring/kube-prometheus-stack-prometheus; range_end:; response_count:1; response_revision:5087; }","duration":"116.792258ms","start":"2026-09-03T16:31:49.917278Z","end":"2026-09-03T16:31:50.034071Z","steps":["trace[811314066] 'agreement among raft nodes before linearized reading' (duration: 116.214726ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:31:57.529835Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.573449ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-03T16:31:57.530266Z","caller":"traceutil/trace.go:171","msg":"trace[358070842] transaction","detail":"{read_only:false; response_revision:5308; number_of_response:1; }","duration":"296.601298ms","start":"2026-09-03T16:31:57.233631Z","end":"2026-09-03T16:31:57.530233Z","steps":["trace[358070842] 'process raft request' (duration: 39.563509ms)","trace[358070842] 'compare' (duration: 256.273453ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:31:57.530326Z","caller":"traceutil/trace.go:171","msg":"trace[1800620476] transaction","detail":"{read_only:false; response_revision:5309; number_of_response:1; }","duration":"277.871456ms","start":"2026-09-03T16:31:57.252441Z","end":"2026-09-03T16:31:57.530313Z","steps":["trace[1800620476] 'process raft request' (duration: 277.481528ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:31:57.53051Z","caller":"traceutil/trace.go:171","msg":"trace[535511292] linearizableReadLoop","detail":"{readStateIndex:5511; appliedIndex:5509; }","duration":"235.846714ms","start":"2026-09-03T16:31:57.294606Z","end":"2026-09-03T16:31:57.530453Z","steps":["trace[535511292] 'read index received' (duration: 104.137266ms)","trace[535511292] 'applied index is now lower than readState.Index' (duration: 131.705558ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:31:57.530795Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.16099ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"warn","ts":"2026-09-03T16:31:57.530865Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.204251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"info","ts":"2026-09-03T16:31:57.530892Z","caller":"traceutil/trace.go:171","msg":"trace[3484418] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:5311; }","duration":"236.294363ms","start":"2026-09-03T16:31:57.294575Z","end":"2026-09-03T16:31:57.530869Z","steps":["trace[3484418] 'agreement among raft nodes before linearized reading' (duration: 236.023217ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:31:57.530949Z","caller":"traceutil/trace.go:171","msg":"trace[144765350] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:5311; }","duration":"236.322774ms","start":"2026-09-03T16:31:57.294604Z","end":"2026-09-03T16:31:57.530926Z","steps":["trace[144765350] 'agreement among raft nodes before linearized reading' (duration: 236.061608ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:31:57.530946Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.112596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-09-03T16:31:57.530973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.294153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/monitoring/loki\" ","response":"range_response_count:1 size:800"} {"level":"info","ts":"2026-09-03T16:31:57.531043Z","caller":"traceutil/trace.go:171","msg":"trace[1442672909] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5311; }","duration":"131.240668ms","start":"2026-09-03T16:31:57.399762Z","end":"2026-09-03T16:31:57.531003Z","steps":["trace[1442672909] 'agreement among raft nodes before linearized reading' (duration: 131.005104ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:31:57.531067Z","caller":"traceutil/trace.go:171","msg":"trace[1359481942] range","detail":"{range_begin:/registry/serviceaccounts/monitoring/loki; range_end:; response_count:1; response_revision:5311; }","duration":"236.426135ms","start":"2026-09-03T16:31:57.294621Z","end":"2026-09-03T16:31:57.531047Z","steps":["trace[1359481942] 'agreement among raft nodes before linearized reading' (duration: 236.15988ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:31:57.531188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.957557ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/\" range_end:\"/registry/secrets/monitoring0\" ","response":"range_response_count:24 size:728972"} {"level":"info","ts":"2026-09-03T16:31:57.531254Z","caller":"traceutil/trace.go:171","msg":"trace[140473079] range","detail":"{range_begin:/registry/secrets/monitoring/; range_end:/registry/secrets/monitoring0; response_count:24; response_revision:5311; }","duration":"155.059049ms","start":"2026-09-03T16:31:57.376177Z","end":"2026-09-03T16:31:57.531236Z","steps":["trace[140473079] 'agreement among raft nodes before linearized reading' (duration: 154.533558ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:32:00.286919Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.537437ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-03T16:32:00.287037Z","caller":"traceutil/trace.go:171","msg":"trace[1537210604] linearizableReadLoop","detail":"{readStateIndex:5570; appliedIndex:5569; }","duration":"171.785938ms","start":"2026-09-03T16:32:00.115222Z","end":"2026-09-03T16:32:00.287008Z","steps":["trace[1537210604] 'read index received' (duration: 33.82µs)","trace[1537210604] 'applied index is now lower than readState.Index' (duration: 171.750958ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:32:00.287109Z","caller":"traceutil/trace.go:171","msg":"trace[1343802349] transaction","detail":"{read_only:false; response_revision:5365; number_of_response:1; }","duration":"212.817889ms","start":"2026-09-03T16:32:00.074283Z","end":"2026-09-03T16:32:00.287101Z","steps":["trace[1343802349] 'process raft request' (duration: 24.035346ms)","trace[1343802349] 'compare' (duration: 188.326703ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:32:00.287418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"172.187936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-b74dadfe-9451-44db-af36-7d6d3620a29b\" ","response":"range_response_count:1 size:3600"} {"level":"info","ts":"2026-09-03T16:32:00.287448Z","caller":"traceutil/trace.go:171","msg":"trace[789716056] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-b74dadfe-9451-44db-af36-7d6d3620a29b; range_end:; response_count:1; response_revision:5365; }","duration":"172.259138ms","start":"2026-09-03T16:32:00.115181Z","end":"2026-09-03T16:32:00.28744Z","steps":["trace[789716056] 'agreement among raft nodes before linearized reading' (duration: 172.018233ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:32:00.44042Z","caller":"traceutil/trace.go:171","msg":"trace[616840541] transaction","detail":"{read_only:false; response_revision:5368; number_of_response:1; }","duration":"141.974009ms","start":"2026-09-03T16:32:00.298395Z","end":"2026-09-03T16:32:00.440369Z","steps":["trace[616840541] 'process raft request' (duration: 141.894777ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:32:00.440516Z","caller":"traceutil/trace.go:171","msg":"trace[1429837770] transaction","detail":"{read_only:false; response_revision:5367; number_of_response:1; }","duration":"148.815244ms","start":"2026-09-03T16:32:00.291641Z","end":"2026-09-03T16:32:00.440457Z","steps":["trace[1429837770] 'process raft request' (duration: 148.466237ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:32:04.167332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.810439ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"info","ts":"2026-09-03T16:32:04.16742Z","caller":"traceutil/trace.go:171","msg":"trace[1589550817] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:5431; }","duration":"137.939081ms","start":"2026-09-03T16:32:04.029459Z","end":"2026-09-03T16:32:04.167398Z","steps":["trace[1589550817] 'range keys from in-memory index tree' (duration: 137.631724ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:32:04.167464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.182724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-03T16:32:04.167586Z","caller":"traceutil/trace.go:171","msg":"trace[1778470589] transaction","detail":"{read_only:false; response_revision:5432; number_of_response:1; }","duration":"137.916731ms","start":"2026-09-03T16:32:04.029652Z","end":"2026-09-03T16:32:04.167568Z","steps":["trace[1778470589] 'process raft request' (duration: 21.569083ms)","trace[1778470589] 'compare' (duration: 115.915488ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:32:04.209543Z","caller":"traceutil/trace.go:171","msg":"trace[634787022] transaction","detail":"{read_only:false; response_revision:5433; number_of_response:1; }","duration":"129.96415ms","start":"2026-09-03T16:32:04.079501Z","end":"2026-09-03T16:32:04.209465Z","steps":["trace[634787022] 'process raft request' (duration: 129.787126ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:32:04.20957Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.428228ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T16:32:04.209806Z","caller":"traceutil/trace.go:171","msg":"trace[1180730920] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5433; }","duration":"115.717814ms","start":"2026-09-03T16:32:04.094073Z","end":"2026-09-03T16:32:04.209791Z","steps":["trace[1180730920] 'agreement among raft nodes before linearized reading' (duration: 115.350836ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:32:04.320996Z","caller":"traceutil/trace.go:171","msg":"trace[1942344633] linearizableReadLoop","detail":"{readStateIndex:5641; appliedIndex:5640; }","duration":"109.44948ms","start":"2026-09-03T16:32:04.211516Z","end":"2026-09-03T16:32:04.320966Z","steps":["trace[1942344633] 'read index received' (duration: 103.298677ms)","trace[1942344633] 'applied index is now lower than readState.Index' (duration: 6.149753ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:32:04.321157Z","caller":"traceutil/trace.go:171","msg":"trace[1395604953] transaction","detail":"{read_only:false; response_revision:5434; number_of_response:1; }","duration":"140.444756ms","start":"2026-09-03T16:32:04.180667Z","end":"2026-09-03T16:32:04.321112Z","steps":["trace[1395604953] 'process raft request' (duration: 134.159551ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:32:04.321269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"109.717645ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T16:32:04.321357Z","caller":"traceutil/trace.go:171","msg":"trace[584860877] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5434; }","duration":"109.847538ms","start":"2026-09-03T16:32:04.211492Z","end":"2026-09-03T16:32:04.321339Z","steps":["trace[584860877] 'agreement among raft nodes before linearized reading' (duration: 109.670255ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:32:04.321991Z","caller":"traceutil/trace.go:171","msg":"trace[722787637] transaction","detail":"{read_only:false; response_revision:5435; number_of_response:1; }","duration":"108.56647ms","start":"2026-09-03T16:32:04.213405Z","end":"2026-09-03T16:32:04.321972Z","steps":["trace[722787637] 'process raft request' (duration: 108.444867ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:32:04.322236Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.736033ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-ae72277e-793e-491d-9e24-4aabb2180b8f\" ","response":"range_response_count:1 size:1450"} {"level":"info","ts":"2026-09-03T16:32:04.322341Z","caller":"traceutil/trace.go:171","msg":"trace[779479333] range","detail":"{range_begin:/registry/persistentvolumes/pvc-ae72277e-793e-491d-9e24-4aabb2180b8f; range_end:; response_count:1; response_revision:5435; }","duration":"107.882296ms","start":"2026-09-03T16:32:04.214408Z","end":"2026-09-03T16:32:04.322291Z","steps":["trace[779479333] 'agreement among raft nodes before linearized reading' (duration: 107.531988ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:32:10.871783Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2922} {"level":"info","ts":"2026-09-03T16:32:10.947952Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2922,"took":"74.870337ms","hash":2486399603,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":31080448,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-03T16:32:10.948039Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2486399603,"revision":2922,"compact-revision":-1} {"level":"info","ts":"2026-09-03T16:32:12.443196Z","caller":"traceutil/trace.go:171","msg":"trace[1706666639] transaction","detail":"{read_only:false; response_revision:5583; number_of_response:1; }","duration":"104.012323ms","start":"2026-09-03T16:32:12.338937Z","end":"2026-09-03T16:32:12.44295Z","steps":["trace[1706666639] 'process raft request' (duration: 103.86763ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:32:13.284581Z","caller":"traceutil/trace.go:171","msg":"trace[2083245471] transaction","detail":"{read_only:false; response_revision:5599; number_of_response:1; }","duration":"125.868872ms","start":"2026-09-03T16:32:13.158681Z","end":"2026-09-03T16:32:13.28455Z","steps":["trace[2083245471] 'process raft request' (duration: 125.812591ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:32:13.285317Z","caller":"traceutil/trace.go:171","msg":"trace[624379400] transaction","detail":"{read_only:false; response_revision:5596; number_of_response:1; }","duration":"127.898096ms","start":"2026-09-03T16:32:13.157405Z","end":"2026-09-03T16:32:13.285303Z","steps":["trace[624379400] 'process raft request' (duration: 126.764292ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:32:13.285372Z","caller":"traceutil/trace.go:171","msg":"trace[1871747662] transaction","detail":"{read_only:false; response_revision:5597; number_of_response:1; }","duration":"127.851695ms","start":"2026-09-03T16:32:13.157483Z","end":"2026-09-03T16:32:13.285335Z","steps":["trace[1871747662] 'process raft request' (duration: 126.894405ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:32:13.285707Z","caller":"traceutil/trace.go:171","msg":"trace[980262346] transaction","detail":"{read_only:false; response_revision:5598; number_of_response:1; }","duration":"127.506767ms","start":"2026-09-03T16:32:13.158186Z","end":"2026-09-03T16:32:13.285692Z","steps":["trace[980262346] 'process raft request' (duration: 126.262671ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:32:21.357318Z","caller":"traceutil/trace.go:171","msg":"trace[171875365] linearizableReadLoop","detail":"{readStateIndex:5957; appliedIndex:5956; }","duration":"139.420502ms","start":"2026-09-03T16:32:21.217873Z","end":"2026-09-03T16:32:21.357293Z","steps":["trace[171875365] 'read index received' (duration: 139.258169ms)","trace[171875365] 'applied index is now lower than readState.Index' (duration: 161.193µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:32:21.357505Z","caller":"traceutil/trace.go:171","msg":"trace[898873557] transaction","detail":"{read_only:false; response_revision:5742; number_of_response:1; }","duration":"146.66123ms","start":"2026-09-03T16:32:21.210749Z","end":"2026-09-03T16:32:21.35741Z","steps":["trace[898873557] 'process raft request' (duration: 146.376163ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:32:21.35757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.6897ms","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-09-03T16:32:21.357619Z","caller":"traceutil/trace.go:171","msg":"trace[659999481] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5742; }","duration":"139.790792ms","start":"2026-09-03T16:32:21.217814Z","end":"2026-09-03T16:32:21.357605Z","steps":["trace[659999481] 'agreement among raft nodes before linearized reading' (duration: 139.622078ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:32:21.358378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.796052ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T16:32:21.358448Z","caller":"traceutil/trace.go:171","msg":"trace[287235675] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:5743; }","duration":"112.901513ms","start":"2026-09-03T16:32:21.24553Z","end":"2026-09-03T16:32:21.358432Z","steps":["trace[287235675] 'agreement among raft nodes before linearized reading' (duration: 112.778921ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:32:21.358783Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.399105ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/storage-loki-0\" ","response":"range_response_count:1 size:1781"} {"level":"info","ts":"2026-09-03T16:32:21.358834Z","caller":"traceutil/trace.go:171","msg":"trace[1480490884] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/storage-loki-0; range_end:; response_count:1; response_revision:5743; }","duration":"106.507607ms","start":"2026-09-03T16:32:21.252314Z","end":"2026-09-03T16:32:21.358822Z","steps":["trace[1480490884] 'agreement among raft nodes before linearized reading' (duration: 106.344493ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:32:23.532377Z","caller":"traceutil/trace.go:171","msg":"trace[1913314294] linearizableReadLoop","detail":"{readStateIndex:5975; appliedIndex:5974; }","duration":"153.023196ms","start":"2026-09-03T16:32:23.379328Z","end":"2026-09-03T16:32:23.532351Z","steps":["trace[1913314294] 'read index received' (duration: 152.869223ms)","trace[1913314294] 'applied index is now lower than readState.Index' (duration: 152.843µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:32:23.532426Z","caller":"traceutil/trace.go:171","msg":"trace[671925725] transaction","detail":"{read_only:false; response_revision:5760; number_of_response:1; }","duration":"169.269085ms","start":"2026-09-03T16:32:23.363133Z","end":"2026-09-03T16:32:23.532402Z","steps":["trace[671925725] 'process raft request' (duration: 169.071221ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:32:23.532601Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.253761ms","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-09-03T16:32:23.532635Z","caller":"traceutil/trace.go:171","msg":"trace[518655586] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5760; }","duration":"153.348343ms","start":"2026-09-03T16:32:23.379276Z","end":"2026-09-03T16:32:23.532625Z","steps":["trace[518655586] 'agreement among raft nodes before linearized reading' (duration: 153.20828ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:32:35.805846Z","caller":"traceutil/trace.go:171","msg":"trace[805113011] transaction","detail":"{read_only:false; response_revision:5953; number_of_response:1; }","duration":"119.172099ms","start":"2026-09-03T16:32:35.686651Z","end":"2026-09-03T16:32:35.805823Z","steps":["trace[805113011] 'process raft request' (duration: 118.950914ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:32:35.87427Z","caller":"traceutil/trace.go:171","msg":"trace[124915840] transaction","detail":"{read_only:false; response_revision:5954; number_of_response:1; }","duration":"136.89612ms","start":"2026-09-03T16:32:35.737351Z","end":"2026-09-03T16:32:35.874248Z","steps":["trace[124915840] 'process raft request' (duration: 136.380108ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:32:35.87433Z","caller":"traceutil/trace.go:171","msg":"trace[875258743] transaction","detail":"{read_only:false; response_revision:5955; number_of_response:1; }","duration":"133.315513ms","start":"2026-09-03T16:32:35.740997Z","end":"2026-09-03T16:32:35.874312Z","steps":["trace[875258743] 'process raft request' (duration: 132.870023ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:33:40.011145Z","caller":"traceutil/trace.go:171","msg":"trace[1773443483] linearizableReadLoop","detail":"{readStateIndex:6689; appliedIndex:6688; }","duration":"112.310802ms","start":"2026-09-03T16:33:39.898804Z","end":"2026-09-03T16:33:40.011115Z","steps":["trace[1773443483] 'read index received' (duration: 112.011676ms)","trace[1773443483] 'applied index is now lower than readState.Index' (duration: 297.636µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:33:40.011224Z","caller":"traceutil/trace.go:171","msg":"trace[1751831226] transaction","detail":"{read_only:false; response_revision:6453; number_of_response:1; }","duration":"124.472502ms","start":"2026-09-03T16:33:39.886721Z","end":"2026-09-03T16:33:40.011194Z","steps":["trace[1751831226] 'process raft request' (duration: 124.192296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:33:40.011433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.6153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12215"} {"level":"info","ts":"2026-09-03T16:33:40.011485Z","caller":"traceutil/trace.go:171","msg":"trace[1802086674] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:6453; }","duration":"112.727403ms","start":"2026-09-03T16:33:39.898742Z","end":"2026-09-03T16:33:40.01147Z","steps":["trace[1802086674] 'agreement among raft nodes before linearized reading' (duration: 112.548728ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:37:10.881048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5545} {"level":"info","ts":"2026-09-03T16:37:10.976862Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5545,"took":"94.175552ms","hash":2438865575,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":27844608,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-03T16:37:10.976939Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2438865575,"revision":5545,"compact-revision":2922} {"level":"info","ts":"2026-09-03T16:38:54.730881Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-0000000000002369.wal"} {"level":"info","ts":"2026-09-03T16:38:58.343868Z","caller":"traceutil/trace.go:171","msg":"trace[1553226869] linearizableReadLoop","detail":"{readStateIndex:9081; appliedIndex:9080; }","duration":"128.257752ms","start":"2026-09-03T16:38:58.215582Z","end":"2026-09-03T16:38:58.343839Z","steps":["trace[1553226869] 'read index received' (duration: 128.020027ms)","trace[1553226869] 'applied index is now lower than readState.Index' (duration: 236.125µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:38:58.343937Z","caller":"traceutil/trace.go:171","msg":"trace[1150007396] transaction","detail":"{read_only:false; response_revision:8769; number_of_response:1; }","duration":"207.82214ms","start":"2026-09-03T16:38:58.136082Z","end":"2026-09-03T16:38:58.343905Z","steps":["trace[1150007396] 'process raft request' (duration: 207.607035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:38:58.344131Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.527058ms","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-09-03T16:38:58.344175Z","caller":"traceutil/trace.go:171","msg":"trace[1684172155] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8769; }","duration":"128.63494ms","start":"2026-09-03T16:38:58.215529Z","end":"2026-09-03T16:38:58.344164Z","steps":["trace[1684172155] 'agreement among raft nodes before linearized reading' (duration: 128.425956ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:39:08.562212Z","caller":"traceutil/trace.go:171","msg":"trace[1155927697] transaction","detail":"{read_only:false; response_revision:8821; number_of_response:1; }","duration":"119.210139ms","start":"2026-09-03T16:39:08.442964Z","end":"2026-09-03T16:39:08.562174Z","steps":["trace[1155927697] 'process raft request' (duration: 83.893951ms)","trace[1155927697] 'compare' (duration: 35.080433ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:39:08.849642Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.591128ms","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-09-03T16:39:08.849762Z","caller":"traceutil/trace.go:171","msg":"trace[1102817484] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8821; }","duration":"100.761602ms","start":"2026-09-03T16:39:08.748974Z","end":"2026-09-03T16:39:08.849735Z","steps":["trace[1102817484] 'range keys from in-memory index tree' (duration: 100.359574ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:39:09.428896Z","caller":"traceutil/trace.go:171","msg":"trace[324283643] linearizableReadLoop","detail":"{readStateIndex:9138; appliedIndex:9137; }","duration":"149.454637ms","start":"2026-09-03T16:39:09.279407Z","end":"2026-09-03T16:39:09.428861Z","steps":["trace[324283643] 'read index received' (duration: 149.192031ms)","trace[324283643] 'applied index is now lower than readState.Index' (duration: 260.855µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:39:09.429019Z","caller":"traceutil/trace.go:171","msg":"trace[445112986] transaction","detail":"{read_only:false; response_revision:8824; number_of_response:1; }","duration":"182.140509ms","start":"2026-09-03T16:39:09.246837Z","end":"2026-09-03T16:39:09.428977Z","steps":["trace[445112986] 'process raft request' (duration: 181.836423ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:39:09.429151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.734483ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/barbican-api-67d4988b6b-c6lqc.18d1dd025dd3d938\" ","response":"range_response_count:1 size:1134"} {"level":"info","ts":"2026-09-03T16:39:09.429202Z","caller":"traceutil/trace.go:171","msg":"trace[498339747] range","detail":"{range_begin:/registry/events/openstack/barbican-api-67d4988b6b-c6lqc.18d1dd025dd3d938; range_end:; response_count:1; response_revision:8824; }","duration":"149.831514ms","start":"2026-09-03T16:39:09.279355Z","end":"2026-09-03T16:39:09.429186Z","steps":["trace[498339747] 'agreement among raft nodes before linearized reading' (duration: 149.63763ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:39:40.921221Z","caller":"traceutil/trace.go:171","msg":"trace[718007511] transaction","detail":"{read_only:false; response_revision:9047; number_of_response:1; }","duration":"315.359919ms","start":"2026-09-03T16:39:40.605815Z","end":"2026-09-03T16:39:40.921175Z","steps":["trace[718007511] 'process raft request' (duration: 314.538281ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:39:40.921197Z","caller":"traceutil/trace.go:171","msg":"trace[874441770] linearizableReadLoop","detail":"{readStateIndex:9371; appliedIndex:9370; }","duration":"193.720728ms","start":"2026-09-03T16:39:40.727412Z","end":"2026-09-03T16:39:40.921133Z","steps":["trace[874441770] 'read index received' (duration: 192.761676ms)","trace[874441770] 'applied index is now lower than readState.Index' (duration: 957.842µs)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:39:40.922305Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T16:39:40.60578Z","time spent":"316.410091ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T16:39:40.921478Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.014174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-03T16:39:40.922532Z","caller":"traceutil/trace.go:171","msg":"trace[13800246] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:9047; }","duration":"195.149568ms","start":"2026-09-03T16:39:40.727341Z","end":"2026-09-03T16:39:40.922491Z","steps":["trace[13800246] 'agreement among raft nodes before linearized reading' (duration: 193.958543ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:39:40.922618Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.491144ms","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-09-03T16:39:40.922697Z","caller":"traceutil/trace.go:171","msg":"trace[2006066525] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:9047; }","duration":"167.663937ms","start":"2026-09-03T16:39:40.755012Z","end":"2026-09-03T16:39:40.922675Z","steps":["trace[2006066525] 'agreement among raft nodes before linearized reading' (duration: 167.445463ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:39:41.194385Z","caller":"traceutil/trace.go:171","msg":"trace[2133869123] linearizableReadLoop","detail":"{readStateIndex:9372; appliedIndex:9371; }","duration":"134.228021ms","start":"2026-09-03T16:39:41.060122Z","end":"2026-09-03T16:39:41.19435Z","steps":["trace[2133869123] 'read index received' (duration: 57.78759ms)","trace[2133869123] 'applied index is now lower than readState.Index' (duration: 76.438391ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:39:41.194434Z","caller":"traceutil/trace.go:171","msg":"trace[643309197] transaction","detail":"{read_only:false; response_revision:9048; number_of_response:1; }","duration":"266.458148ms","start":"2026-09-03T16:39:40.927918Z","end":"2026-09-03T16:39:41.194376Z","steps":["trace[643309197] 'process raft request' (duration: 190.000448ms)","trace[643309197] 'compare' (duration: 76.205195ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:39:41.194714Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.580599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-03T16:39:41.195239Z","caller":"traceutil/trace.go:171","msg":"trace[1685303935] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9048; }","duration":"135.08581ms","start":"2026-09-03T16:39:41.060092Z","end":"2026-09-03T16:39:41.195178Z","steps":["trace[1685303935] 'agreement among raft nodes before linearized reading' (duration: 134.434056ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:39:42.191115Z","caller":"traceutil/trace.go:171","msg":"trace[1390978307] transaction","detail":"{read_only:false; response_revision:9055; number_of_response:1; }","duration":"129.706534ms","start":"2026-09-03T16:39:42.061328Z","end":"2026-09-03T16:39:42.191034Z","steps":["trace[1390978307] 'process raft request' (duration: 129.366707ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:39:51.78523Z","caller":"traceutil/trace.go:171","msg":"trace[996852529] transaction","detail":"{read_only:false; response_revision:9106; number_of_response:1; }","duration":"107.550187ms","start":"2026-09-03T16:39:51.677651Z","end":"2026-09-03T16:39:51.785201Z","steps":["trace[996852529] 'process raft request' (duration: 45.767161ms)","trace[996852529] 'compare' (duration: 61.621193ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:39:54.342043Z","caller":"traceutil/trace.go:171","msg":"trace[989528806] linearizableReadLoop","detail":"{readStateIndex:9454; appliedIndex:9452; }","duration":"215.333541ms","start":"2026-09-03T16:39:54.126687Z","end":"2026-09-03T16:39:54.342021Z","steps":["trace[989528806] 'read index received' (duration: 45.908854ms)","trace[989528806] 'applied index is now lower than readState.Index' (duration: 169.423497ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:39:54.342222Z","caller":"traceutil/trace.go:171","msg":"trace[761777119] transaction","detail":"{read_only:false; response_revision:9128; number_of_response:1; }","duration":"241.521984ms","start":"2026-09-03T16:39:54.100681Z","end":"2026-09-03T16:39:54.342203Z","steps":["trace[761777119] 'process raft request' (duration: 152.413921ms)","trace[761777119] 'compare' (duration: 88.740365ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:39:54.342352Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.644827ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-09-03T16:39:54.342399Z","caller":"traceutil/trace.go:171","msg":"trace[1135944658] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:9129; }","duration":"215.7388ms","start":"2026-09-03T16:39:54.126647Z","end":"2026-09-03T16:39:54.342386Z","steps":["trace[1135944658] 'agreement among raft nodes before linearized reading' (duration: 215.614207ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:40:49.835088Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"427150911cb3975a","local-member-applied-index":10005,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-03T16:40:49.850058Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10005} {"level":"info","ts":"2026-09-03T16:40:49.85017Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5005} {"level":"info","ts":"2026-09-03T16:41:19.659574Z","caller":"traceutil/trace.go:171","msg":"trace[1191638536] transaction","detail":"{read_only:false; response_revision:9957; number_of_response:1; }","duration":"117.96031ms","start":"2026-09-03T16:41:19.541584Z","end":"2026-09-03T16:41:19.659544Z","steps":["trace[1191638536] 'process raft request' (duration: 117.789996ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:41:19.980438Z","caller":"traceutil/trace.go:171","msg":"trace[557027423] linearizableReadLoop","detail":"{readStateIndex:10311; appliedIndex:10310; }","duration":"232.506227ms","start":"2026-09-03T16:41:19.747897Z","end":"2026-09-03T16:41:19.980403Z","steps":["trace[557027423] 'read index received' (duration: 215.555255ms)","trace[557027423] 'applied index is now lower than readState.Index' (duration: 16.949422ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:41:19.98067Z","caller":"traceutil/trace.go:171","msg":"trace[534140551] transaction","detail":"{read_only:false; response_revision:9958; number_of_response:1; }","duration":"267.255799ms","start":"2026-09-03T16:41:19.713381Z","end":"2026-09-03T16:41:19.980637Z","steps":["trace[534140551] 'process raft request' (duration: 250.151434ms)","trace[534140551] 'compare' (duration: 16.172645ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:41:19.980759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.847674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"warn","ts":"2026-09-03T16:41:19.980758Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.794896ms","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-09-03T16:41:19.980849Z","caller":"traceutil/trace.go:171","msg":"trace[803701876] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:9958; }","duration":"232.977807ms","start":"2026-09-03T16:41:19.747855Z","end":"2026-09-03T16:41:19.980833Z","steps":["trace[803701876] 'agreement among raft nodes before linearized reading' (duration: 232.757862ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:41:19.980869Z","caller":"traceutil/trace.go:171","msg":"trace[290996976] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:9958; }","duration":"227.932319ms","start":"2026-09-03T16:41:19.752918Z","end":"2026-09-03T16:41:19.98085Z","steps":["trace[290996976] 'agreement among raft nodes before linearized reading' (duration: 227.719975ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:41:20.120012Z","caller":"traceutil/trace.go:171","msg":"trace[464384108] transaction","detail":"{read_only:false; response_revision:9960; number_of_response:1; }","duration":"134.871ms","start":"2026-09-03T16:41:19.985101Z","end":"2026-09-03T16:41:20.119972Z","steps":["trace[464384108] 'process raft request' (duration: 102.436547ms)","trace[464384108] 'compare' (duration: 32.225669ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:41:20.120097Z","caller":"traceutil/trace.go:171","msg":"trace[644910821] transaction","detail":"{read_only:false; response_revision:9961; number_of_response:1; }","duration":"120.888672ms","start":"2026-09-03T16:41:19.999156Z","end":"2026-09-03T16:41:20.120044Z","steps":["trace[644910821] 'process raft request' (duration: 120.725879ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:42:10.889017Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7925} {"level":"info","ts":"2026-09-03T16:42:10.997278Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7925,"took":"105.77144ms","hash":859856413,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":25276416,"current-db-size-in-use":"25 MB"} {"level":"info","ts":"2026-09-03T16:42:10.997343Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":859856413,"revision":7925,"compact-revision":5545} {"level":"info","ts":"2026-09-03T16:46:57.54511Z","caller":"traceutil/trace.go:171","msg":"trace[2012252248] transaction","detail":"{read_only:false; response_revision:12386; number_of_response:1; }","duration":"197.926447ms","start":"2026-09-03T16:46:57.347163Z","end":"2026-09-03T16:46:57.54509Z","steps":["trace[2012252248] 'process raft request' (duration: 197.805004ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:46:57.599979Z","caller":"traceutil/trace.go:171","msg":"trace[867531256] transaction","detail":"{read_only:false; response_revision:12387; number_of_response:1; }","duration":"236.973533ms","start":"2026-09-03T16:46:57.362965Z","end":"2026-09-03T16:46:57.599939Z","steps":["trace[867531256] 'process raft request' (duration: 210.575227ms)","trace[867531256] 'compare' (duration: 26.181001ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:46:57.793809Z","caller":"traceutil/trace.go:171","msg":"trace[1698947670] transaction","detail":"{read_only:false; response_revision:12390; number_of_response:1; }","duration":"134.575958ms","start":"2026-09-03T16:46:57.659204Z","end":"2026-09-03T16:46:57.79378Z","steps":["trace[1698947670] 'process raft request' (duration: 134.394844ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:47:10.897394Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10318} {"level":"info","ts":"2026-09-03T16:47:10.986665Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10318,"took":"87.680711ms","hash":2851208121,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":25829376,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-09-03T16:47:10.986711Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2851208121,"revision":10318,"compact-revision":7925} {"level":"info","ts":"2026-09-03T16:52:10.905744Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12502} {"level":"info","ts":"2026-09-03T16:52:10.996286Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12502,"took":"88.272552ms","hash":2623916324,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":27979776,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-03T16:52:10.996361Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2623916324,"revision":12502,"compact-revision":10318} {"level":"info","ts":"2026-09-03T16:53:33.042174Z","caller":"traceutil/trace.go:171","msg":"trace[922016336] transaction","detail":"{read_only:false; response_revision:15017; number_of_response:1; }","duration":"151.034746ms","start":"2026-09-03T16:53:32.891108Z","end":"2026-09-03T16:53:33.042143Z","steps":["trace[922016336] 'process raft request' (duration: 150.833362ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:53:51.341364Z","caller":"traceutil/trace.go:171","msg":"trace[1439477693] transaction","detail":"{read_only:false; response_revision:15124; number_of_response:1; }","duration":"210.823398ms","start":"2026-09-03T16:53:51.130515Z","end":"2026-09-03T16:53:51.341338Z","steps":["trace[1439477693] 'process raft request' (duration: 210.645884ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:53:51.451625Z","caller":"traceutil/trace.go:171","msg":"trace[243410489] transaction","detail":"{read_only:false; response_revision:15126; number_of_response:1; }","duration":"264.834664ms","start":"2026-09-03T16:53:51.186773Z","end":"2026-09-03T16:53:51.451608Z","steps":["trace[243410489] 'process raft request' (duration: 264.780313ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:53:51.451677Z","caller":"traceutil/trace.go:171","msg":"trace[1379258963] transaction","detail":"{read_only:false; response_revision:15125; number_of_response:1; }","duration":"267.818648ms","start":"2026-09-03T16:53:51.183839Z","end":"2026-09-03T16:53:51.451658Z","steps":["trace[1379258963] 'process raft request' (duration: 230.115461ms)","trace[1379258963] 'compare' (duration: 37.389061ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:53:51.451938Z","caller":"traceutil/trace.go:171","msg":"trace[63716571] transaction","detail":"{read_only:false; response_revision:15127; number_of_response:1; }","duration":"153.638912ms","start":"2026-09-03T16:53:51.29827Z","end":"2026-09-03T16:53:51.451909Z","steps":["trace[63716571] 'process raft request' (duration: 153.318195ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:53:51.451992Z","caller":"traceutil/trace.go:171","msg":"trace[1943757289] linearizableReadLoop","detail":"{readStateIndex:15656; appliedIndex:15653; }","duration":"175.822997ms","start":"2026-09-03T16:53:51.276156Z","end":"2026-09-03T16:53:51.451979Z","steps":["trace[1943757289] 'read index received' (duration: 64.984563ms)","trace[1943757289] 'applied index is now lower than readState.Index' (duration: 110.836574ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T16:53:51.452117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.96377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/openvswitch-jhw6b\" ","response":"range_response_count:1 size:12360"} {"level":"info","ts":"2026-09-03T16:53:51.452153Z","caller":"traceutil/trace.go:171","msg":"trace[1120100554] range","detail":"{range_begin:/registry/pods/openstack/openvswitch-jhw6b; range_end:; response_count:1; response_revision:15128; }","duration":"176.047102ms","start":"2026-09-03T16:53:51.276097Z","end":"2026-09-03T16:53:51.452144Z","steps":["trace[1120100554] 'agreement among raft nodes before linearized reading' (duration: 175.914809ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:54:02.068345Z","caller":"traceutil/trace.go:171","msg":"trace[1225044968] transaction","detail":"{read_only:false; response_revision:15302; number_of_response:1; }","duration":"102.134451ms","start":"2026-09-03T16:54:01.966186Z","end":"2026-09-03T16:54:02.068321Z","steps":["trace[1225044968] 'process raft request' (duration: 101.976617ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:55:44.188847Z","caller":"traceutil/trace.go:171","msg":"trace[635438615] transaction","detail":"{read_only:false; response_revision:16394; number_of_response:1; }","duration":"253.837477ms","start":"2026-09-03T16:55:43.934985Z","end":"2026-09-03T16:55:44.188822Z","steps":["trace[635438615] 'process raft request' (duration: 253.644953ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:55:44.300359Z","caller":"traceutil/trace.go:171","msg":"trace[757484479] transaction","detail":"{read_only:false; response_revision:16395; number_of_response:1; }","duration":"311.75662ms","start":"2026-09-03T16:55:43.988545Z","end":"2026-09-03T16:55:44.300302Z","steps":["trace[757484479] 'process raft request' (duration: 309.845099ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:55:44.300602Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T16:55:43.988505Z","time spent":"311.963585ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T16:55:44.300843Z","caller":"traceutil/trace.go:171","msg":"trace[2130904270] transaction","detail":"{read_only:false; response_revision:16396; number_of_response:1; }","duration":"309.070693ms","start":"2026-09-03T16:55:43.991726Z","end":"2026-09-03T16:55:44.300797Z","steps":["trace[2130904270] 'process raft request' (duration: 308.521781ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:55:44.3009Z","caller":"traceutil/trace.go:171","msg":"trace[1704705524] transaction","detail":"{read_only:false; response_revision:16397; number_of_response:1; }","duration":"263.474444ms","start":"2026-09-03T16:55:44.03739Z","end":"2026-09-03T16:55:44.300864Z","steps":["trace[1704705524] 'process raft request' (duration: 263.214839ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:55:44.300898Z","caller":"traceutil/trace.go:171","msg":"trace[2061097150] linearizableReadLoop","detail":"{readStateIndex:16962; appliedIndex:16959; }","duration":"264.162738ms","start":"2026-09-03T16:55:44.036689Z","end":"2026-09-03T16:55:44.300852Z","steps":["trace[2061097150] 'read index received' (duration: 152.023562ms)","trace[2061097150] 'applied index is now lower than readState.Index' (duration: 112.137926ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T16:55:44.300988Z","caller":"traceutil/trace.go:171","msg":"trace[601135453] transaction","detail":"{read_only:false; response_revision:16398; number_of_response:1; }","duration":"263.215798ms","start":"2026-09-03T16:55:44.037745Z","end":"2026-09-03T16:55:44.300961Z","steps":["trace[601135453] 'process raft request' (duration: 263.010504ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:55:44.301031Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T16:55:43.991707Z","time spent":"309.219195ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T16:55:44.301082Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.387072ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-user\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-09-03T16:55:44.301085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.389643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-03T16:55:44.301125Z","caller":"traceutil/trace.go:171","msg":"trace[254279992] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-user; range_end:; response_count:0; response_revision:16398; }","duration":"264.449674ms","start":"2026-09-03T16:55:44.036663Z","end":"2026-09-03T16:55:44.301112Z","steps":["trace[254279992] 'agreement among raft nodes before linearized reading' (duration: 264.374863ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:55:44.301123Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.735609ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-ks-endpoints\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-03T16:55:44.301128Z","caller":"traceutil/trace.go:171","msg":"trace[572691807] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:16398; }","duration":"264.460564ms","start":"2026-09-03T16:55:44.036654Z","end":"2026-09-03T16:55:44.301115Z","steps":["trace[572691807] 'agreement among raft nodes before linearized reading' (duration: 264.378563ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:55:44.301167Z","caller":"traceutil/trace.go:171","msg":"trace[393978451] range","detail":"{range_begin:/registry/jobs/openstack/nova-ks-endpoints; range_end:; response_count:0; response_revision:16398; }","duration":"263.813091ms","start":"2026-09-03T16:55:44.037339Z","end":"2026-09-03T16:55:44.301152Z","steps":["trace[393978451] 'agreement among raft nodes before linearized reading' (duration: 263.716879ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:55:44.301192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.325474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-7xhqk\" ","response":"range_response_count:1 size:6521"} {"level":"info","ts":"2026-09-03T16:55:44.301217Z","caller":"traceutil/trace.go:171","msg":"trace[2101780874] range","detail":"{range_begin:/registry/pods/openstack/keepalived-7xhqk; range_end:; response_count:1; response_revision:16398; }","duration":"142.390135ms","start":"2026-09-03T16:55:44.158819Z","end":"2026-09-03T16:55:44.301209Z","steps":["trace[2101780874] 'agreement among raft nodes before linearized reading' (duration: 142.260113ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T16:55:44.301378Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.437275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5522"} {"level":"info","ts":"2026-09-03T16:55:44.301412Z","caller":"traceutil/trace.go:171","msg":"trace[1900281179] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:16398; }","duration":"257.478075ms","start":"2026-09-03T16:55:44.043919Z","end":"2026-09-03T16:55:44.301397Z","steps":["trace[1900281179] 'agreement among raft nodes before linearized reading' (duration: 257.304632ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T16:57:10.91611Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14516} {"level":"info","ts":"2026-09-03T16:57:11.003973Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14516,"took":"83.212166ms","hash":1065370578,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":31240192,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-03T16:57:11.004037Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1065370578,"revision":14516,"compact-revision":12502} {"level":"warn","ts":"2026-09-03T17:01:24.09023Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.164892ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/\" range_end:\"/registry/pods/openstack0\" ","response":"range_response_count:97 size:955620"} {"level":"info","ts":"2026-09-03T17:01:24.090305Z","caller":"traceutil/trace.go:171","msg":"trace[1204242758] range","detail":"{range_begin:/registry/pods/openstack/; range_end:/registry/pods/openstack0; response_count:97; response_revision:18872; }","duration":"157.249634ms","start":"2026-09-03T17:01:23.933042Z","end":"2026-09-03T17:01:24.090292Z","steps":["trace[1204242758] 'range keys from in-memory index tree' (duration: 156.520549ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:01:26.198796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.841079ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-03T17:01:26.199192Z","caller":"traceutil/trace.go:171","msg":"trace[1880281354] transaction","detail":"{read_only:false; response_revision:18884; number_of_response:1; }","duration":"363.13988ms","start":"2026-09-03T17:01:25.836036Z","end":"2026-09-03T17:01:26.199176Z","steps":["trace[1880281354] 'process raft request' (duration: 146.80785ms)","trace[1880281354] 'compare' (duration: 215.506341ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T17:01:26.199245Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:01:25.836018Z","time spent":"363.202242ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T17:01:26.199284Z","caller":"traceutil/trace.go:171","msg":"trace[580297732] transaction","detail":"{read_only:false; response_revision:18886; number_of_response:1; }","duration":"362.053508ms","start":"2026-09-03T17:01:25.837202Z","end":"2026-09-03T17:01:26.199255Z","steps":["trace[580297732] 'process raft request' (duration: 361.784232ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T17:01:26.199344Z","caller":"traceutil/trace.go:171","msg":"trace[2086275252] linearizableReadLoop","detail":"{readStateIndex:19534; appliedIndex:19530; }","duration":"356.56815ms","start":"2026-09-03T17:01:25.842767Z","end":"2026-09-03T17:01:26.199335Z","steps":["trace[2086275252] 'read index received' (duration: 16.027804ms)","trace[2086275252] 'applied index is now lower than readState.Index' (duration: 340.539016ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T17:01:26.19946Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:01:25.837194Z","time spent":"362.16627ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T17:01:26.199535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.771314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-09-03T17:01:26.199573Z","caller":"traceutil/trace.go:171","msg":"trace[827828066] range","detail":"{range_begin:/registry/apiregistration.k8s.io/apiservices/; range_end:/registry/apiregistration.k8s.io/apiservices0; response_count:0; response_revision:18887; }","duration":"356.857656ms","start":"2026-09-03T17:01:25.842701Z","end":"2026-09-03T17:01:26.199559Z","steps":["trace[827828066] 'agreement among raft nodes before linearized reading' (duration: 356.742964ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:01:26.199599Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:01:25.842679Z","time spent":"356.912518ms","remote":"127.0.0.1:48006","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":43,"response size":31,"request content":"key:\"/registry/apiregistration.k8s.io/apiservices/\" range_end:\"/registry/apiregistration.k8s.io/apiservices0\" count_only:true "} {"level":"info","ts":"2026-09-03T17:01:26.199794Z","caller":"traceutil/trace.go:171","msg":"trace[1886382517] transaction","detail":"{read_only:false; response_revision:18885; number_of_response:1; }","duration":"363.60227ms","start":"2026-09-03T17:01:25.836146Z","end":"2026-09-03T17:01:26.199749Z","steps":["trace[1886382517] 'process raft request' (duration: 362.742202ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:01:26.199875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.944403ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/neutron-ks-user-dcd8f\" ","response":"range_response_count:1 size:9298"} {"level":"info","ts":"2026-09-03T17:01:26.19999Z","caller":"traceutil/trace.go:171","msg":"trace[609853963] range","detail":"{range_begin:/registry/pods/openstack/neutron-ks-user-dcd8f; range_end:; response_count:1; response_revision:18887; }","duration":"182.092267ms","start":"2026-09-03T17:01:26.017877Z","end":"2026-09-03T17:01:26.19997Z","steps":["trace[609853963] 'agreement among raft nodes before linearized reading' (duration: 181.77199ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:01:26.200001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:01:25.836138Z","time spent":"363.745993ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T17:01:26.200059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.802211ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-03T17:01:26.200116Z","caller":"traceutil/trace.go:171","msg":"trace[672888206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:18887; }","duration":"103.885432ms","start":"2026-09-03T17:01:26.096214Z","end":"2026-09-03T17:01:26.200099Z","steps":["trace[672888206] 'agreement among raft nodes before linearized reading' (duration: 103.797901ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T17:02:05.536067Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"427150911cb3975a","local-member-applied-index":20007,"local-member-snapshot-index":10005,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-03T17:02:05.542374Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20007} {"level":"info","ts":"2026-09-03T17:02:05.542474Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15007} {"level":"info","ts":"2026-09-03T17:02:10.923225Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16948} {"level":"info","ts":"2026-09-03T17:02:11.031766Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16948,"took":"104.138639ms","hash":4162015680,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":32952320,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-09-03T17:02:11.031823Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4162015680,"revision":16948,"compact-revision":14516} {"level":"info","ts":"2026-09-03T17:07:10.935034Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19380} {"level":"info","ts":"2026-09-03T17:07:11.070349Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19380,"took":"132.110994ms","hash":2707930821,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":30797824,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-03T17:07:11.070423Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2707930821,"revision":19380,"compact-revision":16948} {"level":"info","ts":"2026-09-03T17:07:57.580512Z","caller":"traceutil/trace.go:171","msg":"trace[1028202213] transaction","detail":"{read_only:false; response_revision:21945; number_of_response:1; }","duration":"108.440746ms","start":"2026-09-03T17:07:57.471987Z","end":"2026-09-03T17:07:57.580428Z","steps":["trace[1028202213] 'process raft request' (duration: 108.303933ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:10:36.998342Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.738809ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-03T17:10:36.998477Z","caller":"traceutil/trace.go:171","msg":"trace[2113324576] transaction","detail":"{read_only:false; response_revision:22804; number_of_response:1; }","duration":"600.80887ms","start":"2026-09-03T17:10:36.397654Z","end":"2026-09-03T17:10:36.998462Z","steps":["trace[2113324576] 'process raft request' (duration: 600.766899ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:10:36.998523Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:10:36.397631Z","time spent":"600.870511ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T17:10:36.998628Z","caller":"traceutil/trace.go:171","msg":"trace[1170137319] transaction","detail":"{read_only:false; response_revision:22803; number_of_response:1; }","duration":"627.45568ms","start":"2026-09-03T17:10:36.37104Z","end":"2026-09-03T17:10:36.998495Z","steps":["trace[1170137319] 'process raft request' (duration: 191.502037ms)","trace[1170137319] 'compare' (duration: 435.577704ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T17:10:36.998852Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:10:36.371015Z","time spent":"627.725706ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T17:10:42.441062Z","caller":"traceutil/trace.go:171","msg":"trace[2122697407] transaction","detail":"{read_only:false; response_revision:22830; number_of_response:1; }","duration":"197.18521ms","start":"2026-09-03T17:10:42.243853Z","end":"2026-09-03T17:10:42.441038Z","steps":["trace[2122697407] 'process raft request' (duration: 197.023157ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:10:42.777149Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.993152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-09-03T17:10:42.77728Z","caller":"traceutil/trace.go:171","msg":"trace[1928085339] transaction","detail":"{read_only:false; response_revision:22831; number_of_response:1; }","duration":"377.007727ms","start":"2026-09-03T17:10:42.400248Z","end":"2026-09-03T17:10:42.777256Z","steps":["trace[1928085339] 'process raft request' (duration: 78.81245ms)","trace[1928085339] 'compare' (duration: 297.799868ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T17:10:42.777363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:10:42.400223Z","time spent":"377.097579ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T17:10:43.20441Z","caller":"traceutil/trace.go:171","msg":"trace[1197540568] transaction","detail":"{read_only:false; response_revision:22832; number_of_response:1; }","duration":"162.561747ms","start":"2026-09-03T17:10:43.041824Z","end":"2026-09-03T17:10:43.204386Z","steps":["trace[1197540568] 'process raft request' (duration: 162.404854ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T17:10:43.223992Z","caller":"traceutil/trace.go:171","msg":"trace[735124930] linearizableReadLoop","detail":"{readStateIndex:23614; appliedIndex:23612; }","duration":"127.634748ms","start":"2026-09-03T17:10:43.096336Z","end":"2026-09-03T17:10:43.223971Z","steps":["trace[735124930] 'read index received' (duration: 107.889095ms)","trace[735124930] 'applied index is now lower than readState.Index' (duration: 19.744663ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T17:10:43.224066Z","caller":"traceutil/trace.go:171","msg":"trace[1359532808] transaction","detail":"{read_only:false; response_revision:22833; number_of_response:1; }","duration":"181.695797ms","start":"2026-09-03T17:10:43.042327Z","end":"2026-09-03T17:10:43.224023Z","steps":["trace[1359532808] 'process raft request' (duration: 181.551034ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:10:43.224111Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.755781ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-03T17:10:43.22417Z","caller":"traceutil/trace.go:171","msg":"trace[1541040624] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22833; }","duration":"127.852163ms","start":"2026-09-03T17:10:43.096292Z","end":"2026-09-03T17:10:43.224144Z","steps":["trace[1541040624] 'agreement among raft nodes before linearized reading' (duration: 127.769661ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T17:10:43.426942Z","caller":"traceutil/trace.go:171","msg":"trace[582350041] transaction","detail":"{read_only:false; response_revision:22834; number_of_response:1; }","duration":"151.854008ms","start":"2026-09-03T17:10:43.275051Z","end":"2026-09-03T17:10:43.426905Z","steps":["trace[582350041] 'process raft request' (duration: 128.580199ms)","trace[582350041] 'compare' (duration: 23.114525ms)"],"step_count":2} {"level":"warn","ts":"2026-09-03T17:10:43.785557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.843416ms","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-09-03T17:10:43.785557Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.231914ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-09-03T17:10:43.785636Z","caller":"traceutil/trace.go:171","msg":"trace[2022689553] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:22834; }","duration":"192.964009ms","start":"2026-09-03T17:10:43.592655Z","end":"2026-09-03T17:10:43.785619Z","steps":["trace[2022689553] 'range keys from in-memory index tree' (duration: 192.683243ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T17:10:43.785642Z","caller":"traceutil/trace.go:171","msg":"trace[766338576] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22834; }","duration":"220.361416ms","start":"2026-09-03T17:10:43.565267Z","end":"2026-09-03T17:10:43.785628Z","steps":["trace[766338576] 'range keys from in-memory index tree' (duration: 220.083051ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:10:43.785875Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.470155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-09-03T17:10:43.786008Z","caller":"traceutil/trace.go:171","msg":"trace[1968969278] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:22834; }","duration":"155.66312ms","start":"2026-09-03T17:10:43.630314Z","end":"2026-09-03T17:10:43.785978Z","steps":["trace[1968969278] 'range keys from in-memory index tree' (duration: 155.184079ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T17:12:10.94214Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21696} {"level":"info","ts":"2026-09-03T17:12:11.065945Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21696,"took":"119.976594ms","hash":1788938046,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":27627520,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-09-03T17:12:11.066029Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1788938046,"revision":21696,"compact-revision":19380} {"level":"info","ts":"2026-09-03T17:15:02.335054Z","caller":"traceutil/trace.go:171","msg":"trace[801798810] linearizableReadLoop","detail":"{readStateIndex:26251; appliedIndex:26247; }","duration":"455.149843ms","start":"2026-09-03T17:15:01.879878Z","end":"2026-09-03T17:15:02.335028Z","steps":["trace[801798810] 'read index received' (duration: 22.864ms)","trace[801798810] 'applied index is now lower than readState.Index' (duration: 432.284713ms)"],"step_count":2} {"level":"info","ts":"2026-09-03T17:15:02.335078Z","caller":"traceutil/trace.go:171","msg":"trace[902830498] transaction","detail":"{read_only:false; response_revision:25390; number_of_response:1; }","duration":"472.609208ms","start":"2026-09-03T17:15:01.862422Z","end":"2026-09-03T17:15:02.335031Z","steps":["trace[902830498] 'process raft request' (duration: 472.285081ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T17:15:02.335259Z","caller":"traceutil/trace.go:171","msg":"trace[1484372961] transaction","detail":"{read_only:false; response_revision:25391; number_of_response:1; }","duration":"472.004116ms","start":"2026-09-03T17:15:01.863127Z","end":"2026-09-03T17:15:02.335131Z","steps":["trace[1484372961] 'process raft request' (duration: 471.766531ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T17:15:02.335413Z","caller":"traceutil/trace.go:171","msg":"trace[113687987] transaction","detail":"{read_only:false; response_revision:25392; number_of_response:1; }","duration":"472.102367ms","start":"2026-09-03T17:15:01.863298Z","end":"2026-09-03T17:15:02.3354Z","steps":["trace[113687987] 'process raft request' (duration: 471.671278ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:15:02.335451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:15:01.862397Z","time spent":"472.794562ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4387,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-03T17:15:02.335425Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:15:01.86312Z","time spent":"472.21967ms","remote":"127.0.0.1:47926","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5225,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T17:15:02.335535Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.593853ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-03T17:15:02.335666Z","caller":"traceutil/trace.go:171","msg":"trace[1385086687] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:25392; }","duration":"455.815689ms","start":"2026-09-03T17:15:01.879831Z","end":"2026-09-03T17:15:02.335646Z","steps":["trace[1385086687] 'agreement among raft nodes before linearized reading' (duration: 455.583283ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:15:02.335711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:15:01.879808Z","time spent":"455.892181ms","remote":"127.0.0.1:50368","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":0,"response size":29,"request content":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true "} {"level":"warn","ts":"2026-09-03T17:15:02.335573Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:15:01.863291Z","time spent":"472.164049ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T17:15:02.713013Z","caller":"traceutil/trace.go:171","msg":"trace[1122092665] transaction","detail":"{read_only:false; response_revision:25393; number_of_response:1; }","duration":"455.223095ms","start":"2026-09-03T17:15:02.257762Z","end":"2026-09-03T17:15:02.712985Z","steps":["trace[1122092665] 'process raft request' (duration: 454.9817ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:15:02.713196Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:15:02.257734Z","time spent":"455.354188ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T17:15:02.713326Z","caller":"traceutil/trace.go:171","msg":"trace[75498400] transaction","detail":"{read_only:false; response_revision:25394; number_of_response:1; }","duration":"441.344097ms","start":"2026-09-03T17:15:02.271949Z","end":"2026-09-03T17:15:02.713294Z","steps":["trace[75498400] 'process raft request' (duration: 440.9836ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:15:02.713524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:15:02.271916Z","time spent":"441.491441ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T17:15:02.713461Z","caller":"traceutil/trace.go:171","msg":"trace[1663059660] linearizableReadLoop","detail":"{readStateIndex:26254; appliedIndex:26251; }","duration":"378.239815ms","start":"2026-09-03T17:15:02.335156Z","end":"2026-09-03T17:15:02.713396Z","steps":["trace[1663059660] 'read index received' (duration: 377.751094ms)","trace[1663059660] 'applied index is now lower than readState.Index' (duration: 487.93µs)"],"step_count":2} {"level":"info","ts":"2026-09-03T17:15:02.713698Z","caller":"traceutil/trace.go:171","msg":"trace[1078127220] transaction","detail":"{read_only:false; response_revision:25395; number_of_response:1; }","duration":"413.374157ms","start":"2026-09-03T17:15:02.300304Z","end":"2026-09-03T17:15:02.713678Z","steps":["trace[1078127220] 'process raft request' (duration: 412.885897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:15:02.713792Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:15:02.300279Z","time spent":"413.478871ms","remote":"127.0.0.1:47754","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-03T17:15:02.713809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.540787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"warn","ts":"2026-09-03T17:15:02.71381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"616.110317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-09-03T17:15:02.713874Z","caller":"traceutil/trace.go:171","msg":"trace[975344523] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25395; }","duration":"616.201039ms","start":"2026-09-03T17:15:02.097657Z","end":"2026-09-03T17:15:02.713858Z","steps":["trace[975344523] 'agreement among raft nodes before linearized reading' (duration: 616.108487ms)"],"step_count":1} {"level":"info","ts":"2026-09-03T17:15:02.71389Z","caller":"traceutil/trace.go:171","msg":"trace[762964660] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25395; }","duration":"726.619128ms","start":"2026-09-03T17:15:01.98723Z","end":"2026-09-03T17:15:02.71385Z","steps":["trace[762964660] 'agreement among raft nodes before linearized reading' (duration: 726.333711ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:15:02.713922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:15:02.097639Z","time spent":"616.2729ms","remote":"127.0.0.1:47506","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-09-03T17:15:02.713957Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:15:01.987214Z","time spent":"726.732241ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12239,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-09-03T17:15:02.719992Z","caller":"traceutil/trace.go:171","msg":"trace[1762109247] transaction","detail":"{read_only:false; response_revision:25396; number_of_response:1; }","duration":"380.249368ms","start":"2026-09-03T17:15:02.339706Z","end":"2026-09-03T17:15:02.719955Z","steps":["trace[1762109247] 'process raft request' (duration: 380.015143ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:15:02.720205Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:15:02.33968Z","time spent":"380.412891ms","remote":"127.0.0.1:47692","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4433,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T17:15:02.720298Z","caller":"traceutil/trace.go:171","msg":"trace[892833739] transaction","detail":"{read_only:false; response_revision:25398; number_of_response:1; }","duration":"376.550639ms","start":"2026-09-03T17:15:02.343718Z","end":"2026-09-03T17:15:02.720269Z","steps":["trace[892833739] 'process raft request' (duration: 376.196661ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:15:02.720353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"376.079098ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager\" ","response":"range_response_count:1 size:5243"} {"level":"info","ts":"2026-09-03T17:15:02.720016Z","caller":"traceutil/trace.go:171","msg":"trace[1712732935] transaction","detail":"{read_only:false; response_revision:25397; number_of_response:1; }","duration":"379.969862ms","start":"2026-09-03T17:15:02.340018Z","end":"2026-09-03T17:15:02.719988Z","steps":["trace[1712732935] 'process raft request' (duration: 379.805748ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:15:02.720447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:15:02.343693Z","time spent":"376.666451ms","remote":"127.0.0.1:47950","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4760,"response count":0,"response size":41,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-03T17:15:02.720409Z","caller":"traceutil/trace.go:171","msg":"trace[2115429176] range","detail":"{range_begin:/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager; range_end:; response_count:1; response_revision:25398; }","duration":"376.17234ms","start":"2026-09-03T17:15:02.344222Z","end":"2026-09-03T17:15:02.720395Z","steps":["trace[2115429176] 'agreement among raft nodes before linearized reading' (duration: 375.932655ms)"],"step_count":1} {"level":"warn","ts":"2026-09-03T17:15:02.720562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:15:02.340007Z","time spent":"380.463472ms","remote":"127.0.0.1:47604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":925,"response count":0,"response size":41,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-03T17:15:02.720562Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-03T17:15:02.34421Z","time spent":"376.300603ms","remote":"127.0.0.1:47926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":103,"response count":1,"response size":5266,"request content":"key:\"/registry/deployments/capi-kubeadm-control-plane-system/capi-kubeadm-control-plane-controller-manager\" "} {"level":"info","ts":"2026-09-03T17:16:19.479274Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-00000000000069e4.wal"} {"level":"info","ts":"2026-09-03T17:17:10.949412Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23576} {"level":"info","ts":"2026-09-03T17:17:11.078668Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23576,"took":"124.231994ms","hash":1641588585,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":44036096,"current-db-size-in-use":"44 MB"} {"level":"info","ts":"2026-09-03T17:17:11.078742Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1641588585,"revision":23576,"compact-revision":21696} {"level":"info","ts":"2026-09-03T17:22:10.958974Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26644} {"level":"info","ts":"2026-09-03T17:22:11.102593Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26644,"took":"139.876879ms","hash":3193549891,"current-db-size-bytes":56205312,"current-db-size":"56 MB","current-db-size-in-use-bytes":31154176,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-03T17:22:11.102652Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3193549891,"revision":26644,"compact-revision":23576} {"level":"info","ts":"2026-09-03T17:22:33.662174Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"427150911cb3975a","local-member-applied-index":30008,"local-member-snapshot-index":20007,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-09-03T17:22:33.668532Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30008} {"level":"info","ts":"2026-09-03T17:22:33.668809Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25008}