{"level":"warn","ts":"2026-09-16T21:43:34.761184Z","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-16T21:43:34.761393Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.109: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.109:2380","--initial-cluster=instance=https://162.253.55.109:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.109:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.109: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-16T21:43:34.761495Z","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-16T21:43:34.761543Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.109:2380"]} {"level":"info","ts":"2026-09-16T21:43:34.761671Z","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-16T21:43:34.762624Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.109:2379"]} {"level":"info","ts":"2026-09-16T21:43:34.762701Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.109:2380"],"listen-peer-urls":["https://162.253.55.109:2380"],"advertise-client-urls":["https://162.253.55.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.109: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-16T21:43:35.851879Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"1.088923529s"} {"level":"info","ts":"2026-09-16T21:43:35.967298Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"1ee91cef4f0023c4","cluster-id":"8dccb05df97999f"} {"level":"info","ts":"2026-09-16T21:43:35.96743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 switched to configuration voters=()"} {"level":"info","ts":"2026-09-16T21:43:35.967463Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 became follower at term 0"} {"level":"info","ts":"2026-09-16T21:43:35.967477Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 1ee91cef4f0023c4 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-16T21:43:35.967495Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 became follower at term 1"} {"level":"info","ts":"2026-09-16T21:43:35.967554Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 switched to configuration voters=(2227343304859591620)"} {"level":"warn","ts":"2026-09-16T21:43:35.974447Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-16T21:43:35.979735Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-16T21:43:35.981671Z","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-16T21:43:35.985735Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"1ee91cef4f0023c4","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-16T21:43:35.985973Z","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-16T21:43:35.986086Z","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-16T21:43:35.986097Z","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-16T21:43:35.986197Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"1ee91cef4f0023c4","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-16T21:43:35.988814Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 switched to configuration voters=(2227343304859591620)"} {"level":"info","ts":"2026-09-16T21:43:35.98907Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"8dccb05df97999f","local-member-id":"1ee91cef4f0023c4","added-peer-id":"1ee91cef4f0023c4","added-peer-peer-urls":["https://162.253.55.109:2380"]} {"level":"info","ts":"2026-09-16T21:43:35.99173Z","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-16T21:43:35.991903Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.109:2380"} {"level":"info","ts":"2026-09-16T21:43:35.991976Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.109:2380"} {"level":"info","ts":"2026-09-16T21:43:35.992088Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"1ee91cef4f0023c4","initial-advertise-peer-urls":["https://162.253.55.109:2380"],"listen-peer-urls":["https://162.253.55.109:2380"],"advertise-client-urls":["https://162.253.55.109:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.109:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-16T21:43:35.992146Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-16T21:43:36.868557Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 is starting a new election at term 1"} {"level":"info","ts":"2026-09-16T21:43:36.868609Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 became pre-candidate at term 1"} {"level":"info","ts":"2026-09-16T21:43:36.868641Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 received MsgPreVoteResp from 1ee91cef4f0023c4 at term 1"} {"level":"info","ts":"2026-09-16T21:43:36.868654Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 became candidate at term 2"} {"level":"info","ts":"2026-09-16T21:43:36.868707Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 received MsgVoteResp from 1ee91cef4f0023c4 at term 2"} {"level":"info","ts":"2026-09-16T21:43:36.86872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"1ee91cef4f0023c4 became leader at term 2"} {"level":"info","ts":"2026-09-16T21:43:36.868729Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 1ee91cef4f0023c4 elected leader 1ee91cef4f0023c4 at term 2"} {"level":"info","ts":"2026-09-16T21:43:36.870025Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"1ee91cef4f0023c4","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.109:2379]}","request-path":"/0/members/1ee91cef4f0023c4/attributes","cluster-id":"8dccb05df97999f","publish-timeout":"7s"} {"level":"info","ts":"2026-09-16T21:43:36.870035Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-16T21:43:36.870074Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T21:43:36.870182Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-16T21:43:36.870467Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-16T21:43:36.870508Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-09-16T21:43:36.871427Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"8dccb05df97999f","local-member-id":"1ee91cef4f0023c4","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T21:43:36.871655Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T21:43:36.871803Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-16T21:43:36.873133Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-16T21:43:36.873786Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.109:2379"} {"level":"info","ts":"2026-09-16T21:44:16.841282Z","caller":"traceutil/trace.go:171","msg":"trace[1771257326] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"194.795008ms","start":"2026-09-16T21:44:16.646459Z","end":"2026-09-16T21:44:16.841254Z","steps":["trace[1771257326] 'process raft request' (duration: 194.612204ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:16.887985Z","caller":"traceutil/trace.go:171","msg":"trace[2048568158] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"238.99903ms","start":"2026-09-16T21:44:16.648964Z","end":"2026-09-16T21:44:16.887963Z","steps":["trace[2048568158] 'process raft request' (duration: 238.879118ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:16.887985Z","caller":"traceutil/trace.go:171","msg":"trace[1954604347] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"240.341701ms","start":"2026-09-16T21:44:16.647614Z","end":"2026-09-16T21:44:16.887956Z","steps":["trace[1954604347] 'process raft request' (duration: 240.046075ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:16.888178Z","caller":"traceutil/trace.go:171","msg":"trace[1977215255] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"227.510312ms","start":"2026-09-16T21:44:16.660635Z","end":"2026-09-16T21:44:16.888146Z","steps":["trace[1977215255] 'process raft request' (duration: 227.343469ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:16.888535Z","caller":"traceutil/trace.go:171","msg":"trace[2131687366] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"233.103339ms","start":"2026-09-16T21:44:16.655416Z","end":"2026-09-16T21:44:16.88852Z","steps":["trace[2131687366] 'process raft request' (duration: 232.470685ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:16.888637Z","caller":"traceutil/trace.go:171","msg":"trace[1685429424] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"193.800486ms","start":"2026-09-16T21:44:16.69481Z","end":"2026-09-16T21:44:16.888611Z","steps":["trace[1685429424] 'process raft request' (duration: 193.325485ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:16.888608Z","caller":"traceutil/trace.go:171","msg":"trace[1047320368] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"229.156381ms","start":"2026-09-16T21:44:16.659393Z","end":"2026-09-16T21:44:16.88855Z","steps":["trace[1047320368] 'process raft request' (duration: 228.533386ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:16.888531Z","caller":"traceutil/trace.go:171","msg":"trace[287391512] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"224.923355ms","start":"2026-09-16T21:44:16.663581Z","end":"2026-09-16T21:44:16.888504Z","steps":["trace[287391512] 'process raft request' (duration: 224.432413ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:16.888798Z","caller":"traceutil/trace.go:171","msg":"trace[1659195523] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"212.802843ms","start":"2026-09-16T21:44:16.675986Z","end":"2026-09-16T21:44:16.888788Z","steps":["trace[1659195523] 'process raft request' (duration: 212.100967ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:16.888881Z","caller":"traceutil/trace.go:171","msg":"trace[329222064] transaction","detail":"{read_only:false; response_revision:541; number_of_response:1; }","duration":"173.671723ms","start":"2026-09-16T21:44:16.715201Z","end":"2026-09-16T21:44:16.888873Z","steps":["trace[329222064] 'process raft request' (duration: 172.988567ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:16.888472Z","caller":"traceutil/trace.go:171","msg":"trace[305316006] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"224.863654ms","start":"2026-09-16T21:44:16.663592Z","end":"2026-09-16T21:44:16.888456Z","steps":["trace[305316006] 'process raft request' (duration: 224.450974ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:44:17.314311Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"262.235123ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-16T21:44:17.314556Z","caller":"traceutil/trace.go:171","msg":"trace[1667591950] transaction","detail":"{read_only:false; number_of_response:1; response_revision:544; }","duration":"465.013944ms","start":"2026-09-16T21:44:16.849524Z","end":"2026-09-16T21:44:17.314538Z","steps":["trace[1667591950] 'process raft request' (duration: 464.971073ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:44:17.314756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:44:16.849507Z","time spent":"465.087495ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":102,"response count":0,"response size":7365,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T21:44:17.314996Z","caller":"traceutil/trace.go:171","msg":"trace[1503056836] transaction","detail":"{read_only:false; response_revision:544; number_of_response:1; }","duration":"465.947465ms","start":"2026-09-16T21:44:16.849008Z","end":"2026-09-16T21:44:17.314955Z","steps":["trace[1503056836] 'process raft request' (duration: 465.425854ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:17.315021Z","caller":"traceutil/trace.go:171","msg":"trace[685755787] transaction","detail":"{read_only:false; response_revision:543; number_of_response:1; }","duration":"468.299388ms","start":"2026-09-16T21:44:16.846693Z","end":"2026-09-16T21:44:17.314992Z","steps":["trace[685755787] 'process raft request' (duration: 204.863338ms)","trace[685755787] 'compare' (duration: 262.000548ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:44:17.315192Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:44:16.84667Z","time spent":"468.489302ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":995,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-16T21:44:17.31548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:44:16.848995Z","time spent":"466.13948ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7317,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T21:44:17.430569Z","caller":"traceutil/trace.go:171","msg":"trace[461608165] transaction","detail":"{read_only:false; response_revision:546; number_of_response:1; }","duration":"534.464692ms","start":"2026-09-16T21:44:16.89608Z","end":"2026-09-16T21:44:17.430545Z","steps":["trace[461608165] 'process raft request' (duration: 534.319429ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:44:17.430782Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:44:16.896072Z","time spent":"534.650986ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1004,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-16T21:44:17.430564Z","caller":"traceutil/trace.go:171","msg":"trace[772744670] transaction","detail":"{read_only:false; response_revision:545; number_of_response:1; }","duration":"534.528653ms","start":"2026-09-16T21:44:16.895995Z","end":"2026-09-16T21:44:17.430523Z","steps":["trace[772744670] 'process raft request' (duration: 521.950562ms)","trace[772744670] 'compare' (duration: 12.245044ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:44:17.430878Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:44:16.895972Z","time spent":"534.893222ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":980,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-16T21:44:17.430653Z","caller":"traceutil/trace.go:171","msg":"trace[20712004] transaction","detail":"{read_only:false; response_revision:547; number_of_response:1; }","duration":"530.128535ms","start":"2026-09-16T21:44:16.900514Z","end":"2026-09-16T21:44:17.430642Z","steps":["trace[20712004] 'process raft request' (duration: 529.945241ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:44:17.430958Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:44:16.900482Z","time spent":"530.443961ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":10063,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T21:44:17.431004Z","caller":"traceutil/trace.go:171","msg":"trace[1748685811] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"522.42145ms","start":"2026-09-16T21:44:16.908574Z","end":"2026-09-16T21:44:17.430996Z","steps":["trace[1748685811] 'process raft request' (duration: 521.988631ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:17.430685Z","caller":"traceutil/trace.go:171","msg":"trace[265827349] transaction","detail":"{read_only:false; response_revision:548; number_of_response:1; }","duration":"527.419024ms","start":"2026-09-16T21:44:16.903261Z","end":"2026-09-16T21:44:17.43068Z","steps":["trace[265827349] 'process raft request' (duration: 527.26201ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:17.431012Z","caller":"traceutil/trace.go:171","msg":"trace[1884017335] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:561; }","duration":"462.586305ms","start":"2026-09-16T21:44:16.9684Z","end":"2026-09-16T21:44:17.430986Z","steps":["trace[1884017335] 'read index received' (duration: 83.369907ms)","trace[1884017335] 'applied index is now lower than readState.Index' (duration: 379.214928ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:44:17.431076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:44:16.903242Z","time spent":"527.818802ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-16T21:44:17.431089Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:44:16.908543Z","time spent":"522.493672ms","remote":"127.0.0.1:42660","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":998,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-16T21:44:17.431167Z","caller":"traceutil/trace.go:171","msg":"trace[1462249998] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"520.317903ms","start":"2026-09-16T21:44:16.910834Z","end":"2026-09-16T21:44:17.431152Z","steps":["trace[1462249998] 'process raft request' (duration: 519.777111ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:17.431244Z","caller":"traceutil/trace.go:171","msg":"trace[1711039297] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"445.927372ms","start":"2026-09-16T21:44:16.985303Z","end":"2026-09-16T21:44:17.431231Z","steps":["trace[1711039297] 'process raft request' (duration: 445.634645ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:44:17.431277Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:44:16.985272Z","time spent":"445.989362ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-09-16T21:44:17.431342Z","caller":"traceutil/trace.go:171","msg":"trace[1752615527] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"516.867356ms","start":"2026-09-16T21:44:16.914467Z","end":"2026-09-16T21:44:17.431335Z","steps":["trace[1752615527] 'process raft request' (duration: 516.316244ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:44:17.431376Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:44:16.914448Z","time spent":"516.908386ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30393,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T21:44:17.431357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:44:16.910806Z","time spent":"520.441126ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":30374,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T21:44:17.431444Z","caller":"traceutil/trace.go:171","msg":"trace[1111928898] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"509.393357ms","start":"2026-09-16T21:44:16.922044Z","end":"2026-09-16T21:44:17.431438Z","steps":["trace[1111928898] 'process raft request' (duration: 508.814665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:44:17.431477Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:44:16.922036Z","time spent":"509.423917ms","remote":"127.0.0.1:42186","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":28739,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T21:44:17.431599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.20309ms","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-16T21:44:17.431621Z","caller":"traceutil/trace.go:171","msg":"trace[1210439169] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:555; }","duration":"463.261081ms","start":"2026-09-16T21:44:16.968354Z","end":"2026-09-16T21:44:17.431615Z","steps":["trace[1210439169] 'agreement among raft nodes before linearized reading' (duration: 463.175648ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:44:17.431639Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:44:16.968332Z","time spent":"463.302022ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":528,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-09-16T21:44:17.431786Z","caller":"traceutil/trace.go:171","msg":"trace[948926207] transaction","detail":"{read_only:false; response_revision:555; number_of_response:1; }","duration":"106.308646ms","start":"2026-09-16T21:44:17.325468Z","end":"2026-09-16T21:44:17.431777Z","steps":["trace[948926207] 'process raft request' (duration: 105.867456ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:44:17.431869Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"448.98217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-09-16T21:44:17.43194Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.065493ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:44:17.43197Z","caller":"traceutil/trace.go:171","msg":"trace[1985183592] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:556; }","duration":"217.118354ms","start":"2026-09-16T21:44:17.214845Z","end":"2026-09-16T21:44:17.431963Z","steps":["trace[1985183592] 'agreement among raft nodes before linearized reading' (duration: 216.954481ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:17.43193Z","caller":"traceutil/trace.go:171","msg":"trace[1108402954] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:556; }","duration":"449.081882ms","start":"2026-09-16T21:44:16.982833Z","end":"2026-09-16T21:44:17.431915Z","steps":["trace[1108402954] 'agreement among raft nodes before linearized reading' (duration: 448.941409ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:17.431886Z","caller":"traceutil/trace.go:171","msg":"trace[1183564443] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"106.475741ms","start":"2026-09-16T21:44:17.325315Z","end":"2026-09-16T21:44:17.43179Z","steps":["trace[1183564443] 'process raft request' (duration: 105.753904ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:44:17.43189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"411.966941ms","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-16T21:44:17.432308Z","caller":"traceutil/trace.go:171","msg":"trace[2023939985] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:556; }","duration":"412.41143ms","start":"2026-09-16T21:44:17.019878Z","end":"2026-09-16T21:44:17.43229Z","steps":["trace[2023939985] 'agreement among raft nodes before linearized reading' (duration: 411.94646ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:44:17.432357Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:44:17.019862Z","time spent":"412.480392ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-16T21:44:17.432059Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:44:16.982806Z","time spent":"449.235926ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-09-16T21:44:23.655943Z","caller":"traceutil/trace.go:171","msg":"trace[1227864994] transaction","detail":"{read_only:false; response_revision:641; number_of_response:1; }","duration":"121.29216ms","start":"2026-09-16T21:44:23.5338Z","end":"2026-09-16T21:44:23.655092Z","steps":["trace[1227864994] 'process raft request' (duration: 121.020664ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:23.810562Z","caller":"traceutil/trace.go:171","msg":"trace[348146943] transaction","detail":"{read_only:false; response_revision:643; number_of_response:1; }","duration":"153.403067ms","start":"2026-09-16T21:44:23.65712Z","end":"2026-09-16T21:44:23.810523Z","steps":["trace[348146943] 'process raft request' (duration: 81.431196ms)","trace[348146943] 'compare' (duration: 71.824828ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:44:23.833175Z","caller":"traceutil/trace.go:171","msg":"trace[1512301784] transaction","detail":"{read_only:false; response_revision:644; number_of_response:1; }","duration":"107.114914ms","start":"2026-09-16T21:44:23.726Z","end":"2026-09-16T21:44:23.833115Z","steps":["trace[1512301784] 'process raft request' (duration: 106.979821ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:24.431819Z","caller":"traceutil/trace.go:171","msg":"trace[1184737535] linearizableReadLoop","detail":"{readStateIndex:669; appliedIndex:668; }","duration":"217.473094ms","start":"2026-09-16T21:44:24.214297Z","end":"2026-09-16T21:44:24.43177Z","steps":["trace[1184737535] 'read index received' (duration: 217.227499ms)","trace[1184737535] 'applied index is now lower than readState.Index' (duration: 244.645µs)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:44:24.431981Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.659668ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:44:24.43202Z","caller":"traceutil/trace.go:171","msg":"trace[937568371] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:645; }","duration":"217.71757ms","start":"2026-09-16T21:44:24.214291Z","end":"2026-09-16T21:44:24.432009Z","steps":["trace[937568371] 'agreement among raft nodes before linearized reading' (duration: 217.611968ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:44:24.431951Z","caller":"traceutil/trace.go:171","msg":"trace[210802923] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"574.598558ms","start":"2026-09-16T21:44:23.85729Z","end":"2026-09-16T21:44:24.431888Z","steps":["trace[210802923] 'process raft request' (duration: 574.308492ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:44:24.432248Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:44:23.85727Z","time spent":"574.821513ms","remote":"127.0.0.1:42316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2735,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-16T21:44:24.432381Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.065373ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-vmb8l\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-09-16T21:44:24.432432Z","caller":"traceutil/trace.go:171","msg":"trace[966118303] range","detail":"{range_begin:/registry/pods/kube-system/cilium-vmb8l; range_end:; response_count:1; response_revision:645; }","duration":"203.132834ms","start":"2026-09-16T21:44:24.229289Z","end":"2026-09-16T21:44:24.432422Z","steps":["trace[966118303] 'agreement among raft nodes before linearized reading' (duration: 202.97563ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:44:24.432737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"159.37688ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-09-16T21:44:24.432789Z","caller":"traceutil/trace.go:171","msg":"trace[173688189] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:645; }","duration":"159.463963ms","start":"2026-09-16T21:44:24.273312Z","end":"2026-09-16T21:44:24.432776Z","steps":["trace[173688189] 'agreement among raft nodes before linearized reading' (duration: 159.156856ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:44:40.725444Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.824701ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-16T21:44:40.725614Z","caller":"traceutil/trace.go:171","msg":"trace[1889696017] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:808; }","duration":"128.085486ms","start":"2026-09-16T21:44:40.597493Z","end":"2026-09-16T21:44:40.725578Z","steps":["trace[1889696017] 'range keys from in-memory index tree' (duration: 127.476554ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:45:14.716025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.963644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumendpoints/cert-manager/cert-manager-startupapicheck-nlmhj\" ","response":"range_response_count:1 size:3478"} {"level":"info","ts":"2026-09-16T21:45:14.716153Z","caller":"traceutil/trace.go:171","msg":"trace[1262107870] range","detail":"{range_begin:/registry/cilium.io/ciliumendpoints/cert-manager/cert-manager-startupapicheck-nlmhj; range_end:; response_count:1; response_revision:1227; }","duration":"102.099156ms","start":"2026-09-16T21:45:14.614002Z","end":"2026-09-16T21:45:14.716101Z","steps":["trace[1262107870] 'range keys from in-memory index tree' (duration: 101.78182ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:45:59.390354Z","caller":"traceutil/trace.go:171","msg":"trace[1932660438] transaction","detail":"{read_only:false; response_revision:1869; number_of_response:1; }","duration":"120.410441ms","start":"2026-09-16T21:45:59.269926Z","end":"2026-09-16T21:45:59.390336Z","steps":["trace[1932660438] 'process raft request' (duration: 120.286509ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:46:02.827412Z","caller":"traceutil/trace.go:171","msg":"trace[955327659] transaction","detail":"{read_only:false; number_of_response:0; response_revision:1908; }","duration":"104.609214ms","start":"2026-09-16T21:46:02.722777Z","end":"2026-09-16T21:46:02.827386Z","steps":["trace[955327659] 'process raft request' (duration: 84.408954ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:46:09.025308Z","caller":"traceutil/trace.go:171","msg":"trace[1157646991] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"167.356111ms","start":"2026-09-16T21:46:08.857918Z","end":"2026-09-16T21:46:09.025274Z","steps":["trace[1157646991] 'process raft request' (duration: 167.180869ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:46:31.674198Z","caller":"traceutil/trace.go:171","msg":"trace[1350601555] transaction","detail":"{read_only:false; response_revision:2135; number_of_response:1; }","duration":"102.155561ms","start":"2026-09-16T21:46:31.572013Z","end":"2026-09-16T21:46:31.674169Z","steps":["trace[1350601555] 'process raft request' (duration: 101.998649ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:46:31.715227Z","caller":"traceutil/trace.go:171","msg":"trace[80480305] transaction","detail":"{read_only:false; response_revision:2136; number_of_response:1; }","duration":"135.097974ms","start":"2026-09-16T21:46:31.580099Z","end":"2026-09-16T21:46:31.715197Z","steps":["trace[80480305] 'process raft request' (duration: 134.719018ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:46:40.180898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.475095ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/ingress-nginx/ingress-nginx-controller\" ","response":"range_response_count:1 size:1986"} {"level":"info","ts":"2026-09-16T21:46:40.181135Z","caller":"traceutil/trace.go:171","msg":"trace[712970460] range","detail":"{range_begin:/registry/services/specs/ingress-nginx/ingress-nginx-controller; range_end:; response_count:1; response_revision:2193; }","duration":"102.752499ms","start":"2026-09-16T21:46:40.078341Z","end":"2026-09-16T21:46:40.181094Z","steps":["trace[712970460] 'range keys from in-memory index tree' (duration: 102.296361ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:08.139952Z","caller":"traceutil/trace.go:171","msg":"trace[1062330730] transaction","detail":"{read_only:false; response_revision:2397; number_of_response:1; }","duration":"179.332506ms","start":"2026-09-16T21:47:07.960563Z","end":"2026-09-16T21:47:08.139896Z","steps":["trace[1062330730] 'process raft request' (duration: 178.698035ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:08.150717Z","caller":"traceutil/trace.go:171","msg":"trace[581199802] transaction","detail":"{read_only:false; response_revision:2398; number_of_response:1; }","duration":"189.912808ms","start":"2026-09-16T21:47:07.960784Z","end":"2026-09-16T21:47:08.150697Z","steps":["trace[581199802] 'process raft request' (duration: 189.742545ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:08.150981Z","caller":"traceutil/trace.go:171","msg":"trace[1404131751] transaction","detail":"{read_only:false; response_revision:2399; number_of_response:1; }","duration":"190.110731ms","start":"2026-09-16T21:47:07.960849Z","end":"2026-09-16T21:47:08.15096Z","steps":["trace[1404131751] 'process raft request' (duration: 189.796766ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:08.151233Z","caller":"traceutil/trace.go:171","msg":"trace[1136861496] transaction","detail":"{read_only:false; response_revision:2400; number_of_response:1; }","duration":"189.960139ms","start":"2026-09-16T21:47:07.961247Z","end":"2026-09-16T21:47:08.151207Z","steps":["trace[1136861496] 'process raft request' (duration: 189.638244ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:08.151856Z","caller":"traceutil/trace.go:171","msg":"trace[1297261138] transaction","detail":"{read_only:false; response_revision:2401; number_of_response:1; }","duration":"190.466528ms","start":"2026-09-16T21:47:07.961361Z","end":"2026-09-16T21:47:08.151828Z","steps":["trace[1297261138] 'process raft request' (duration: 189.674136ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:08.15195Z","caller":"traceutil/trace.go:171","msg":"trace[870498745] transaction","detail":"{read_only:false; response_revision:2402; number_of_response:1; }","duration":"184.21056ms","start":"2026-09-16T21:47:07.967722Z","end":"2026-09-16T21:47:08.151932Z","steps":["trace[870498745] 'process raft request' (duration: 183.771014ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:11.632305Z","caller":"traceutil/trace.go:171","msg":"trace[272908096] transaction","detail":"{read_only:false; response_revision:2425; number_of_response:1; }","duration":"120.131446ms","start":"2026-09-16T21:47:11.512125Z","end":"2026-09-16T21:47:11.632257Z","steps":["trace[272908096] 'process raft request' (duration: 104.986642ms)","trace[272908096] 'compare' (duration: 14.940561ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:47:11.764603Z","caller":"traceutil/trace.go:171","msg":"trace[669946910] transaction","detail":"{read_only:false; response_revision:2426; number_of_response:1; }","duration":"208.784575ms","start":"2026-09-16T21:47:11.55579Z","end":"2026-09-16T21:47:11.764575Z","steps":["trace[669946910] 'process raft request' (duration: 177.655725ms)","trace[669946910] 'compare' (duration: 30.104195ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:47:12.586315Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.433178ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T21:47:12.58642Z","caller":"traceutil/trace.go:171","msg":"trace[814378196] transaction","detail":"{read_only:false; response_revision:2432; number_of_response:1; }","duration":"210.4874ms","start":"2026-09-16T21:47:12.375914Z","end":"2026-09-16T21:47:12.586402Z","steps":["trace[814378196] 'process raft request' (duration: 102.885259ms)","trace[814378196] 'compare' (duration: 107.259546ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:47:12.844758Z","caller":"traceutil/trace.go:171","msg":"trace[1928708472] transaction","detail":"{read_only:false; response_revision:2434; number_of_response:1; }","duration":"145.573448ms","start":"2026-09-16T21:47:12.699151Z","end":"2026-09-16T21:47:12.844724Z","steps":["trace[1928708472] 'process raft request' (duration: 145.284794ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:12.993143Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.368255ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T21:47:12.993261Z","caller":"traceutil/trace.go:171","msg":"trace[148674809] linearizableReadLoop","detail":"{readStateIndex:2525; appliedIndex:2523; }","duration":"236.277469ms","start":"2026-09-16T21:47:12.756963Z","end":"2026-09-16T21:47:12.993241Z","steps":["trace[148674809] 'read index received' (duration: 87.509921ms)","trace[148674809] 'applied index is now lower than readState.Index' (duration: 148.766268ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:47:12.993526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"219.941377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:4830"} {"level":"info","ts":"2026-09-16T21:47:12.993605Z","caller":"traceutil/trace.go:171","msg":"trace[1523473851] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2435; }","duration":"220.072038ms","start":"2026-09-16T21:47:12.773517Z","end":"2026-09-16T21:47:12.993589Z","steps":["trace[1523473851] 'agreement among raft nodes before linearized reading' (duration: 219.829804ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:12.993744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"191.452696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-09-16T21:47:12.993851Z","caller":"traceutil/trace.go:171","msg":"trace[1056306919] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2435; }","duration":"191.611468ms","start":"2026-09-16T21:47:12.802219Z","end":"2026-09-16T21:47:12.993831Z","steps":["trace[1056306919] 'agreement among raft nodes before linearized reading' (duration: 191.328414ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:12.993967Z","caller":"traceutil/trace.go:171","msg":"trace[1465718621] transaction","detail":"{read_only:false; response_revision:2435; number_of_response:1; }","duration":"278.939867ms","start":"2026-09-16T21:47:12.714337Z","end":"2026-09-16T21:47:12.993277Z","steps":["trace[1465718621] 'process raft request' (duration: 155.314178ms)","trace[1465718621] 'compare' (duration: 123.200563ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:47:12.994392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.484822ms","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-16T21:47:12.994498Z","caller":"traceutil/trace.go:171","msg":"trace[806584044] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2435; }","duration":"237.578679ms","start":"2026-09-16T21:47:12.756904Z","end":"2026-09-16T21:47:12.994483Z","steps":["trace[806584044] 'agreement among raft nodes before linearized reading' (duration: 236.410011ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:32.191884Z","caller":"traceutil/trace.go:171","msg":"trace[1868109796] transaction","detail":"{read_only:false; response_revision:2645; number_of_response:1; }","duration":"226.349177ms","start":"2026-09-16T21:47:31.965518Z","end":"2026-09-16T21:47:32.191867Z","steps":["trace[1868109796] 'process raft request' (duration: 226.257705ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:32.20503Z","caller":"traceutil/trace.go:171","msg":"trace[1214328008] transaction","detail":"{read_only:false; response_revision:2646; number_of_response:1; }","duration":"105.692208ms","start":"2026-09-16T21:47:32.099286Z","end":"2026-09-16T21:47:32.204978Z","steps":["trace[1214328008] 'process raft request' (duration: 105.491915ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:32.869532Z","caller":"traceutil/trace.go:171","msg":"trace[1237010369] transaction","detail":"{read_only:false; response_revision:2649; number_of_response:1; }","duration":"545.905991ms","start":"2026-09-16T21:47:32.323597Z","end":"2026-09-16T21:47:32.869503Z","steps":["trace[1237010369] 'process raft request' (duration: 545.729238ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:32.869696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:47:32.323573Z","time spent":"546.038142ms","remote":"127.0.0.1:42580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T21:47:32.870205Z","caller":"traceutil/trace.go:171","msg":"trace[359008731] transaction","detail":"{read_only:false; response_revision:2650; number_of_response:1; }","duration":"111.095951ms","start":"2026-09-16T21:47:32.759078Z","end":"2026-09-16T21:47:32.870174Z","steps":["trace[359008731] 'process raft request' (duration: 110.968809ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:47:32.870501Z","caller":"traceutil/trace.go:171","msg":"trace[1511025425] linearizableReadLoop","detail":"{readStateIndex:2748; appliedIndex:2749; }","duration":"338.372783ms","start":"2026-09-16T21:47:32.532112Z","end":"2026-09-16T21:47:32.870485Z","steps":["trace[1511025425] 'read index received' (duration: 338.365293ms)","trace[1511025425] 'applied index is now lower than readState.Index' (duration: 6.56µs)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:47:32.870627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.509485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:47:32.87067Z","caller":"traceutil/trace.go:171","msg":"trace[404515801] range","detail":"{range_begin:/registry/cilium.io/ciliumnetworkpolicies/; range_end:/registry/cilium.io/ciliumnetworkpolicies0; response_count:0; response_revision:2650; }","duration":"338.590556ms","start":"2026-09-16T21:47:32.532065Z","end":"2026-09-16T21:47:32.870655Z","steps":["trace[404515801] 'agreement among raft nodes before linearized reading' (duration: 338.516714ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:47:32.870706Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:47:32.532045Z","time spent":"338.650177ms","remote":"127.0.0.1:41856","response type":"/etcdserverpb.KV/Range","request count":0,"request size":90,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumnetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumnetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-16T21:47:32.870754Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.234707ms","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-16T21:47:32.870794Z","caller":"traceutil/trace.go:171","msg":"trace[411766570] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2650; }","duration":"106.323488ms","start":"2026-09-16T21:47:32.764457Z","end":"2026-09-16T21:47:32.87078Z","steps":["trace[411766570] 'agreement among raft nodes before linearized reading' (duration: 106.193806ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:48:06.852541Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.676785ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T21:48:06.8529Z","caller":"traceutil/trace.go:171","msg":"trace[863759837] linearizableReadLoop","detail":"{readStateIndex:2998; appliedIndex:2996; }","duration":"243.253967ms","start":"2026-09-16T21:48:06.609624Z","end":"2026-09-16T21:48:06.852878Z","steps":["trace[863759837] 'read index received' (duration: 136.31993ms)","trace[863759837] 'applied index is now lower than readState.Index' (duration: 106.931917ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:48:06.853067Z","caller":"traceutil/trace.go:171","msg":"trace[2029418429] transaction","detail":"{read_only:false; response_revision:2884; number_of_response:1; }","duration":"453.79004ms","start":"2026-09-16T21:48:06.399218Z","end":"2026-09-16T21:48:06.853008Z","steps":["trace[2029418429] 'process raft request' (duration: 453.436035ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:48:06.853153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.015675ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:48:06.853227Z","caller":"traceutil/trace.go:171","msg":"trace[2134626866] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:2884; }","duration":"162.147601ms","start":"2026-09-16T21:48:06.691063Z","end":"2026-09-16T21:48:06.853211Z","steps":["trace[2134626866] 'agreement among raft nodes before linearized reading' (duration: 162.002766ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:48:06.853235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:48:06.399155Z","time spent":"453.958537ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T21:48:06.853716Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"244.094231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:644"} {"level":"info","ts":"2026-09-16T21:48:06.853753Z","caller":"traceutil/trace.go:171","msg":"trace[443933236] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2884; }","duration":"244.171504ms","start":"2026-09-16T21:48:06.609571Z","end":"2026-09-16T21:48:06.853743Z","steps":["trace[443933236] 'agreement among raft nodes before linearized reading' (duration: 243.512078ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:48:06.853863Z","caller":"traceutil/trace.go:171","msg":"trace[246964487] transaction","detail":"{read_only:false; response_revision:2883; number_of_response:1; }","duration":"453.755328ms","start":"2026-09-16T21:48:06.39918Z","end":"2026-09-16T21:48:06.852935Z","steps":["trace[246964487] 'process raft request' (duration: 111.594704ms)","trace[246964487] 'compare' (duration: 341.457207ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:48:06.854001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:48:06.399155Z","time spent":"454.761599ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":514,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T21:48:07.340375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"359.831042ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T21:48:07.34092Z","caller":"traceutil/trace.go:171","msg":"trace[1944520654] linearizableReadLoop","detail":"{readStateIndex:2999; appliedIndex:2998; }","duration":"413.193231ms","start":"2026-09-16T21:48:06.927698Z","end":"2026-09-16T21:48:07.340891Z","steps":["trace[1944520654] 'read index received' (duration: 52.555396ms)","trace[1944520654] 'applied index is now lower than readState.Index' (duration: 360.635595ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:48:07.341087Z","caller":"traceutil/trace.go:171","msg":"trace[63424740] transaction","detail":"{read_only:false; response_revision:2885; number_of_response:1; }","duration":"480.634703ms","start":"2026-09-16T21:48:06.860434Z","end":"2026-09-16T21:48:07.341068Z","steps":["trace[63424740] 'process raft request' (duration: 119.919675ms)","trace[63424740] 'compare' (duration: 359.023081ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:48:07.341211Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:48:06.860413Z","time spent":"480.752787ms","remote":"127.0.0.1:42254","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":629,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T21:48:07.341456Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.734863ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-16T21:48:07.341512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.523664ms","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-16T21:48:07.341672Z","caller":"traceutil/trace.go:171","msg":"trace[1237857066] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2885; }","duration":"250.725564ms","start":"2026-09-16T21:48:07.090926Z","end":"2026-09-16T21:48:07.341652Z","steps":["trace[1237857066] 'agreement among raft nodes before linearized reading' (duration: 250.264965ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:48:07.341543Z","caller":"traceutil/trace.go:171","msg":"trace[2113921628] range","detail":"{range_begin:/registry/rabbitmq.com/permissions/; range_end:/registry/rabbitmq.com/permissions0; response_count:0; response_revision:2885; }","duration":"413.876959ms","start":"2026-09-16T21:48:06.927643Z","end":"2026-09-16T21:48:07.34152Z","steps":["trace[2113921628] 'agreement among raft nodes before linearized reading' (duration: 413.705002ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:48:07.341884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:48:06.927622Z","time spent":"414.241273ms","remote":"127.0.0.1:37812","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":27,"request content":"key:\"/registry/rabbitmq.com/permissions/\" range_end:\"/registry/rabbitmq.com/permissions0\" count_only:true "} {"level":"info","ts":"2026-09-16T21:48:07.543481Z","caller":"traceutil/trace.go:171","msg":"trace[1061474287] transaction","detail":"{read_only:false; response_revision:2886; number_of_response:1; }","duration":"196.503919ms","start":"2026-09-16T21:48:07.346955Z","end":"2026-09-16T21:48:07.543459Z","steps":["trace[1061474287] 'process raft request' (duration: 179.541065ms)","trace[1061474287] 'compare' (duration: 16.729654ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:48:08.018432Z","caller":"traceutil/trace.go:171","msg":"trace[2075148947] transaction","detail":"{read_only:false; response_revision:2887; number_of_response:1; }","duration":"671.24497ms","start":"2026-09-16T21:48:07.34716Z","end":"2026-09-16T21:48:08.018404Z","steps":["trace[2075148947] 'process raft request' (duration: 671.020791ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:48:08.018466Z","caller":"traceutil/trace.go:171","msg":"trace[1789906535] transaction","detail":"{read_only:false; response_revision:2888; number_of_response:1; }","duration":"667.923937ms","start":"2026-09-16T21:48:07.350517Z","end":"2026-09-16T21:48:08.018441Z","steps":["trace[1789906535] 'process raft request' (duration: 667.819653ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:48:08.01857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:48:07.347123Z","time spent":"671.372135ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T21:48:08.018617Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:48:07.350494Z","time spent":"668.045222ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":537,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T21:48:08.018786Z","caller":"traceutil/trace.go:171","msg":"trace[177012337] linearizableReadLoop","detail":"{readStateIndex:3002; appliedIndex:2999; }","duration":"583.775854ms","start":"2026-09-16T21:48:07.434603Z","end":"2026-09-16T21:48:08.018379Z","steps":["trace[177012337] 'read index received' (duration: 91.909222ms)","trace[177012337] 'applied index is now lower than readState.Index' (duration: 491.865312ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:48:08.019041Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.976787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/08db1feb.percona.com\" ","response":"range_response_count:1 size:525"} {"level":"info","ts":"2026-09-16T21:48:08.019076Z","caller":"traceutil/trace.go:171","msg":"trace[576522399] range","detail":"{range_begin:/registry/leases/openstack/08db1feb.percona.com; range_end:; response_count:1; response_revision:2888; }","duration":"150.056879ms","start":"2026-09-16T21:48:07.869011Z","end":"2026-09-16T21:48:08.019068Z","steps":["trace[576522399] 'agreement among raft nodes before linearized reading' (duration: 149.934665ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:48:08.019059Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"584.379667ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:439"} {"level":"warn","ts":"2026-09-16T21:48:08.019276Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"283.323424ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-09-16T21:48:08.019213Z","caller":"traceutil/trace.go:171","msg":"trace[150557323] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2888; }","duration":"584.642357ms","start":"2026-09-16T21:48:07.434552Z","end":"2026-09-16T21:48:08.019194Z","steps":["trace[150557323] 'agreement among raft nodes before linearized reading' (duration: 583.974141ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:48:08.01934Z","caller":"traceutil/trace.go:171","msg":"trace[474563137] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2888; }","duration":"283.429299ms","start":"2026-09-16T21:48:07.735894Z","end":"2026-09-16T21:48:08.019323Z","steps":["trace[474563137] 'agreement among raft nodes before linearized reading' (duration: 283.241451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:48:08.019368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"435.934169ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:48:08.019433Z","caller":"traceutil/trace.go:171","msg":"trace[615827952] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:2888; }","duration":"436.042344ms","start":"2026-09-16T21:48:07.583371Z","end":"2026-09-16T21:48:08.019413Z","steps":["trace[615827952] 'agreement among raft nodes before linearized reading' (duration: 435.901337ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:48:08.019446Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"538.62174ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:48:08.019487Z","caller":"traceutil/trace.go:171","msg":"trace[925804055] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/referencegrants/; range_end:/registry/gateway.networking.k8s.io/referencegrants0; response_count:0; response_revision:2888; }","duration":"538.705662ms","start":"2026-09-16T21:48:07.480772Z","end":"2026-09-16T21:48:08.019477Z","steps":["trace[925804055] 'agreement among raft nodes before linearized reading' (duration: 538.136429ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:48:08.019485Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:48:07.583344Z","time spent":"436.125867ms","remote":"127.0.0.1:43554","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true "} {"level":"warn","ts":"2026-09-16T21:48:08.019524Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:48:07.480752Z","time spent":"538.762865ms","remote":"127.0.0.1:43672","response type":"/etcdserverpb.KV/Range","request count":0,"request size":110,"response count":0,"response size":27,"request content":"key:\"/registry/gateway.networking.k8s.io/referencegrants/\" range_end:\"/registry/gateway.networking.k8s.io/referencegrants0\" count_only:true "} {"level":"warn","ts":"2026-09-16T21:48:08.019621Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:48:07.434527Z","time spent":"584.9477ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-16T21:48:08.437668Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"413.986359ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:567"} {"level":"info","ts":"2026-09-16T21:48:08.437747Z","caller":"traceutil/trace.go:171","msg":"trace[727868848] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2888; }","duration":"414.111884ms","start":"2026-09-16T21:48:08.023616Z","end":"2026-09-16T21:48:08.437728Z","steps":["trace[727868848] 'range keys from in-memory index tree' (duration: 413.810292ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:48:08.438018Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:48:08.023583Z","time spent":"414.196327ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":589,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-09-16T21:48:08.439399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.434724ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T21:48:08.439623Z","caller":"traceutil/trace.go:171","msg":"trace[606262138] transaction","detail":"{read_only:false; response_revision:2889; number_of_response:1; }","duration":"415.191978ms","start":"2026-09-16T21:48:08.024414Z","end":"2026-09-16T21:48:08.439606Z","steps":["trace[606262138] 'process raft request' (duration: 248.489542ms)","trace[606262138] 'compare' (duration: 165.857322ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:48:08.439823Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:48:08.024391Z","time spent":"415.268711ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T21:48:08.440379Z","caller":"traceutil/trace.go:171","msg":"trace[19858167] transaction","detail":"{read_only:false; response_revision:2890; number_of_response:1; }","duration":"414.862264ms","start":"2026-09-16T21:48:08.0255Z","end":"2026-09-16T21:48:08.440363Z","steps":["trace[19858167] 'process raft request' (duration: 414.669957ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:48:08.440381Z","caller":"traceutil/trace.go:171","msg":"trace[1955546529] linearizableReadLoop","detail":"{readStateIndex:3005; appliedIndex:3002; }","duration":"347.412325ms","start":"2026-09-16T21:48:08.09293Z","end":"2026-09-16T21:48:08.440342Z","steps":["trace[1955546529] 'read index received' (duration: 180.055074ms)","trace[1955546529] 'applied index is now lower than readState.Index' (duration: 167.356111ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:48:08.440557Z","caller":"traceutil/trace.go:171","msg":"trace[1005060753] transaction","detail":"{read_only:false; response_revision:2891; number_of_response:1; }","duration":"414.333873ms","start":"2026-09-16T21:48:08.026151Z","end":"2026-09-16T21:48:08.440485Z","steps":["trace[1005060753] 'process raft request' (duration: 414.152906ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:48:08.440585Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:48:08.025485Z","time spent":"414.932686ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T21:48:08.440746Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:48:08.026124Z","time spent":"414.5087ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T21:48:08.440775Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.666966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:48:08.441127Z","caller":"traceutil/trace.go:171","msg":"trace[2002630918] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:2891; }","duration":"348.073993ms","start":"2026-09-16T21:48:08.092893Z","end":"2026-09-16T21:48:08.440967Z","steps":["trace[2002630918] 'agreement among raft nodes before linearized reading' (duration: 347.662296ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:48:08.441363Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:48:08.09288Z","time spent":"348.453647ms","remote":"127.0.0.1:43784","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true "} {"level":"warn","ts":"2026-09-16T21:48:08.441554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.61287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1296"} {"level":"info","ts":"2026-09-16T21:48:08.441634Z","caller":"traceutil/trace.go:171","msg":"trace[1211629250] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2892; }","duration":"239.756066ms","start":"2026-09-16T21:48:08.201871Z","end":"2026-09-16T21:48:08.441627Z","steps":["trace[1211629250] 'agreement among raft nodes before linearized reading' (duration: 239.490176ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:48:08.441711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.23939ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:48:08.441921Z","caller":"traceutil/trace.go:171","msg":"trace[479010003] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2892; }","duration":"228.345164ms","start":"2026-09-16T21:48:08.213427Z","end":"2026-09-16T21:48:08.441772Z","steps":["trace[479010003] 'agreement among raft nodes before linearized reading' (duration: 228.156286ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:48:39.873014Z","caller":"traceutil/trace.go:171","msg":"trace[1212049922] linearizableReadLoop","detail":"{readStateIndex:3265; appliedIndex:3264; }","duration":"155.161294ms","start":"2026-09-16T21:48:39.717826Z","end":"2026-09-16T21:48:39.872987Z","steps":["trace[1212049922] 'read index received' (duration: 122.350439ms)","trace[1212049922] 'applied index is now lower than readState.Index' (duration: 32.809095ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:48:39.873072Z","caller":"traceutil/trace.go:171","msg":"trace[959660215] transaction","detail":"{read_only:false; response_revision:3135; number_of_response:1; }","duration":"174.115456ms","start":"2026-09-16T21:48:39.698917Z","end":"2026-09-16T21:48:39.873033Z","steps":["trace[959660215] 'process raft request' (duration: 141.342161ms)","trace[959660215] 'compare' (duration: 32.552186ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:48:39.873283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.433103ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-09-16T21:48:39.873341Z","caller":"traceutil/trace.go:171","msg":"trace[1955629945] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:3135; }","duration":"155.540307ms","start":"2026-09-16T21:48:39.717787Z","end":"2026-09-16T21:48:39.873328Z","steps":["trace[1955629945] 'agreement among raft nodes before linearized reading' (duration: 155.324039ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:49:04.821943Z","caller":"traceutil/trace.go:171","msg":"trace[1413203064] transaction","detail":"{read_only:false; response_revision:3359; number_of_response:1; }","duration":"131.912583ms","start":"2026-09-16T21:49:04.689988Z","end":"2026-09-16T21:49:04.821901Z","steps":["trace[1413203064] 'process raft request' (duration: 131.635194ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:50:31.4267Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.078467ms","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-16T21:50:31.426808Z","caller":"traceutil/trace.go:171","msg":"trace[401461327] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4526; }","duration":"108.21285ms","start":"2026-09-16T21:50:31.318577Z","end":"2026-09-16T21:50:31.42679Z","steps":["trace[401461327] 'agreement among raft nodes before linearized reading' (duration: 107.963674ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:50:36.199266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.913285ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-09-16T21:50:36.199338Z","caller":"traceutil/trace.go:171","msg":"trace[364478896] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4635; }","duration":"136.030428ms","start":"2026-09-16T21:50:36.063292Z","end":"2026-09-16T21:50:36.199322Z","steps":["trace[364478896] 'range keys from in-memory index tree' (duration: 135.734971ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:50:36.199353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"278.713245ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"warn","ts":"2026-09-16T21:50:36.199438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.67276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-5d9a9ef6-f4d8-4d62-aa1f-3ff2669f12db\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-09-16T21:50:36.199514Z","caller":"traceutil/trace.go:171","msg":"trace[1527308773] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-5d9a9ef6-f4d8-4d62-aa1f-3ff2669f12db; range_end:; response_count:1; response_revision:4635; }","duration":"101.787353ms","start":"2026-09-16T21:50:36.097707Z","end":"2026-09-16T21:50:36.199495Z","steps":["trace[1527308773] 'range keys from in-memory index tree' (duration: 101.350792ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:50:36.199451Z","caller":"traceutil/trace.go:171","msg":"trace[1075023686] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4635; }","duration":"278.858389ms","start":"2026-09-16T21:50:35.920568Z","end":"2026-09-16T21:50:36.199426Z","steps":["trace[1075023686] 'range keys from in-memory index tree' (duration: 278.49835ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:50:36.317661Z","caller":"traceutil/trace.go:171","msg":"trace[323408851] linearizableReadLoop","detail":"{readStateIndex:4837; appliedIndex:4834; }","duration":"104.47235ms","start":"2026-09-16T21:50:36.213139Z","end":"2026-09-16T21:50:36.317612Z","steps":["trace[323408851] 'read index received' (duration: 60.7867ms)","trace[323408851] 'applied index is now lower than readState.Index' (duration: 43.68415ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:50:36.317792Z","caller":"traceutil/trace.go:171","msg":"trace[583773682] transaction","detail":"{read_only:false; response_revision:4638; number_of_response:1; }","duration":"110.497438ms","start":"2026-09-16T21:50:36.20728Z","end":"2026-09-16T21:50:36.317778Z","steps":["trace[583773682] 'process raft request' (duration: 110.22603ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:50:36.317969Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.767787ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:50:36.317673Z","caller":"traceutil/trace.go:171","msg":"trace[1016002082] transaction","detail":"{read_only:false; response_revision:4637; number_of_response:1; }","duration":"110.939059ms","start":"2026-09-16T21:50:36.206694Z","end":"2026-09-16T21:50:36.317633Z","steps":["trace[1016002082] 'process raft request' (duration: 99.453155ms)","trace[1016002082] 'compare' (duration: 11.101734ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:50:36.318053Z","caller":"traceutil/trace.go:171","msg":"trace[124708588] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4638; }","duration":"104.930891ms","start":"2026-09-16T21:50:36.213106Z","end":"2026-09-16T21:50:36.318037Z","steps":["trace[124708588] 'agreement among raft nodes before linearized reading' (duration: 104.765367ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:50:48.059098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"239.393162ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-16T21:50:48.059586Z","caller":"traceutil/trace.go:171","msg":"trace[858278284] transaction","detail":"{read_only:false; response_revision:4816; number_of_response:1; }","duration":"427.003409ms","start":"2026-09-16T21:50:47.632548Z","end":"2026-09-16T21:50:48.059551Z","steps":["trace[858278284] 'process raft request' (duration: 187.044822ms)","trace[858278284] 'compare' (duration: 238.974813ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:50:48.059692Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:50:47.632521Z","time spent":"427.120502ms","remote":"127.0.0.1:42384","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-16T21:50:48.444381Z","caller":"traceutil/trace.go:171","msg":"trace[961868519] transaction","detail":"{read_only:false; response_revision:4818; number_of_response:1; }","duration":"164.818229ms","start":"2026-09-16T21:50:48.279534Z","end":"2026-09-16T21:50:48.444352Z","steps":["trace[961868519] 'process raft request' (duration: 164.617693ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:50:48.643874Z","caller":"traceutil/trace.go:171","msg":"trace[285370862] linearizableReadLoop","detail":"{readStateIndex:5025; appliedIndex:5023; }","duration":"215.192932ms","start":"2026-09-16T21:50:48.428643Z","end":"2026-09-16T21:50:48.643836Z","steps":["trace[285370862] 'read index received' (duration: 15.606917ms)","trace[285370862] 'applied index is now lower than readState.Index' (duration: 199.584245ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:50:48.644018Z","caller":"traceutil/trace.go:171","msg":"trace[1376292210] transaction","detail":"{read_only:false; response_revision:4819; number_of_response:1; }","duration":"318.603461ms","start":"2026-09-16T21:50:48.325354Z","end":"2026-09-16T21:50:48.643957Z","steps":["trace[1376292210] 'process raft request' (duration: 318.106639ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:50:48.644225Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-16T21:50:48.325324Z","time spent":"318.797555ms","remote":"127.0.0.1:42580","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":16430,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-16T21:50:48.644224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"215.568ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:660"} {"level":"info","ts":"2026-09-16T21:50:48.644298Z","caller":"traceutil/trace.go:171","msg":"trace[1864543934] range","detail":"{range_begin:/registry/configmaps/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4819; }","duration":"215.683123ms","start":"2026-09-16T21:50:48.428596Z","end":"2026-09-16T21:50:48.644279Z","steps":["trace[1864543934] 'agreement among raft nodes before linearized reading' (duration: 215.443988ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:50:48.712588Z","caller":"traceutil/trace.go:171","msg":"trace[1267366625] transaction","detail":"{read_only:false; response_revision:4820; number_of_response:1; }","duration":"260.83612ms","start":"2026-09-16T21:50:48.451722Z","end":"2026-09-16T21:50:48.712558Z","steps":["trace[1267366625] 'process raft request' (duration: 260.458092ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:50:48.712733Z","caller":"traceutil/trace.go:171","msg":"trace[383116559] transaction","detail":"{read_only:false; response_revision:4822; number_of_response:1; }","duration":"258.642817ms","start":"2026-09-16T21:50:48.454077Z","end":"2026-09-16T21:50:48.71272Z","steps":["trace[383116559] 'process raft request' (duration: 258.33696ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:50:48.712913Z","caller":"traceutil/trace.go:171","msg":"trace[1200176947] transaction","detail":"{read_only:false; response_revision:4823; number_of_response:1; }","duration":"258.499453ms","start":"2026-09-16T21:50:48.454397Z","end":"2026-09-16T21:50:48.712897Z","steps":["trace[1200176947] 'process raft request' (duration: 258.067393ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:50:48.712914Z","caller":"traceutil/trace.go:171","msg":"trace[1648602764] transaction","detail":"{read_only:false; response_revision:4821; number_of_response:1; }","duration":"260.267407ms","start":"2026-09-16T21:50:48.452588Z","end":"2026-09-16T21:50:48.712856Z","steps":["trace[1648602764] 'process raft request' (duration: 259.769026ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:50:48.713177Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.85121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-09-16T21:50:48.713218Z","caller":"traceutil/trace.go:171","msg":"trace[2072666003] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4823; }","duration":"267.941273ms","start":"2026-09-16T21:50:48.445264Z","end":"2026-09-16T21:50:48.713205Z","steps":["trace[2072666003] 'agreement among raft nodes before linearized reading' (duration: 267.747517ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:50:48.852027Z","caller":"traceutil/trace.go:171","msg":"trace[1869198550] linearizableReadLoop","detail":"{readStateIndex:5030; appliedIndex:5029; }","duration":"139.483969ms","start":"2026-09-16T21:50:48.712513Z","end":"2026-09-16T21:50:48.851997Z","steps":["trace[1869198550] 'read index received' (duration: 83.565102ms)","trace[1869198550] 'applied index is now lower than readState.Index' (duration: 55.916977ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:50:48.85208Z","caller":"traceutil/trace.go:171","msg":"trace[706178391] transaction","detail":"{read_only:false; number_of_response:0; response_revision:4823; }","duration":"200.408705ms","start":"2026-09-16T21:50:48.651647Z","end":"2026-09-16T21:50:48.852056Z","steps":["trace[706178391] 'process raft request' (duration: 144.55826ms)","trace[706178391] 'compare' (duration: 55.731663ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:50:48.852331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.851045ms","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-16T21:50:48.85239Z","caller":"traceutil/trace.go:171","msg":"trace[382780741] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4823; }","duration":"141.967258ms","start":"2026-09-16T21:50:48.710407Z","end":"2026-09-16T21:50:48.852374Z","steps":["trace[382780741] 'agreement among raft nodes before linearized reading' (duration: 141.765443ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:50:48.852505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.874591ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:553"} {"level":"warn","ts":"2026-09-16T21:50:48.852611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.460873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"info","ts":"2026-09-16T21:50:48.852625Z","caller":"traceutil/trace.go:171","msg":"trace[104945447] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4823; }","duration":"136.043025ms","start":"2026-09-16T21:50:48.71655Z","end":"2026-09-16T21:50:48.852593Z","steps":["trace[104945447] 'agreement among raft nodes before linearized reading' (duration: 135.684316ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:50:48.852668Z","caller":"traceutil/trace.go:171","msg":"trace[208163433] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4823; }","duration":"204.543184ms","start":"2026-09-16T21:50:48.648105Z","end":"2026-09-16T21:50:48.852648Z","steps":["trace[208163433] 'agreement among raft nodes before linearized reading' (duration: 204.37917ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:51:25.394813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.419747ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-09-16T21:51:25.394853Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.668524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:51:25.394893Z","caller":"traceutil/trace.go:171","msg":"trace[1782772909] range","detail":"{range_begin:/registry/jobs/openstack/keystone-db-sync; range_end:; response_count:0; response_revision:5216; }","duration":"118.54247ms","start":"2026-09-16T21:51:25.276333Z","end":"2026-09-16T21:51:25.394876Z","steps":["trace[1782772909] 'range keys from in-memory index tree' (duration: 118.342105ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:51:25.394925Z","caller":"traceutil/trace.go:171","msg":"trace[1535178388] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5216; }","duration":"182.774917ms","start":"2026-09-16T21:51:25.212133Z","end":"2026-09-16T21:51:25.394908Z","steps":["trace[1535178388] 'range keys from in-memory index tree' (duration: 182.614853ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:51:25.395093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.746499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-credential-setup\" ","response":"range_response_count:1 size:6156"} {"level":"warn","ts":"2026-09-16T21:51:25.395136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.506871ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/keystone-fernet-setup\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:51:25.39517Z","caller":"traceutil/trace.go:171","msg":"trace[1575358072] range","detail":"{range_begin:/registry/jobs/openstack/keystone-fernet-setup; range_end:; response_count:0; response_revision:5216; }","duration":"119.557553ms","start":"2026-09-16T21:51:25.275603Z","end":"2026-09-16T21:51:25.395161Z","steps":["trace[1575358072] 'range keys from in-memory index tree' (duration: 119.44298ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:51:25.395209Z","caller":"traceutil/trace.go:171","msg":"trace[1718399701] range","detail":"{range_begin:/registry/jobs/openstack/keystone-credential-setup; range_end:; response_count:1; response_revision:5216; }","duration":"108.851731ms","start":"2026-09-16T21:51:25.286308Z","end":"2026-09-16T21:51:25.395159Z","steps":["trace[1718399701] 'range keys from in-memory index tree' (duration: 108.586735ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:51:25.395172Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.132524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1825"} {"level":"info","ts":"2026-09-16T21:51:25.395257Z","caller":"traceutil/trace.go:171","msg":"trace[435140684] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:5216; }","duration":"167.244787ms","start":"2026-09-16T21:51:25.228002Z","end":"2026-09-16T21:51:25.395246Z","steps":["trace[435140684] 'range keys from in-memory index tree' (duration: 166.970671ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:51:25.395368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.089352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:645"} {"level":"info","ts":"2026-09-16T21:51:25.39541Z","caller":"traceutil/trace.go:171","msg":"trace[1708954198] range","detail":"{range_begin:/registry/configmaps/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5216; }","duration":"103.169864ms","start":"2026-09-16T21:51:25.292229Z","end":"2026-09-16T21:51:25.395399Z","steps":["trace[1708954198] 'range keys from in-memory index tree' (duration: 102.962099ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:52:42.156168Z","caller":"traceutil/trace.go:171","msg":"trace[218484276] transaction","detail":"{read_only:false; number_of_response:0; response_revision:5846; }","duration":"127.825761ms","start":"2026-09-16T21:52:42.028308Z","end":"2026-09-16T21:52:42.156134Z","steps":["trace[218484276] 'process raft request' (duration: 78.418421ms)","trace[218484276] 'compare' (duration: 49.324758ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:52:42.402499Z","caller":"traceutil/trace.go:171","msg":"trace[486323090] transaction","detail":"{read_only:false; response_revision:5849; number_of_response:1; }","duration":"239.265037ms","start":"2026-09-16T21:52:42.163181Z","end":"2026-09-16T21:52:42.402446Z","steps":["trace[486323090] 'process raft request' (duration: 239.152365ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:52:42.402547Z","caller":"traceutil/trace.go:171","msg":"trace[1758911392] linearizableReadLoop","detail":"{readStateIndex:6104; appliedIndex:6100; }","duration":"188.588112ms","start":"2026-09-16T21:52:42.213937Z","end":"2026-09-16T21:52:42.402526Z","steps":["trace[1758911392] 'read index received' (duration: 20.304304ms)","trace[1758911392] 'applied index is now lower than readState.Index' (duration: 168.282768ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:52:42.402491Z","caller":"traceutil/trace.go:171","msg":"trace[518861949] transaction","detail":"{read_only:false; response_revision:5848; number_of_response:1; }","duration":"239.753447ms","start":"2026-09-16T21:52:42.162661Z","end":"2026-09-16T21:52:42.402415Z","steps":["trace[518861949] 'process raft request' (duration: 152.079267ms)","trace[518861949] 'compare' (duration: 87.429306ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:52:42.402692Z","caller":"traceutil/trace.go:171","msg":"trace[2021552259] transaction","detail":"{read_only:false; response_revision:5850; number_of_response:1; }","duration":"227.504037ms","start":"2026-09-16T21:52:42.175173Z","end":"2026-09-16T21:52:42.402678Z","steps":["trace[2021552259] 'process raft request' (duration: 227.207641ms)"],"step_count":1} {"level":"warn","ts":"2026-09-16T21:52:42.402901Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.94218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-16T21:52:42.402957Z","caller":"traceutil/trace.go:171","msg":"trace[1801273934] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5850; }","duration":"189.041002ms","start":"2026-09-16T21:52:42.2139Z","end":"2026-09-16T21:52:42.402941Z","steps":["trace[1801273934] 'agreement among raft nodes before linearized reading' (duration: 188.94697ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:52:42.578275Z","caller":"traceutil/trace.go:171","msg":"trace[1419911229] linearizableReadLoop","detail":"{readStateIndex:6106; appliedIndex:6105; }","duration":"146.30488ms","start":"2026-09-16T21:52:42.431942Z","end":"2026-09-16T21:52:42.578247Z","steps":["trace[1419911229] 'read index received' (duration: 16.923536ms)","trace[1419911229] 'applied index is now lower than readState.Index' (duration: 129.380054ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:52:42.578311Z","caller":"traceutil/trace.go:171","msg":"trace[2000864232] transaction","detail":"{read_only:false; response_revision:5852; number_of_response:1; }","duration":"164.074951ms","start":"2026-09-16T21:52:42.414206Z","end":"2026-09-16T21:52:42.578281Z","steps":["trace[2000864232] 'process raft request' (duration: 115.364416ms)","trace[2000864232] 'compare' (duration: 48.542202ms)"],"step_count":2} {"level":"warn","ts":"2026-09-16T21:52:42.57857Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.565386ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:568"} {"level":"warn","ts":"2026-09-16T21:52:42.578583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"146.625936ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:11497"} {"level":"info","ts":"2026-09-16T21:52:42.578625Z","caller":"traceutil/trace.go:171","msg":"trace[2140807254] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5852; }","duration":"126.665297ms","start":"2026-09-16T21:52:42.45194Z","end":"2026-09-16T21:52:42.578606Z","steps":["trace[2140807254] 'agreement among raft nodes before linearized reading' (duration: 126.452523ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:52:42.578641Z","caller":"traceutil/trace.go:171","msg":"trace[1297317737] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:5852; }","duration":"146.724777ms","start":"2026-09-16T21:52:42.4319Z","end":"2026-09-16T21:52:42.578625Z","steps":["trace[1297317737] 'agreement among raft nodes before linearized reading' (duration: 146.466742ms)"],"step_count":1} {"level":"info","ts":"2026-09-16T21:53:23.754556Z","caller":"traceutil/trace.go:171","msg":"trace[469542625] transaction","detail":"{read_only:false; number_of_response:0; response_revision:6123; }","duration":"102.208187ms","start":"2026-09-16T21:53:23.652319Z","end":"2026-09-16T21:53:23.754527Z","steps":["trace[469542625] 'process raft request' (duration: 75.682183ms)","trace[469542625] 'compare' (duration: 26.456692ms)"],"step_count":2} {"level":"info","ts":"2026-09-16T21:53:36.90151Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3092} {"level":"info","ts":"2026-09-16T21:53:37.018445Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3092,"took":"115.409467ms","hash":120120627,"current-db-size-bytes":58433536,"current-db-size":"58 MB","current-db-size-in-use-bytes":30863360,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-09-16T21:53:37.018501Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":120120627,"revision":3092,"compact-revision":-1}