{"level":"warn","ts":"2026-04-07T00:07:59.467043Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:07:59.467122Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.14:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.14:2380","--initial-cluster=instance=https://199.19.213.14:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.14:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.14: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-04-07T00:07:59.467185Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-04-07T00:07:59.467199Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.14:2380"]} {"level":"info","ts":"2026-04-07T00:07:59.467217Z","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-04-07T00:07:59.46784Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.14:2379"]} {"level":"info","ts":"2026-04-07T00:07:59.46791Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.19.213.14:2380"],"listen-peer-urls":["https://199.19.213.14:2380"],"advertise-client-urls":["https://199.19.213.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.14: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-04-07T00:07:59.471716Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.621481ms"} {"level":"info","ts":"2026-04-07T00:07:59.477252Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"329f95029c2a91ed","cluster-id":"5fd85759a04d0f9f"} {"level":"info","ts":"2026-04-07T00:07:59.477321Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed switched to configuration voters=()"} {"level":"info","ts":"2026-04-07T00:07:59.477342Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed became follower at term 0"} {"level":"info","ts":"2026-04-07T00:07:59.477356Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 329f95029c2a91ed [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-04-07T00:07:59.477365Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed became follower at term 1"} {"level":"info","ts":"2026-04-07T00:07:59.4774Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed switched to configuration voters=(3647798061635899885)"} {"level":"warn","ts":"2026-04-07T00:07:59.483827Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-04-07T00:07:59.485028Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-04-07T00:07:59.486222Z","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-04-07T00:07:59.489369Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"329f95029c2a91ed","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-04-07T00:07:59.489591Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"329f95029c2a91ed","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-04-07T00:07:59.489724Z","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-04-07T00:07:59.489792Z","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-04-07T00:07:59.489804Z","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-04-07T00:07:59.490903Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed switched to configuration voters=(3647798061635899885)"} {"level":"info","ts":"2026-04-07T00:07:59.492367Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5fd85759a04d0f9f","local-member-id":"329f95029c2a91ed","added-peer-id":"329f95029c2a91ed","added-peer-peer-urls":["https://199.19.213.14:2380"]} {"level":"info","ts":"2026-04-07T00:07:59.494618Z","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-04-07T00:07:59.494789Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.14:2380"} {"level":"info","ts":"2026-04-07T00:07:59.494849Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"329f95029c2a91ed","initial-advertise-peer-urls":["https://199.19.213.14:2380"],"listen-peer-urls":["https://199.19.213.14:2380"],"advertise-client-urls":["https://199.19.213.14:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.14:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-04-07T00:07:59.494868Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.14:2380"} {"level":"info","ts":"2026-04-07T00:07:59.494893Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-04-07T00:07:59.977921Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed is starting a new election at term 1"} {"level":"info","ts":"2026-04-07T00:07:59.97803Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed became pre-candidate at term 1"} {"level":"info","ts":"2026-04-07T00:07:59.978092Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed received MsgPreVoteResp from 329f95029c2a91ed at term 1"} {"level":"info","ts":"2026-04-07T00:07:59.978125Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed became candidate at term 2"} {"level":"info","ts":"2026-04-07T00:07:59.978142Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed received MsgVoteResp from 329f95029c2a91ed at term 2"} {"level":"info","ts":"2026-04-07T00:07:59.978166Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"329f95029c2a91ed became leader at term 2"} {"level":"info","ts":"2026-04-07T00:07:59.978187Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 329f95029c2a91ed elected leader 329f95029c2a91ed at term 2"} {"level":"info","ts":"2026-04-07T00:07:59.979927Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"329f95029c2a91ed","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.14:2379]}","request-path":"/0/members/329f95029c2a91ed/attributes","cluster-id":"5fd85759a04d0f9f","publish-timeout":"7s"} {"level":"info","ts":"2026-04-07T00:07:59.979958Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:07:59.980287Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-04-07T00:07:59.980325Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-04-07T00:07:59.979991Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:07:59.980054Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-04-07T00:07:59.984753Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-04-07T00:07:59.984825Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.14:2379"} {"level":"info","ts":"2026-04-07T00:07:59.990562Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5fd85759a04d0f9f","local-member-id":"329f95029c2a91ed","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:07:59.99066Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:07:59.990688Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-04-07T00:08:27.939065Z","caller":"traceutil/trace.go:171","msg":"trace[1491996288] linearizableReadLoop","detail":"{readStateIndex:486; appliedIndex:485; }","duration":"144.567403ms","start":"2026-04-07T00:08:27.794476Z","end":"2026-04-07T00:08:27.939044Z","steps":["trace[1491996288] 'read index received' (duration: 144.384679ms)","trace[1491996288] 'applied index is now lower than readState.Index' (duration: 181.564µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:27.939261Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.773777ms","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-04-07T00:08:27.939323Z","caller":"traceutil/trace.go:171","msg":"trace[1116170085] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:470; }","duration":"144.908441ms","start":"2026-04-07T00:08:27.794402Z","end":"2026-04-07T00:08:27.93931Z","steps":["trace[1116170085] 'agreement among raft nodes before linearized reading' (duration: 144.799639ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:27.939182Z","caller":"traceutil/trace.go:171","msg":"trace[1308880210] transaction","detail":"{read_only:false; response_revision:470; number_of_response:1; }","duration":"146.965033ms","start":"2026-04-07T00:08:27.792172Z","end":"2026-04-07T00:08:27.939137Z","steps":["trace[1308880210] 'process raft request' (duration: 146.741558ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:31.767481Z","caller":"traceutil/trace.go:171","msg":"trace[638005610] transaction","detail":"{read_only:false; response_revision:511; number_of_response:1; }","duration":"374.483569ms","start":"2026-04-07T00:08:31.392961Z","end":"2026-04-07T00:08:31.767445Z","steps":["trace[638005610] 'process raft request' (duration: 374.246372ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:31.768221Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:31.392936Z","time spent":"374.664873ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:08:31.777547Z","caller":"traceutil/trace.go:171","msg":"trace[921002548] transaction","detail":"{read_only:false; response_revision:514; number_of_response:1; }","duration":"213.978598ms","start":"2026-04-07T00:08:31.56354Z","end":"2026-04-07T00:08:31.777519Z","steps":["trace[921002548] 'process raft request' (duration: 213.817264ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:31.77828Z","caller":"traceutil/trace.go:171","msg":"trace[459877220] transaction","detail":"{read_only:false; response_revision:512; number_of_response:1; }","duration":"223.082925ms","start":"2026-04-07T00:08:31.554311Z","end":"2026-04-07T00:08:31.777394Z","steps":["trace[459877220] 'process raft request' (duration: 222.809548ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:31.778028Z","caller":"traceutil/trace.go:171","msg":"trace[2072150447] transaction","detail":"{read_only:false; response_revision:513; number_of_response:1; }","duration":"214.477549ms","start":"2026-04-07T00:08:31.562952Z","end":"2026-04-07T00:08:31.777429Z","steps":["trace[2072150447] 'process raft request' (duration: 214.359085ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:31.779221Z","caller":"traceutil/trace.go:171","msg":"trace[437654822] transaction","detail":"{read_only:false; response_revision:515; number_of_response:1; }","duration":"205.629528ms","start":"2026-04-07T00:08:31.573018Z","end":"2026-04-07T00:08:31.778648Z","steps":["trace[437654822] 'process raft request' (duration: 205.108585ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:31.779205Z","caller":"traceutil/trace.go:171","msg":"trace[737829049] transaction","detail":"{read_only:false; response_revision:516; number_of_response:1; }","duration":"205.164577ms","start":"2026-04-07T00:08:31.573955Z","end":"2026-04-07T00:08:31.77912Z","steps":["trace[737829049] 'process raft request' (duration: 204.738886ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:31.781142Z","caller":"traceutil/trace.go:171","msg":"trace[121198186] transaction","detail":"{read_only:false; response_revision:518; number_of_response:1; }","duration":"205.570417ms","start":"2026-04-07T00:08:31.575476Z","end":"2026-04-07T00:08:31.781046Z","steps":["trace[121198186] 'process raft request' (duration: 204.177012ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:31.78142Z","caller":"traceutil/trace.go:171","msg":"trace[517057626] transaction","detail":"{read_only:false; response_revision:517; number_of_response:1; }","duration":"205.932456ms","start":"2026-04-07T00:08:31.575452Z","end":"2026-04-07T00:08:31.781385Z","steps":["trace[517057626] 'process raft request' (duration: 203.68852ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:31.781857Z","caller":"traceutil/trace.go:171","msg":"trace[629490437] transaction","detail":"{read_only:false; response_revision:520; number_of_response:1; }","duration":"186.42878ms","start":"2026-04-07T00:08:31.595145Z","end":"2026-04-07T00:08:31.781574Z","steps":["trace[629490437] 'process raft request' (duration: 185.684161ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:31.782035Z","caller":"traceutil/trace.go:171","msg":"trace[1749064848] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"156.684055ms","start":"2026-04-07T00:08:31.625081Z","end":"2026-04-07T00:08:31.781765Z","steps":["trace[1749064848] 'process raft request' (duration: 156.204823ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:31.781993Z","caller":"traceutil/trace.go:171","msg":"trace[299381331] transaction","detail":"{read_only:false; response_revision:521; number_of_response:1; }","duration":"169.835245ms","start":"2026-04-07T00:08:31.611863Z","end":"2026-04-07T00:08:31.781699Z","steps":["trace[299381331] 'process raft request' (duration: 169.193179ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:31.781927Z","caller":"traceutil/trace.go:171","msg":"trace[1051985611] transaction","detail":"{read_only:false; response_revision:519; number_of_response:1; }","duration":"205.562707ms","start":"2026-04-07T00:08:31.576111Z","end":"2026-04-07T00:08:31.781673Z","steps":["trace[1051985611] 'process raft request' (duration: 204.030679ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:38.790327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.367918ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-07T00:08:38.847247Z","caller":"traceutil/trace.go:171","msg":"trace[459735652] transaction","detail":"{read_only:false; response_revision:625; number_of_response:1; }","duration":"173.943405ms","start":"2026-04-07T00:08:38.673274Z","end":"2026-04-07T00:08:38.847218Z","steps":["trace[459735652] 'process raft request' (duration: 173.251597ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:39.391127Z","caller":"traceutil/trace.go:171","msg":"trace[1443193378] linearizableReadLoop","detail":"{readStateIndex:647; appliedIndex:646; }","duration":"291.721774ms","start":"2026-04-07T00:08:39.099297Z","end":"2026-04-07T00:08:39.391018Z","steps":["trace[1443193378] 'read index received' (duration: 290.953515ms)","trace[1443193378] 'applied index is now lower than readState.Index' (duration: 509.322µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:08:39.391522Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"292.121094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:08:39.391601Z","caller":"traceutil/trace.go:171","msg":"trace[402328803] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:626; }","duration":"292.307729ms","start":"2026-04-07T00:08:39.099258Z","end":"2026-04-07T00:08:39.391566Z","steps":["trace[402328803] 'agreement among raft nodes before linearized reading' (duration: 291.97713ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:08:39.391676Z","caller":"traceutil/trace.go:171","msg":"trace[667198611] transaction","detail":"{read_only:false; response_revision:626; number_of_response:1; }","duration":"484.859094ms","start":"2026-04-07T00:08:38.906745Z","end":"2026-04-07T00:08:39.391604Z","steps":["trace[667198611] 'process raft request' (duration: 483.580672ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:08:39.391895Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:08:38.906722Z","time spent":"485.085519ms","remote":"127.0.0.1:56004","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2603,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-04-07T00:09:24.324233Z","caller":"traceutil/trace.go:171","msg":"trace[1719227428] linearizableReadLoop","detail":"{readStateIndex:1218; appliedIndex:1217; }","duration":"224.17342ms","start":"2026-04-07T00:09:24.100032Z","end":"2026-04-07T00:09:24.324206Z","steps":["trace[1719227428] 'read index received' (duration: 224.079198ms)","trace[1719227428] 'applied index is now lower than readState.Index' (duration: 91.902µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:09:24.324378Z","caller":"traceutil/trace.go:171","msg":"trace[1739701825] transaction","detail":"{read_only:false; response_revision:1185; number_of_response:1; }","duration":"255.030438ms","start":"2026-04-07T00:09:24.069294Z","end":"2026-04-07T00:09:24.324324Z","steps":["trace[1739701825] 'process raft request' (duration: 254.73056ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:09:24.324501Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.419097ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:09:24.324634Z","caller":"traceutil/trace.go:171","msg":"trace[906370318] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1185; }","duration":"224.618602ms","start":"2026-04-07T00:09:24.099987Z","end":"2026-04-07T00:09:24.324606Z","steps":["trace[906370318] 'agreement among raft nodes before linearized reading' (duration: 224.367936ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:11.404629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.301414ms","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-04-07T00:10:11.405451Z","caller":"traceutil/trace.go:171","msg":"trace[1241587180] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1851; }","duration":"144.177515ms","start":"2026-04-07T00:10:11.26125Z","end":"2026-04-07T00:10:11.405428Z","steps":["trace[1241587180] 'range keys from in-memory index tree' (duration: 143.136569ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:10:11.876624Z","caller":"traceutil/trace.go:171","msg":"trace[1102674026] transaction","detail":"{read_only:false; response_revision:1854; number_of_response:1; }","duration":"103.143253ms","start":"2026-04-07T00:10:11.773432Z","end":"2026-04-07T00:10:11.876575Z","steps":["trace[1102674026] 'process raft request' (duration: 102.915928ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:57.163265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.601409ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:10:57.163596Z","caller":"traceutil/trace.go:171","msg":"trace[196698578] transaction","detail":"{read_only:false; response_revision:2158; number_of_response:1; }","duration":"234.71086ms","start":"2026-04-07T00:10:56.928788Z","end":"2026-04-07T00:10:57.163499Z","steps":["trace[196698578] 'process raft request' (duration: 113.768894ms)","trace[196698578] 'compare' (duration: 120.434394ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:57.30723Z","caller":"traceutil/trace.go:171","msg":"trace[720077465] linearizableReadLoop","detail":"{readStateIndex:2239; appliedIndex:2238; }","duration":"142.034708ms","start":"2026-04-07T00:10:57.165155Z","end":"2026-04-07T00:10:57.30719Z","steps":["trace[720077465] 'read index received' (duration: 137.479338ms)","trace[720077465] 'applied index is now lower than readState.Index' (duration: 4.55445ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:10:57.307303Z","caller":"traceutil/trace.go:171","msg":"trace[585702921] transaction","detail":"{read_only:false; response_revision:2159; number_of_response:1; }","duration":"170.708772ms","start":"2026-04-07T00:10:57.136573Z","end":"2026-04-07T00:10:57.307282Z","steps":["trace[585702921] 'process raft request' (duration: 166.162092ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:10:57.307346Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.174272ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:10:57.30752Z","caller":"traceutil/trace.go:171","msg":"trace[64462230] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2159; }","duration":"142.330865ms","start":"2026-04-07T00:10:57.165126Z","end":"2026-04-07T00:10:57.307457Z","steps":["trace[64462230] 'agreement among raft nodes before linearized reading' (duration: 142.160851ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:25.486044Z","caller":"traceutil/trace.go:171","msg":"trace[257294735] linearizableReadLoop","detail":"{readStateIndex:2930; appliedIndex:2929; }","duration":"129.434272ms","start":"2026-04-07T00:12:25.356582Z","end":"2026-04-07T00:12:25.486016Z","steps":["trace[257294735] 'read index received' (duration: 129.268098ms)","trace[257294735] 'applied index is now lower than readState.Index' (duration: 165.174µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:12:25.486114Z","caller":"traceutil/trace.go:171","msg":"trace[483790926] transaction","detail":"{read_only:false; response_revision:2823; number_of_response:1; }","duration":"148.36005ms","start":"2026-04-07T00:12:25.337714Z","end":"2026-04-07T00:12:25.486074Z","steps":["trace[483790926] 'process raft request' (duration: 148.154565ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:25.486266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.660448ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:435"} {"level":"info","ts":"2026-04-07T00:12:25.486308Z","caller":"traceutil/trace.go:171","msg":"trace[2084986937] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2823; }","duration":"129.743129ms","start":"2026-04-07T00:12:25.356553Z","end":"2026-04-07T00:12:25.486296Z","steps":["trace[2084986937] 'agreement among raft nodes before linearized reading' (duration: 129.591295ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:25.52462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"167.944904ms","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-04-07T00:12:25.524727Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.842896ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:554"} {"level":"info","ts":"2026-04-07T00:12:25.524735Z","caller":"traceutil/trace.go:171","msg":"trace[1340138687] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2824; }","duration":"168.116207ms","start":"2026-04-07T00:12:25.356595Z","end":"2026-04-07T00:12:25.524711Z","steps":["trace[1340138687] 'agreement among raft nodes before linearized reading' (duration: 167.78281ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:12:25.524779Z","caller":"traceutil/trace.go:171","msg":"trace[1626018244] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2824; }","duration":"149.918348ms","start":"2026-04-07T00:12:25.374844Z","end":"2026-04-07T00:12:25.524763Z","steps":["trace[1626018244] 'agreement among raft nodes before linearized reading' (duration: 149.765954ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:12:25.525021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.6284ms","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-04-07T00:12:25.525086Z","caller":"traceutil/trace.go:171","msg":"trace[2122732859] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2824; }","duration":"163.723502ms","start":"2026-04-07T00:12:25.361347Z","end":"2026-04-07T00:12:25.525071Z","steps":["trace[2122732859] 'agreement among raft nodes before linearized reading' (duration: 163.528317ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:14:57.1779Z","caller":"traceutil/trace.go:171","msg":"trace[1994865834] transaction","detail":"{read_only:false; response_revision:3826; number_of_response:1; }","duration":"181.846373ms","start":"2026-04-07T00:14:56.99602Z","end":"2026-04-07T00:14:57.177866Z","steps":["trace[1994865834] 'process raft request' (duration: 181.632958ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:30.954057Z","caller":"traceutil/trace.go:171","msg":"trace[871058737] transaction","detail":"{read_only:false; response_revision:5029; number_of_response:1; }","duration":"117.974913ms","start":"2026-04-07T00:16:30.836062Z","end":"2026-04-07T00:16:30.954037Z","steps":["trace[871058737] 'process raft request' (duration: 117.84988ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:31.204283Z","caller":"traceutil/trace.go:171","msg":"trace[1860654479] transaction","detail":"{read_only:false; response_revision:5031; number_of_response:1; }","duration":"365.604233ms","start":"2026-04-07T00:16:30.838666Z","end":"2026-04-07T00:16:31.20427Z","steps":["trace[1860654479] 'process raft request' (duration: 365.362107ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:31.204429Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:30.838644Z","time spent":"365.700026ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:16:31.204141Z","caller":"traceutil/trace.go:171","msg":"trace[2145332874] transaction","detail":"{read_only:false; response_revision:5030; number_of_response:1; }","duration":"365.50894ms","start":"2026-04-07T00:16:30.838581Z","end":"2026-04-07T00:16:31.20409Z","steps":["trace[2145332874] 'process raft request' (duration: 365.297045ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:31.204687Z","caller":"traceutil/trace.go:171","msg":"trace[1839889310] transaction","detail":"{read_only:false; response_revision:5032; number_of_response:1; }","duration":"365.796808ms","start":"2026-04-07T00:16:30.838861Z","end":"2026-04-07T00:16:31.204658Z","steps":["trace[1839889310] 'process raft request' (duration: 365.368728ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:31.204749Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:30.838512Z","time spent":"366.200588ms","remote":"127.0.0.1:56040","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-04-07T00:16:31.20483Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:30.83885Z","time spent":"365.903251ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:16:31.204873Z","caller":"traceutil/trace.go:171","msg":"trace[459082895] transaction","detail":"{read_only:false; response_revision:5033; number_of_response:1; }","duration":"365.662224ms","start":"2026-04-07T00:16:30.839185Z","end":"2026-04-07T00:16:31.204847Z","steps":["trace[459082895] 'process raft request' (duration: 365.348777ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:31.204951Z","caller":"traceutil/trace.go:171","msg":"trace[1447119164] transaction","detail":"{read_only:false; response_revision:5034; number_of_response:1; }","duration":"365.573822ms","start":"2026-04-07T00:16:30.839354Z","end":"2026-04-07T00:16:31.204927Z","steps":["trace[1447119164] 'process raft request' (duration: 365.410638ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:31.205009Z","caller":"traceutil/trace.go:171","msg":"trace[1877004923] transaction","detail":"{read_only:false; response_revision:5035; number_of_response:1; }","duration":"364.481636ms","start":"2026-04-07T00:16:30.840512Z","end":"2026-04-07T00:16:31.204994Z","steps":["trace[1877004923] 'process raft request' (duration: 364.327383ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:31.205019Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:30.839166Z","time spent":"365.776367ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:16:31.205082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:30.840492Z","time spent":"364.553458ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":515,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:16:31.20508Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:30.839332Z","time spent":"365.699285ms","remote":"127.0.0.1:56040","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-04-07T00:16:31.205151Z","caller":"traceutil/trace.go:171","msg":"trace[1774048272] linearizableReadLoop","detail":"{readStateIndex:5230; appliedIndex:5222; }","duration":"204.141038ms","start":"2026-04-07T00:16:31.000942Z","end":"2026-04-07T00:16:31.205084Z","steps":["trace[1774048272] 'read index received' (duration: 202.892307ms)","trace[1774048272] 'applied index is now lower than readState.Index' (duration: 1.24762ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:31.205181Z","caller":"traceutil/trace.go:171","msg":"trace[1677817532] transaction","detail":"{read_only:false; response_revision:5036; number_of_response:1; }","duration":"364.547427ms","start":"2026-04-07T00:16:30.840624Z","end":"2026-04-07T00:16:31.205171Z","steps":["trace[1677817532] 'process raft request' (duration: 364.292411ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:31.205218Z","caller":"traceutil/trace.go:171","msg":"trace[685529524] transaction","detail":"{read_only:false; response_revision:5037; number_of_response:1; }","duration":"274.632312ms","start":"2026-04-07T00:16:30.930576Z","end":"2026-04-07T00:16:31.205208Z","steps":["trace[685529524] 'process raft request' (duration: 274.419267ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:31.205239Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:16:30.840614Z","time spent":"364.594448ms","remote":"127.0.0.1:55956","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3702,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:16:31.205409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.465606ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q55vz\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-07T00:16:31.205453Z","caller":"traceutil/trace.go:171","msg":"trace[269177971] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q55vz; range_end:; response_count:1; response_revision:5037; }","duration":"204.545987ms","start":"2026-04-07T00:16:31.000896Z","end":"2026-04-07T00:16:31.205442Z","steps":["trace[269177971] 'agreement among raft nodes before linearized reading' (duration: 204.360493ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:31.222231Z","caller":"traceutil/trace.go:171","msg":"trace[876339127] transaction","detail":"{read_only:false; response_revision:5038; number_of_response:1; }","duration":"132.477394ms","start":"2026-04-07T00:16:31.089734Z","end":"2026-04-07T00:16:31.222211Z","steps":["trace[876339127] 'process raft request' (duration: 132.159666ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:31.222231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.295539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:16:31.222308Z","caller":"traceutil/trace.go:171","msg":"trace[1014826081] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5039; }","duration":"122.415831ms","start":"2026-04-07T00:16:31.099874Z","end":"2026-04-07T00:16:31.22229Z","steps":["trace[1014826081] 'agreement among raft nodes before linearized reading' (duration: 122.270278ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:40.108757Z","caller":"traceutil/trace.go:171","msg":"trace[2134740118] transaction","detail":"{read_only:false; response_revision:5212; number_of_response:1; }","duration":"107.777408ms","start":"2026-04-07T00:16:40.000956Z","end":"2026-04-07T00:16:40.108734Z","steps":["trace[2134740118] 'process raft request' (duration: 103.263168ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:40.109108Z","caller":"traceutil/trace.go:171","msg":"trace[1621349933] transaction","detail":"{read_only:false; response_revision:5213; number_of_response:1; }","duration":"108.060534ms","start":"2026-04-07T00:16:40.001012Z","end":"2026-04-07T00:16:40.109073Z","steps":["trace[1621349933] 'process raft request' (duration: 107.649534ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:40.109271Z","caller":"traceutil/trace.go:171","msg":"trace[390822195] transaction","detail":"{read_only:false; response_revision:5214; number_of_response:1; }","duration":"108.198197ms","start":"2026-04-07T00:16:40.001051Z","end":"2026-04-07T00:16:40.109249Z","steps":["trace[390822195] 'process raft request' (duration: 107.835368ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:40.109431Z","caller":"traceutil/trace.go:171","msg":"trace[770274214] transaction","detail":"{read_only:false; response_revision:5215; number_of_response:1; }","duration":"100.011488ms","start":"2026-04-07T00:16:40.00941Z","end":"2026-04-07T00:16:40.109421Z","steps":["trace[770274214] 'process raft request' (duration: 99.781983ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:16:43.067758Z","caller":"traceutil/trace.go:171","msg":"trace[1916529915] linearizableReadLoop","detail":"{readStateIndex:5464; appliedIndex:5463; }","duration":"122.448442ms","start":"2026-04-07T00:16:42.945288Z","end":"2026-04-07T00:16:43.067737Z","steps":["trace[1916529915] 'read index received' (duration: 122.210986ms)","trace[1916529915] 'applied index is now lower than readState.Index' (duration: 236.916µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:16:43.067863Z","caller":"traceutil/trace.go:171","msg":"trace[180625031] transaction","detail":"{read_only:false; response_revision:5264; number_of_response:1; }","duration":"195.902239ms","start":"2026-04-07T00:16:42.871902Z","end":"2026-04-07T00:16:43.067804Z","steps":["trace[180625031] 'process raft request' (duration: 195.657633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:16:43.06803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.725839ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-2llkq\" ","response":"range_response_count:1 size:7817"} {"level":"info","ts":"2026-04-07T00:16:43.068143Z","caller":"traceutil/trace.go:171","msg":"trace[1580237911] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-2llkq; range_end:; response_count:1; response_revision:5264; }","duration":"122.906073ms","start":"2026-04-07T00:16:42.945213Z","end":"2026-04-07T00:16:43.06812Z","steps":["trace[1580237911] 'agreement among raft nodes before linearized reading' (duration: 122.601816ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:18:00.09193Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3017} {"level":"info","ts":"2026-04-07T00:18:00.172187Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3017,"took":"77.581032ms","hash":984474028,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":31506432,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T00:18:00.172294Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":984474028,"revision":3017,"compact-revision":-1} {"level":"warn","ts":"2026-04-07T00:22:44.229183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.301062ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:22:44.229303Z","caller":"traceutil/trace.go:171","msg":"trace[308216956] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8214; }","duration":"129.486546ms","start":"2026-04-07T00:22:44.099792Z","end":"2026-04-07T00:22:44.229279Z","steps":["trace[308216956] 'range keys from in-memory index tree' (duration: 129.21733ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:00.099156Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6091} {"level":"info","ts":"2026-04-07T00:23:00.193902Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6091,"took":"91.083329ms","hash":1421186780,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":23228416,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-04-07T00:23:00.194574Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1421186780,"revision":6091,"compact-revision":3017} {"level":"warn","ts":"2026-04-07T00:23:37.658616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.493291ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:23:37.658846Z","caller":"traceutil/trace.go:171","msg":"trace[1813642924] linearizableReadLoop","detail":"{readStateIndex:8899; appliedIndex:8898; }","duration":"213.151936ms","start":"2026-04-07T00:23:37.445672Z","end":"2026-04-07T00:23:37.658823Z","steps":["trace[1813642924] 'read index received' (duration: 84.369628ms)","trace[1813642924] 'applied index is now lower than readState.Index' (duration: 128.779978ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:23:37.658935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"213.27455ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:23:37.658913Z","caller":"traceutil/trace.go:171","msg":"trace[1348994086] transaction","detail":"{read_only:false; response_revision:8590; number_of_response:1; }","duration":"286.488897ms","start":"2026-04-07T00:23:37.372378Z","end":"2026-04-07T00:23:37.658866Z","steps":["trace[1348994086] 'process raft request' (duration: 157.580206ms)","trace[1348994086] 'compare' (duration: 128.177834ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:23:37.659022Z","caller":"traceutil/trace.go:171","msg":"trace[1340507965] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:8590; }","duration":"213.375281ms","start":"2026-04-07T00:23:37.445581Z","end":"2026-04-07T00:23:37.658957Z","steps":["trace[1340507965] 'agreement among raft nodes before linearized reading' (duration: 213.30086ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:23:38.478343Z","caller":"traceutil/trace.go:171","msg":"trace[1430362251] transaction","detail":"{read_only:false; response_revision:8602; number_of_response:1; }","duration":"127.794305ms","start":"2026-04-07T00:23:38.350517Z","end":"2026-04-07T00:23:38.478311Z","steps":["trace[1430362251] 'process raft request' (duration: 127.598391ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:24:27.000699Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000024b3.wal"} {"level":"info","ts":"2026-04-07T00:25:36.455374Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"329f95029c2a91ed","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T00:25:36.46067Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-04-07T00:25:36.460802Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-04-07T00:28:00.105618Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8306} {"level":"info","ts":"2026-04-07T00:28:00.183381Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8306,"took":"75.532935ms","hash":3198947441,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":26296320,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-04-07T00:28:00.183472Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3198947441,"revision":8306,"compact-revision":6091} {"level":"info","ts":"2026-04-07T00:33:00.112479Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10952} {"level":"info","ts":"2026-04-07T00:33:00.193158Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10952,"took":"78.551995ms","hash":829144957,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":27832320,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-04-07T00:33:00.193221Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":829144957,"revision":10952,"compact-revision":8306} {"level":"info","ts":"2026-04-07T00:34:14.783893Z","caller":"traceutil/trace.go:171","msg":"trace[47492119] transaction","detail":"{read_only:false; response_revision:13510; number_of_response:1; }","duration":"119.398641ms","start":"2026-04-07T00:34:14.664477Z","end":"2026-04-07T00:34:14.783875Z","steps":["trace[47492119] 'process raft request' (duration: 115.60923ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:34:14.784398Z","caller":"traceutil/trace.go:171","msg":"trace[1583176658] transaction","detail":"{read_only:false; response_revision:13511; number_of_response:1; }","duration":"102.35912ms","start":"2026-04-07T00:34:14.681986Z","end":"2026-04-07T00:34:14.784345Z","steps":["trace[1583176658] 'process raft request' (duration: 101.858068ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:34:37.583855Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.030898ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:34:37.583967Z","caller":"traceutil/trace.go:171","msg":"trace[1673523029] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:13636; }","duration":"154.200501ms","start":"2026-04-07T00:34:37.429745Z","end":"2026-04-07T00:34:37.583945Z","steps":["trace[1673523029] 'count revisions from in-memory index tree' (duration: 153.934735ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:34:55.343945Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"532.915069ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" ","response":"range_response_count:86 size:929585"} {"level":"info","ts":"2026-04-07T00:34:55.344054Z","caller":"traceutil/trace.go:171","msg":"trace[308837599] range","detail":"{range_begin:/registry/secrets/openstack/; range_end:/registry/secrets/openstack0; response_count:86; response_revision:13743; }","duration":"533.062773ms","start":"2026-04-07T00:34:54.810956Z","end":"2026-04-07T00:34:55.344019Z","steps":["trace[308837599] 'range keys from in-memory index tree' (duration: 532.166601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:34:55.3441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:34:54.810935Z","time spent":"533.153615ms","remote":"127.0.0.1:55874","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":86,"response size":929607,"request content":"key:\"/registry/secrets/openstack/\" range_end:\"/registry/secrets/openstack0\" "} {"level":"warn","ts":"2026-04-07T00:34:55.344238Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.484368ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-04-07T00:34:55.344411Z","caller":"traceutil/trace.go:171","msg":"trace[1502558631] transaction","detail":"{read_only:false; response_revision:13744; number_of_response:1; }","duration":"408.064197ms","start":"2026-04-07T00:34:54.936326Z","end":"2026-04-07T00:34:55.34439Z","steps":["trace[1502558631] 'process raft request' (duration: 202.328032ms)","trace[1502558631] 'compare' (duration: 205.313855ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:34:55.344707Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:34:54.936298Z","time spent":"408.368635ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:34:55.354381Z","caller":"traceutil/trace.go:171","msg":"trace[461863694] linearizableReadLoop","detail":"{readStateIndex:14221; appliedIndex:14219; }","duration":"254.513872ms","start":"2026-04-07T00:34:55.099841Z","end":"2026-04-07T00:34:55.354355Z","steps":["trace[461863694] 'read index received' (duration: 38.820947ms)","trace[461863694] 'applied index is now lower than readState.Index' (duration: 215.691765ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:34:55.354511Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.647925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:34:55.354549Z","caller":"traceutil/trace.go:171","msg":"trace[710259625] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:13745; }","duration":"254.775789ms","start":"2026-04-07T00:34:55.099762Z","end":"2026-04-07T00:34:55.354537Z","steps":["trace[710259625] 'agreement among raft nodes before linearized reading' (duration: 254.677616ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:34:55.35474Z","caller":"traceutil/trace.go:171","msg":"trace[786646080] transaction","detail":"{read_only:false; response_revision:13745; number_of_response:1; }","duration":"416.121032ms","start":"2026-04-07T00:34:54.938583Z","end":"2026-04-07T00:34:55.354704Z","steps":["trace[786646080] 'process raft request' (duration: 415.674601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:34:55.355174Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:34:54.938568Z","time spent":"416.226784ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:36:28.816328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.718011ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-04-07T00:36:28.816414Z","caller":"traceutil/trace.go:171","msg":"trace[359547107] linearizableReadLoop","detail":"{readStateIndex:15419; appliedIndex:15418; }","duration":"250.255678ms","start":"2026-04-07T00:36:28.566145Z","end":"2026-04-07T00:36:28.816401Z","steps":["trace[359547107] 'read index received' (duration: 56.397784ms)","trace[359547107] 'applied index is now lower than readState.Index' (duration: 193.856824ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:36:28.816506Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"250.35028ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagerconfigs/\" range_end:\"/registry/monitoring.coreos.com/alertmanagerconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-04-07T00:36:28.816524Z","caller":"traceutil/trace.go:171","msg":"trace[1162036813] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagerconfigs/; range_end:/registry/monitoring.coreos.com/alertmanagerconfigs0; response_count:0; response_revision:14907; }","duration":"250.417192ms","start":"2026-04-07T00:36:28.566098Z","end":"2026-04-07T00:36:28.816516Z","steps":["trace[1162036813] 'agreement among raft nodes before linearized reading' (duration: 250.366651ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:36:28.816719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.650749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/keepalived-q55vz\" ","response":"range_response_count:1 size:6520"} {"level":"info","ts":"2026-04-07T00:36:28.816735Z","caller":"traceutil/trace.go:171","msg":"trace[1826507522] range","detail":"{range_begin:/registry/pods/openstack/keepalived-q55vz; range_end:; response_count:1; response_revision:14907; }","duration":"248.68848ms","start":"2026-04-07T00:36:28.56804Z","end":"2026-04-07T00:36:28.816728Z","steps":["trace[1826507522] 'agreement among raft nodes before linearized reading' (duration: 248.608618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:36:28.817151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"165.723615ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"info","ts":"2026-04-07T00:36:28.817275Z","caller":"traceutil/trace.go:171","msg":"trace[1667132407] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:14907; }","duration":"165.91555ms","start":"2026-04-07T00:36:28.651331Z","end":"2026-04-07T00:36:28.817247Z","steps":["trace[1667132407] 'agreement among raft nodes before linearized reading' (duration: 165.527341ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:36:28.817617Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.935686ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-04-07T00:36:28.817702Z","caller":"traceutil/trace.go:171","msg":"trace[1604597308] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:14907; }","duration":"124.073418ms","start":"2026-04-07T00:36:28.69361Z","end":"2026-04-07T00:36:28.817683Z","steps":["trace[1604597308] 'agreement among raft nodes before linearized reading' (duration: 123.872244ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:38:00.11859Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12989} {"level":"info","ts":"2026-04-07T00:38:00.192985Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12989,"took":"72.184745ms","hash":921470287,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":31158272,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T00:38:00.193041Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":921470287,"revision":12989,"compact-revision":10952} {"level":"info","ts":"2026-04-07T00:42:18.289779Z","caller":"traceutil/trace.go:171","msg":"trace[546131675] transaction","detail":"{read_only:false; response_revision:17551; number_of_response:1; }","duration":"109.783181ms","start":"2026-04-07T00:42:18.179954Z","end":"2026-04-07T00:42:18.289737Z","steps":["trace[546131675] 'process raft request' (duration: 109.607957ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:42:18.705806Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.828861ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:42:18.705942Z","caller":"traceutil/trace.go:171","msg":"trace[1168582031] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:17551; }","duration":"346.040916ms","start":"2026-04-07T00:42:18.359867Z","end":"2026-04-07T00:42:18.705908Z","steps":["trace[1168582031] 'count revisions from in-memory index tree' (duration: 345.719708ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:42:18.705993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:42:18.35983Z","time spent":"346.149758ms","remote":"127.0.0.1:56226","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":30,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:42:18.759102Z","caller":"traceutil/trace.go:171","msg":"trace[41273570] transaction","detail":"{read_only:false; response_revision:17552; number_of_response:1; }","duration":"321.0514ms","start":"2026-04-07T00:42:18.438024Z","end":"2026-04-07T00:42:18.759075Z","steps":["trace[41273570] 'process raft request' (duration: 320.861285ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:42:18.759338Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:42:18.437999Z","time spent":"321.257524ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:42:18.801611Z","caller":"traceutil/trace.go:171","msg":"trace[761486570] linearizableReadLoop","detail":"{readStateIndex:18153; appliedIndex:18151; }","duration":"188.820166ms","start":"2026-04-07T00:42:18.612758Z","end":"2026-04-07T00:42:18.801578Z","steps":["trace[761486570] 'read index received' (duration: 146.154572ms)","trace[761486570] 'applied index is now lower than readState.Index' (duration: 42.660724ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:42:18.801666Z","caller":"traceutil/trace.go:171","msg":"trace[1767671143] transaction","detail":"{read_only:false; response_revision:17553; number_of_response:1; }","duration":"165.744297ms","start":"2026-04-07T00:42:18.635844Z","end":"2026-04-07T00:42:18.801589Z","steps":["trace[1767671143] 'process raft request' (duration: 165.586473ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:42:18.80192Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"107.101775ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/libvirt-libvirt-default-hb9fd\" ","response":"range_response_count:1 size:20862"} {"level":"info","ts":"2026-04-07T00:42:18.802Z","caller":"traceutil/trace.go:171","msg":"trace[427313199] range","detail":"{range_begin:/registry/pods/openstack/libvirt-libvirt-default-hb9fd; range_end:; response_count:1; response_revision:17553; }","duration":"107.28428ms","start":"2026-04-07T00:42:18.694695Z","end":"2026-04-07T00:42:18.80198Z","steps":["trace[427313199] 'agreement among raft nodes before linearized reading' (duration: 107.070445ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:42:18.802068Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.258526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-v7q6s\" ","response":"range_response_count:1 size:9799"} {"level":"info","ts":"2026-04-07T00:42:18.802216Z","caller":"traceutil/trace.go:171","msg":"trace[1385003141] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-v7q6s; range_end:; response_count:1; response_revision:17553; }","duration":"189.500512ms","start":"2026-04-07T00:42:18.612683Z","end":"2026-04-07T00:42:18.802183Z","steps":["trace[1385003141] 'agreement among raft nodes before linearized reading' (duration: 189.040581ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:43:00.129764Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15504} {"level":"info","ts":"2026-04-07T00:43:00.225788Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15504,"took":"89.952219ms","hash":2651811308,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":33542144,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-04-07T00:43:00.225883Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2651811308,"revision":15504,"compact-revision":12989} {"level":"info","ts":"2026-04-07T00:45:43.339877Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"329f95029c2a91ed","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T00:45:43.346634Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-04-07T00:45:43.34675Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-04-07T00:47:03.650031Z","caller":"traceutil/trace.go:171","msg":"trace[1079717203] transaction","detail":"{read_only:false; response_revision:19812; number_of_response:1; }","duration":"102.084152ms","start":"2026-04-07T00:47:03.547895Z","end":"2026-04-07T00:47:03.649979Z","steps":["trace[1079717203] 'process raft request' (duration: 100.440012ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:47:03.650036Z","caller":"traceutil/trace.go:171","msg":"trace[823579144] transaction","detail":"{read_only:false; response_revision:19813; number_of_response:1; }","duration":"101.328514ms","start":"2026-04-07T00:47:03.548672Z","end":"2026-04-07T00:47:03.65Z","steps":["trace[823579144] 'process raft request' (duration: 101.232141ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:47:25.865704Z","caller":"traceutil/trace.go:171","msg":"trace[647662882] linearizableReadLoop","detail":"{readStateIndex:20603; appliedIndex:20602; }","duration":"106.061738ms","start":"2026-04-07T00:47:25.759615Z","end":"2026-04-07T00:47:25.865677Z","steps":["trace[647662882] 'read index received' (duration: 105.867633ms)","trace[647662882] 'applied index is now lower than readState.Index' (duration: 192.655µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:47:25.865801Z","caller":"traceutil/trace.go:171","msg":"trace[395904085] transaction","detail":"{read_only:false; response_revision:19924; number_of_response:1; }","duration":"205.902777ms","start":"2026-04-07T00:47:25.659875Z","end":"2026-04-07T00:47:25.865778Z","steps":["trace[395904085] 'process raft request' (duration: 205.63592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:47:25.866161Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.53733ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"warn","ts":"2026-04-07T00:47:25.866207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.440971ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:47:25.866215Z","caller":"traceutil/trace.go:171","msg":"trace[639079907] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:19924; }","duration":"106.666623ms","start":"2026-04-07T00:47:25.759534Z","end":"2026-04-07T00:47:25.866201Z","steps":["trace[639079907] 'agreement among raft nodes before linearized reading' (duration: 106.292314ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:47:25.866347Z","caller":"traceutil/trace.go:171","msg":"trace[378098440] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:19924; }","duration":"102.628786ms","start":"2026-04-07T00:47:25.763692Z","end":"2026-04-07T00:47:25.866321Z","steps":["trace[378098440] 'agreement among raft nodes before linearized reading' (duration: 102.312129ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:47:25.866299Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.237435ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-07T00:47:25.866486Z","caller":"traceutil/trace.go:171","msg":"trace[1942743141] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:19924; }","duration":"104.490161ms","start":"2026-04-07T00:47:25.761948Z","end":"2026-04-07T00:47:25.866438Z","steps":["trace[1942743141] 'agreement among raft nodes before linearized reading' (duration: 104.009369ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:47:26.137049Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.636077ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:47:26.13734Z","caller":"traceutil/trace.go:171","msg":"trace[446557167] transaction","detail":"{read_only:false; response_revision:19925; number_of_response:1; }","duration":"265.335726ms","start":"2026-04-07T00:47:25.871965Z","end":"2026-04-07T00:47:26.1373Z","steps":["trace[446557167] 'process raft request' (duration: 145.246938ms)","trace[446557167] 'compare' (duration: 119.132945ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:47:26.137413Z","caller":"traceutil/trace.go:171","msg":"trace[1600912293] transaction","detail":"{read_only:false; response_revision:19926; number_of_response:1; }","duration":"265.387267ms","start":"2026-04-07T00:47:25.872003Z","end":"2026-04-07T00:47:26.13739Z","steps":["trace[1600912293] 'process raft request' (duration: 265.213983ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:47:26.137865Z","caller":"traceutil/trace.go:171","msg":"trace[1578257331] transaction","detail":"{read_only:false; response_revision:19927; number_of_response:1; }","duration":"265.065ms","start":"2026-04-07T00:47:25.872779Z","end":"2026-04-07T00:47:26.137844Z","steps":["trace[1578257331] 'process raft request' (duration: 264.562888ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:47:26.368349Z","caller":"traceutil/trace.go:171","msg":"trace[218449041] transaction","detail":"{read_only:false; response_revision:19929; number_of_response:1; }","duration":"171.139194ms","start":"2026-04-07T00:47:26.197172Z","end":"2026-04-07T00:47:26.368311Z","steps":["trace[218449041] 'process raft request' (duration: 170.507039ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:48:00.138039Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":18078} {"level":"info","ts":"2026-04-07T00:48:00.250169Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":18078,"took":"107.386591ms","hash":3978094924,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":30113792,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-07T00:48:00.250233Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3978094924,"revision":18078,"compact-revision":15504} {"level":"warn","ts":"2026-04-07T00:51:05.219031Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"177.596871ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:51:05.219231Z","caller":"traceutil/trace.go:171","msg":"trace[1939329542] transaction","detail":"{read_only:false; response_revision:22251; number_of_response:1; }","duration":"322.156343ms","start":"2026-04-07T00:51:04.897034Z","end":"2026-04-07T00:51:05.21919Z","steps":["trace[1939329542] 'process raft request' (duration: 144.226174ms)","trace[1939329542] 'compare' (duration: 177.354825ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:51:05.219391Z","caller":"traceutil/trace.go:171","msg":"trace[952265506] transaction","detail":"{read_only:false; response_revision:22252; number_of_response:1; }","duration":"144.403627ms","start":"2026-04-07T00:51:05.074958Z","end":"2026-04-07T00:51:05.219362Z","steps":["trace[952265506] 'process raft request' (duration: 144.271934ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:51:05.219395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:51:04.896995Z","time spent":"322.316357ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:51:05.220184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.324728ms","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-04-07T00:51:05.220258Z","caller":"traceutil/trace.go:171","msg":"trace[2034059113] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:22252; }","duration":"180.445871ms","start":"2026-04-07T00:51:05.03979Z","end":"2026-04-07T00:51:05.220236Z","steps":["trace[2034059113] 'agreement among raft nodes before linearized reading' (duration: 180.122753ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:51:05.219608Z","caller":"traceutil/trace.go:171","msg":"trace[698107380] linearizableReadLoop","detail":"{readStateIndex:23003; appliedIndex:23002; }","duration":"179.742472ms","start":"2026-04-07T00:51:05.039852Z","end":"2026-04-07T00:51:05.219595Z","steps":["trace[698107380] 'read index received' (duration: 1.221319ms)","trace[698107380] 'applied index is now lower than readState.Index' (duration: 178.519423ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:51:05.423154Z","caller":"traceutil/trace.go:171","msg":"trace[447973628] transaction","detail":"{read_only:false; response_revision:22254; number_of_response:1; }","duration":"319.017667ms","start":"2026-04-07T00:51:05.1041Z","end":"2026-04-07T00:51:05.423117Z","steps":["trace[447973628] 'process raft request' (duration: 318.784891ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:51:05.423169Z","caller":"traceutil/trace.go:171","msg":"trace[1002105843] transaction","detail":"{read_only:false; response_revision:22253; number_of_response:1; }","duration":"333.804765ms","start":"2026-04-07T00:51:05.089304Z","end":"2026-04-07T00:51:05.423109Z","steps":["trace[1002105843] 'process raft request' (duration: 315.85255ms)","trace[1002105843] 'compare' (duration: 17.598917ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:51:05.423193Z","caller":"traceutil/trace.go:171","msg":"trace[1929693094] linearizableReadLoop","detail":"{readStateIndex:23006; appliedIndex:23004; }","duration":"202.71506ms","start":"2026-04-07T00:51:05.220418Z","end":"2026-04-07T00:51:05.423133Z","steps":["trace[1929693094] 'read index received' (duration: 184.753675ms)","trace[1929693094] 'applied index is now lower than readState.Index' (duration: 17.958175ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:51:05.423336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:51:05.10408Z","time spent":"319.151279ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":554,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:51:05.423368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:51:05.089262Z","time spent":"333.99346ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":519,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:51:05.423026Z","caller":"traceutil/trace.go:171","msg":"trace[1950712110] transaction","detail":"{read_only:false; response_revision:22255; number_of_response:1; }","duration":"198.065368ms","start":"2026-04-07T00:51:05.224937Z","end":"2026-04-07T00:51:05.423002Z","steps":["trace[1950712110] 'process raft request' (duration: 198.027997ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:51:05.423769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"321.845186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:51:05.423812Z","caller":"traceutil/trace.go:171","msg":"trace[1336350178] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:22256; }","duration":"321.961308ms","start":"2026-04-07T00:51:05.101841Z","end":"2026-04-07T00:51:05.423803Z","steps":["trace[1336350178] 'agreement among raft nodes before linearized reading' (duration: 321.483587ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:51:05.423834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:51:05.10181Z","time spent":"322.019179ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-04-07T00:51:05.424006Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.456152ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/acme.cert-manager.io/challenges/\" range_end:\"/registry/acme.cert-manager.io/challenges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:51:05.424057Z","caller":"traceutil/trace.go:171","msg":"trace[378677588] range","detail":"{range_begin:/registry/acme.cert-manager.io/challenges/; range_end:/registry/acme.cert-manager.io/challenges0; response_count:0; response_revision:22257; }","duration":"178.557615ms","start":"2026-04-07T00:51:05.245486Z","end":"2026-04-07T00:51:05.424044Z","steps":["trace[378677588] 'agreement among raft nodes before linearized reading' (duration: 178.420961ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:53:00.156048Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20330} {"level":"info","ts":"2026-04-07T00:53:00.265002Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20330,"took":"105.451065ms","hash":2666689218,"current-db-size-bytes":57839616,"current-db-size":"58 MB","current-db-size-in-use-bytes":41500672,"current-db-size-in-use":"42 MB"} {"level":"info","ts":"2026-04-07T00:53:00.265078Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2666689218,"revision":20330,"compact-revision":18078} {"level":"info","ts":"2026-04-07T00:54:29.50924Z","caller":"traceutil/trace.go:171","msg":"trace[1763207614] linearizableReadLoop","detail":"{readStateIndex:25124; appliedIndex:25123; }","duration":"187.86787ms","start":"2026-04-07T00:54:29.32134Z","end":"2026-04-07T00:54:29.509208Z","steps":["trace[1763207614] 'read index received' (duration: 187.640655ms)","trace[1763207614] 'applied index is now lower than readState.Index' (duration: 225.865µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:29.509342Z","caller":"traceutil/trace.go:171","msg":"trace[1646590061] transaction","detail":"{read_only:false; response_revision:24318; number_of_response:1; }","duration":"201.287215ms","start":"2026-04-07T00:54:29.308021Z","end":"2026-04-07T00:54:29.509308Z","steps":["trace[1646590061] 'process raft request' (duration: 200.964858ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:29.509431Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"188.057255ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/exchanges/\" range_end:\"/registry/rabbitmq.com/exchanges0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:29.509523Z","caller":"traceutil/trace.go:171","msg":"trace[140327390] range","detail":"{range_begin:/registry/rabbitmq.com/exchanges/; range_end:/registry/rabbitmq.com/exchanges0; response_count:0; response_revision:24318; }","duration":"188.211879ms","start":"2026-04-07T00:54:29.321285Z","end":"2026-04-07T00:54:29.509497Z","steps":["trace[140327390] 'agreement among raft nodes before linearized reading' (duration: 188.038095ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:29.863496Z","caller":"traceutil/trace.go:171","msg":"trace[391302577] linearizableReadLoop","detail":"{readStateIndex:25125; appliedIndex:25124; }","duration":"309.456594ms","start":"2026-04-07T00:54:29.553999Z","end":"2026-04-07T00:54:29.863456Z","steps":["trace[391302577] 'read index received' (duration: 309.247909ms)","trace[391302577] 'applied index is now lower than readState.Index' (duration: 206.805µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:29.863601Z","caller":"traceutil/trace.go:171","msg":"trace[855919389] transaction","detail":"{read_only:false; response_revision:24319; number_of_response:1; }","duration":"478.307365ms","start":"2026-04-07T00:54:29.385248Z","end":"2026-04-07T00:54:29.863555Z","steps":["trace[855919389] 'process raft request' (duration: 477.966636ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:29.86375Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:29.385217Z","time spent":"478.445949ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":475,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:29.863735Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.704281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:29.863832Z","caller":"traceutil/trace.go:171","msg":"trace[859845679] range","detail":"{range_begin:/registry/rabbitmq.com/policies/; range_end:/registry/rabbitmq.com/policies0; response_count:0; response_revision:24319; }","duration":"309.867035ms","start":"2026-04-07T00:54:29.553944Z","end":"2026-04-07T00:54:29.863811Z","steps":["trace[859845679] 'agreement among raft nodes before linearized reading' (duration: 309.65318ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:29.863883Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:29.55392Z","time spent":"309.953787ms","remote":"127.0.0.1:42068","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":0,"response size":28,"request content":"key:\"/registry/rabbitmq.com/policies/\" range_end:\"/registry/rabbitmq.com/policies0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:54:29.92264Z","caller":"traceutil/trace.go:171","msg":"trace[1680453647] transaction","detail":"{read_only:false; response_revision:24320; number_of_response:1; }","duration":"345.580239ms","start":"2026-04-07T00:54:29.577032Z","end":"2026-04-07T00:54:29.922612Z","steps":["trace[1680453647] 'process raft request' (duration: 345.408075ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:29.922829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:29.57701Z","time spent":"345.700693ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:30.309269Z","caller":"traceutil/trace.go:171","msg":"trace[1295125927] linearizableReadLoop","detail":"{readStateIndex:25128; appliedIndex:25126; }","duration":"193.916387ms","start":"2026-04-07T00:54:30.115331Z","end":"2026-04-07T00:54:30.309247Z","steps":["trace[1295125927] 'read index received' (duration: 70.061086ms)","trace[1295125927] 'applied index is now lower than readState.Index' (duration: 123.853981ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:30.309418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.05743ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:30.309461Z","caller":"traceutil/trace.go:171","msg":"trace[1829651531] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclustertemplates0; response_count:0; response_revision:24322; }","duration":"194.137263ms","start":"2026-04-07T00:54:30.115312Z","end":"2026-04-07T00:54:30.309449Z","steps":["trace[1829651531] 'agreement among raft nodes before linearized reading' (duration: 194.03244ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:30.309525Z","caller":"traceutil/trace.go:171","msg":"trace[821907857] transaction","detail":"{read_only:false; response_revision:24322; number_of_response:1; }","duration":"213.792978ms","start":"2026-04-07T00:54:30.095681Z","end":"2026-04-07T00:54:30.309474Z","steps":["trace[821907857] 'process raft request' (duration: 199.859031ms)","trace[821907857] 'compare' (duration: 13.281772ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:33.623024Z","caller":"traceutil/trace.go:171","msg":"trace[2071052598] transaction","detail":"{read_only:false; response_revision:24349; number_of_response:1; }","duration":"228.795701ms","start":"2026-04-07T00:54:33.394184Z","end":"2026-04-07T00:54:33.62298Z","steps":["trace[2071052598] 'process raft request' (duration: 155.946067ms)","trace[2071052598] 'compare' (duration: 72.631879ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:33.889724Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.092152ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:33.889844Z","caller":"traceutil/trace.go:171","msg":"trace[1350069128] linearizableReadLoop","detail":"{readStateIndex:25157; appliedIndex:25156; }","duration":"264.454754ms","start":"2026-04-07T00:54:33.625372Z","end":"2026-04-07T00:54:33.889827Z","steps":["trace[1350069128] 'read index received' (duration: 136.053165ms)","trace[1350069128] 'applied index is now lower than readState.Index' (duration: 128.400319ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:33.889942Z","caller":"traceutil/trace.go:171","msg":"trace[2043453869] transaction","detail":"{read_only:false; response_revision:24350; number_of_response:1; }","duration":"367.584113ms","start":"2026-04-07T00:54:33.522315Z","end":"2026-04-07T00:54:33.889899Z","steps":["trace[2043453869] 'process raft request' (duration: 239.208644ms)","trace[2043453869] 'compare' (duration: 127.744864ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:33.89003Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.66826ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-07T00:54:33.890062Z","caller":"traceutil/trace.go:171","msg":"trace[1567413281] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24350; }","duration":"264.743471ms","start":"2026-04-07T00:54:33.625308Z","end":"2026-04-07T00:54:33.890052Z","steps":["trace[1567413281] 'agreement among raft nodes before linearized reading' (duration: 264.609658ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:33.890294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.095866ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:33.890361Z","caller":"traceutil/trace.go:171","msg":"trace[814253330] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanetemplates0; response_count:0; response_revision:24350; }","duration":"155.212188ms","start":"2026-04-07T00:54:33.735129Z","end":"2026-04-07T00:54:33.890342Z","steps":["trace[814253330] 'agreement among raft nodes before linearized reading' (duration: 155.106156ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:33.890199Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:33.522291Z","time spent":"367.740406ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:34.622651Z","caller":"traceutil/trace.go:171","msg":"trace[1979099812] transaction","detail":"{read_only:false; response_revision:24353; number_of_response:1; }","duration":"298.680293ms","start":"2026-04-07T00:54:34.323941Z","end":"2026-04-07T00:54:34.622622Z","steps":["trace[1979099812] 'process raft request' (duration: 262.794524ms)","trace[1979099812] 'compare' (duration: 35.666514ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:34.623069Z","caller":"traceutil/trace.go:171","msg":"trace[955615841] transaction","detail":"{read_only:false; response_revision:24354; number_of_response:1; }","duration":"295.729181ms","start":"2026-04-07T00:54:34.32729Z","end":"2026-04-07T00:54:34.623019Z","steps":["trace[955615841] 'process raft request' (duration: 295.281601ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:34.623248Z","caller":"traceutil/trace.go:171","msg":"trace[599571828] linearizableReadLoop","detail":"{readStateIndex:25163; appliedIndex:25159; }","duration":"135.435751ms","start":"2026-04-07T00:54:34.487774Z","end":"2026-04-07T00:54:34.623209Z","steps":["trace[599571828] 'read index received' (duration: 98.862055ms)","trace[599571828] 'applied index is now lower than readState.Index' (duration: 36.571956ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:34.623278Z","caller":"traceutil/trace.go:171","msg":"trace[249305849] transaction","detail":"{read_only:false; response_revision:24355; number_of_response:1; }","duration":"294.999586ms","start":"2026-04-07T00:54:34.32825Z","end":"2026-04-07T00:54:34.62325Z","steps":["trace[249305849] 'process raft request' (duration: 294.713428ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:34.623443Z","caller":"traceutil/trace.go:171","msg":"trace[1055031641] transaction","detail":"{read_only:false; response_revision:24356; number_of_response:1; }","duration":"257.482856ms","start":"2026-04-07T00:54:34.365945Z","end":"2026-04-07T00:54:34.623428Z","steps":["trace[1055031641] 'process raft request' (duration: 257.196978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:34.623613Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.82314ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/\" range_end:\"/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:34.623662Z","caller":"traceutil/trace.go:171","msg":"trace[1513536888] range","detail":"{range_begin:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes/; range_end:/registry/controlplane.cluster.x-k8s.io/kubeadmcontrolplanes0; response_count:0; response_revision:24356; }","duration":"135.938392ms","start":"2026-04-07T00:54:34.48771Z","end":"2026-04-07T00:54:34.623648Z","steps":["trace[1513536888] 'agreement among raft nodes before linearized reading' (duration: 135.83883ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:34.623704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"111.89674ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:34.623757Z","caller":"traceutil/trace.go:171","msg":"trace[1177345921] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackclusters/magnum-system0; response_count:0; response_revision:24356; }","duration":"111.981732ms","start":"2026-04-07T00:54:34.511759Z","end":"2026-04-07T00:54:34.623741Z","steps":["trace[1177345921] 'agreement among raft nodes before linearized reading' (duration: 111.85944ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:35.018442Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"180.443881ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:35.018698Z","caller":"traceutil/trace.go:171","msg":"trace[1606732315] linearizableReadLoop","detail":"{readStateIndex:25167; appliedIndex:25163; }","duration":"387.640658ms","start":"2026-04-07T00:54:34.631037Z","end":"2026-04-07T00:54:35.018677Z","steps":["trace[1606732315] 'read index received' (duration: 39.318162ms)","trace[1606732315] 'applied index is now lower than readState.Index' (duration: 348.321416ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:35.018713Z","caller":"traceutil/trace.go:171","msg":"trace[908906674] transaction","detail":"{read_only:false; response_revision:24358; number_of_response:1; }","duration":"390.176529ms","start":"2026-04-07T00:54:34.628498Z","end":"2026-04-07T00:54:35.018674Z","steps":["trace[908906674] 'process raft request' (duration: 209.416172ms)","trace[908906674] 'compare' (duration: 180.337877ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:35.018779Z","caller":"traceutil/trace.go:171","msg":"trace[1689582897] transaction","detail":"{read_only:false; response_revision:24360; number_of_response:1; }","duration":"389.006962ms","start":"2026-04-07T00:54:34.62976Z","end":"2026-04-07T00:54:35.018767Z","steps":["trace[1689582897] 'process raft request' (duration: 388.868198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:35.018829Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:34.629753Z","time spent":"389.046622ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:35.018778Z","caller":"traceutil/trace.go:171","msg":"trace[2146894691] transaction","detail":"{read_only:false; response_revision:24359; number_of_response:1; }","duration":"389.053593ms","start":"2026-04-07T00:54:34.62967Z","end":"2026-04-07T00:54:35.018723Z","steps":["trace[2146894691] 'process raft request' (duration: 388.878558ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:35.018879Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"387.828933ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:54:35.018938Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:34.628491Z","time spent":"390.286803ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:35.018973Z","caller":"traceutil/trace.go:171","msg":"trace[775364912] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:24360; }","duration":"387.959456ms","start":"2026-04-07T00:54:34.630995Z","end":"2026-04-07T00:54:35.018954Z","steps":["trace[775364912] 'agreement among raft nodes before linearized reading' (duration: 387.833273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:35.018976Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:34.629645Z","time spent":"389.241956ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:35.019028Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:34.630984Z","time spent":"388.014207ms","remote":"127.0.0.1:55968","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":0,"response size":28,"request content":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" "} {"level":"info","ts":"2026-04-07T00:54:35.214924Z","caller":"traceutil/trace.go:171","msg":"trace[1292025930] linearizableReadLoop","detail":"{readStateIndex:25168; appliedIndex:25167; }","duration":"196.164972ms","start":"2026-04-07T00:54:35.018731Z","end":"2026-04-07T00:54:35.214896Z","steps":["trace[1292025930] 'read index received' (duration: 196.030878ms)","trace[1292025930] 'applied index is now lower than readState.Index' (duration: 132.454µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:35.215014Z","caller":"traceutil/trace.go:171","msg":"trace[1687436049] transaction","detail":"{read_only:false; response_revision:24361; number_of_response:1; }","duration":"286.351905ms","start":"2026-04-07T00:54:34.92862Z","end":"2026-04-07T00:54:35.214972Z","steps":["trace[1687436049] 'process raft request' (duration: 285.622077ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:35.215165Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.958815ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:54:35.215231Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.251212ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-04-07T00:54:35.215242Z","caller":"traceutil/trace.go:171","msg":"trace[355006802] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24361; }","duration":"191.085308ms","start":"2026-04-07T00:54:35.024135Z","end":"2026-04-07T00:54:35.215221Z","steps":["trace[355006802] 'agreement among raft nodes before linearized reading' (duration: 190.934195ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:35.215269Z","caller":"traceutil/trace.go:171","msg":"trace[1626453090] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:24361; }","duration":"276.363544ms","start":"2026-04-07T00:54:34.938893Z","end":"2026-04-07T00:54:35.215257Z","steps":["trace[1626453090] 'agreement among raft nodes before linearized reading' (duration: 276.132309ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:35.215323Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.674335ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"warn","ts":"2026-04-07T00:54:35.215331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"114.707268ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:35.215453Z","caller":"traceutil/trace.go:171","msg":"trace[1990484969] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24361; }","duration":"114.844072ms","start":"2026-04-07T00:54:35.100578Z","end":"2026-04-07T00:54:35.215422Z","steps":["trace[1990484969] 'agreement among raft nodes before linearized reading' (duration: 114.626897ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:35.215453Z","caller":"traceutil/trace.go:171","msg":"trace[1901381644] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24361; }","duration":"265.907691ms","start":"2026-04-07T00:54:34.949502Z","end":"2026-04-07T00:54:35.21541Z","steps":["trace[1901381644] 'agreement among raft nodes before linearized reading' (duration: 265.501211ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:35.215593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.417664ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-07T00:54:35.21563Z","caller":"traceutil/trace.go:171","msg":"trace[2111980771] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24361; }","duration":"169.488405ms","start":"2026-04-07T00:54:35.046131Z","end":"2026-04-07T00:54:35.215619Z","steps":["trace[2111980771] 'agreement among raft nodes before linearized reading' (duration: 169.325571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:35.215679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"345.58376ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:35.215773Z","caller":"traceutil/trace.go:171","msg":"trace[2050384214] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyextensionpolicies/; range_end:/registry/gateway.envoyproxy.io/envoyextensionpolicies0; response_count:0; response_revision:24361; }","duration":"345.761524ms","start":"2026-04-07T00:54:34.869987Z","end":"2026-04-07T00:54:35.215748Z","steps":["trace[2050384214] 'agreement among raft nodes before linearized reading' (duration: 345.60371ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:35.215816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:34.869965Z","time spent":"345.840056ms","remote":"127.0.0.1:53170","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyextensionpolicies0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:54:35.376342Z","caller":"traceutil/trace.go:171","msg":"trace[1264218290] transaction","detail":"{read_only:false; response_revision:24362; number_of_response:1; }","duration":"155.880345ms","start":"2026-04-07T00:54:35.220432Z","end":"2026-04-07T00:54:35.376312Z","steps":["trace[1264218290] 'process raft request' (duration: 129.308322ms)","trace[1264218290] 'compare' (duration: 26.353838ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:35.376752Z","caller":"traceutil/trace.go:171","msg":"trace[1432557690] transaction","detail":"{read_only:false; response_revision:24363; number_of_response:1; }","duration":"156.060289ms","start":"2026-04-07T00:54:35.220664Z","end":"2026-04-07T00:54:35.376724Z","steps":["trace[1432557690] 'process raft request' (duration: 155.591348ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:35.376767Z","caller":"traceutil/trace.go:171","msg":"trace[669742413] linearizableReadLoop","detail":"{readStateIndex:25171; appliedIndex:25168; }","duration":"154.118441ms","start":"2026-04-07T00:54:35.222614Z","end":"2026-04-07T00:54:35.376733Z","steps":["trace[669742413] 'read index received' (duration: 127.106338ms)","trace[669742413] 'applied index is now lower than readState.Index' (duration: 27.010463ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:35.376892Z","caller":"traceutil/trace.go:171","msg":"trace[1715991407] transaction","detail":"{read_only:false; response_revision:24364; number_of_response:1; }","duration":"155.930918ms","start":"2026-04-07T00:54:35.220905Z","end":"2026-04-07T00:54:35.376836Z","steps":["trace[1715991407] 'process raft request' (duration: 155.6542ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:35.376982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.351348ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:35.377032Z","caller":"traceutil/trace.go:171","msg":"trace[1718017969] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:24364; }","duration":"154.444311ms","start":"2026-04-07T00:54:35.222569Z","end":"2026-04-07T00:54:35.377013Z","steps":["trace[1718017969] 'agreement among raft nodes before linearized reading' (duration: 154.288027ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:36.017943Z","caller":"traceutil/trace.go:171","msg":"trace[1928169251] transaction","detail":"{read_only:false; response_revision:24365; number_of_response:1; }","duration":"119.065464ms","start":"2026-04-07T00:54:35.898839Z","end":"2026-04-07T00:54:36.017904Z","steps":["trace[1928169251] 'process raft request' (duration: 118.712195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:36.273483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.375854ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0\" ","response":"range_response_count:1 size:1566"} {"level":"info","ts":"2026-04-07T00:54:36.273683Z","caller":"traceutil/trace.go:171","msg":"trace[373024918] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/data-ovn-ovsdb-nb-0; range_end:; response_count:1; response_revision:24365; }","duration":"129.647361ms","start":"2026-04-07T00:54:36.14401Z","end":"2026-04-07T00:54:36.273657Z","steps":["trace[373024918] 'range keys from in-memory index tree' (duration: 129.155469ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:36.544126Z","caller":"traceutil/trace.go:171","msg":"trace[357835996] linearizableReadLoop","detail":"{readStateIndex:25173; appliedIndex:25172; }","duration":"266.166677ms","start":"2026-04-07T00:54:36.277923Z","end":"2026-04-07T00:54:36.544089Z","steps":["trace[357835996] 'read index received' (duration: 265.87136ms)","trace[357835996] 'applied index is now lower than readState.Index' (duration: 292.467µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:36.544149Z","caller":"traceutil/trace.go:171","msg":"trace[807028917] transaction","detail":"{read_only:false; response_revision:24366; number_of_response:1; }","duration":"328.603709ms","start":"2026-04-07T00:54:36.215519Z","end":"2026-04-07T00:54:36.544123Z","steps":["trace[807028917] 'process raft request' (duration: 328.23719ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:36.54435Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:36.215482Z","time spent":"328.769372ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:36.544518Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.555706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumes/pvc-dcdba8b5-0af7-4aa9-9e59-e550fc75a4bf\" ","response":"range_response_count:1 size:1284"} {"level":"info","ts":"2026-04-07T00:54:36.544633Z","caller":"traceutil/trace.go:171","msg":"trace[1715334095] range","detail":"{range_begin:/registry/persistentvolumes/pvc-dcdba8b5-0af7-4aa9-9e59-e550fc75a4bf; range_end:; response_count:1; response_revision:24366; }","duration":"266.713449ms","start":"2026-04-07T00:54:36.277892Z","end":"2026-04-07T00:54:36.544605Z","steps":["trace[1715334095] 'agreement among raft nodes before linearized reading' (duration: 266.31273ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:36.569645Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.049154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies/\" range_end:\"/registry/gateway.envoyproxy.io/backendtrafficpolicies0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:36.569725Z","caller":"traceutil/trace.go:171","msg":"trace[1879276861] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/backendtrafficpolicies/; range_end:/registry/gateway.envoyproxy.io/backendtrafficpolicies0; response_count:0; response_revision:24367; }","duration":"195.172836ms","start":"2026-04-07T00:54:36.374533Z","end":"2026-04-07T00:54:36.569706Z","steps":["trace[1879276861] 'agreement among raft nodes before linearized reading' (duration: 195.020192ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:36.569803Z","caller":"traceutil/trace.go:171","msg":"trace[663892612] transaction","detail":"{read_only:false; response_revision:24367; number_of_response:1; }","duration":"155.436314ms","start":"2026-04-07T00:54:36.414348Z","end":"2026-04-07T00:54:36.569784Z","steps":["trace[663892612] 'process raft request' (duration: 155.007354ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:36.822782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"245.258401ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-07T00:54:36.822833Z","caller":"traceutil/trace.go:171","msg":"trace[843999181] transaction","detail":"{read_only:false; response_revision:24368; number_of_response:1; }","duration":"194.50123ms","start":"2026-04-07T00:54:36.628282Z","end":"2026-04-07T00:54:36.822783Z","steps":["trace[843999181] 'process raft request' (duration: 105.440463ms)","trace[843999181] 'compare' (duration: 88.823031ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:36.82294Z","caller":"traceutil/trace.go:171","msg":"trace[1828008330] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24367; }","duration":"245.514567ms","start":"2026-04-07T00:54:36.577395Z","end":"2026-04-07T00:54:36.82291Z","steps":["trace[1828008330] 'range keys from in-memory index tree' (duration: 245.046345ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:37.022252Z","caller":"traceutil/trace.go:171","msg":"trace[1465310071] transaction","detail":"{read_only:false; response_revision:24369; number_of_response:1; }","duration":"392.881686ms","start":"2026-04-07T00:54:36.629321Z","end":"2026-04-07T00:54:37.022202Z","steps":["trace[1465310071] 'process raft request' (duration: 392.604879ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.022565Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:36.629294Z","time spent":"393.057629ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:37.022261Z","caller":"traceutil/trace.go:171","msg":"trace[1182285261] linearizableReadLoop","detail":"{readStateIndex:25178; appliedIndex:25174; }","duration":"347.322052ms","start":"2026-04-07T00:54:36.674906Z","end":"2026-04-07T00:54:37.022229Z","steps":["trace[1182285261] 'read index received' (duration: 58.846635ms)","trace[1182285261] 'applied index is now lower than readState.Index' (duration: 288.474016ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:37.022761Z","caller":"traceutil/trace.go:171","msg":"trace[201683035] transaction","detail":"{read_only:false; response_revision:24370; number_of_response:1; }","duration":"393.340696ms","start":"2026-04-07T00:54:36.62939Z","end":"2026-04-07T00:54:37.022731Z","steps":["trace[201683035] 'process raft request' (duration: 392.735142ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:37.02277Z","caller":"traceutil/trace.go:171","msg":"trace[368616774] transaction","detail":"{read_only:false; response_revision:24371; number_of_response:1; }","duration":"393.09197ms","start":"2026-04-07T00:54:36.629641Z","end":"2026-04-07T00:54:37.022733Z","steps":["trace[368616774] 'process raft request' (duration: 392.533447ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.02279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.728602ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"warn","ts":"2026-04-07T00:54:37.022964Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:36.629345Z","time spent":"393.555191ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:37.022989Z","caller":"traceutil/trace.go:171","msg":"trace[131886592] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24371; }","duration":"348.11693ms","start":"2026-04-07T00:54:36.674855Z","end":"2026-04-07T00:54:37.022972Z","steps":["trace[131886592] 'agreement among raft nodes before linearized reading' (duration: 347.472505ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.023029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:36.629597Z","time spent":"393.328006ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:37.023047Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"195.157276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-07T00:54:37.023124Z","caller":"traceutil/trace.go:171","msg":"trace[177923390] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24371; }","duration":"195.270979ms","start":"2026-04-07T00:54:36.827827Z","end":"2026-04-07T00:54:37.023098Z","steps":["trace[177923390] 'agreement among raft nodes before linearized reading' (duration: 194.972182ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.023158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:36.674835Z","time spent":"348.240253ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T00:54:37.37265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.500891ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:37.372891Z","caller":"traceutil/trace.go:171","msg":"trace[273320272] linearizableReadLoop","detail":"{readStateIndex:25182; appliedIndex:25180; }","duration":"273.479733ms","start":"2026-04-07T00:54:37.099388Z","end":"2026-04-07T00:54:37.372868Z","steps":["trace[273320272] 'read index received' (duration: 104.490531ms)","trace[273320272] 'applied index is now lower than readState.Index' (duration: 168.987392ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:37.372907Z","caller":"traceutil/trace.go:171","msg":"trace[555991784] transaction","detail":"{read_only:false; response_revision:24375; number_of_response:1; }","duration":"341.284086ms","start":"2026-04-07T00:54:37.031589Z","end":"2026-04-07T00:54:37.372873Z","steps":["trace[555991784] 'process raft request' (duration: 341.201774ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:37.372921Z","caller":"traceutil/trace.go:171","msg":"trace[1032123389] transaction","detail":"{read_only:false; response_revision:24374; number_of_response:1; }","duration":"343.195602ms","start":"2026-04-07T00:54:37.029686Z","end":"2026-04-07T00:54:37.372881Z","steps":["trace[1032123389] 'process raft request' (duration: 174.329372ms)","trace[1032123389] 'compare' (duration: 168.343477ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:37.373056Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"273.650828ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:54:37.373083Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:37.031564Z","time spent":"341.424709ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:37.373128Z","caller":"traceutil/trace.go:171","msg":"trace[101814358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24375; }","duration":"273.780651ms","start":"2026-04-07T00:54:37.099329Z","end":"2026-04-07T00:54:37.373109Z","steps":["trace[101814358] 'agreement among raft nodes before linearized reading' (duration: 273.642828ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.373142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:37.029661Z","time spent":"343.408197ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:37.387649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.676467ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:37.387738Z","caller":"traceutil/trace.go:171","msg":"trace[1952323409] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24376; }","duration":"287.784131ms","start":"2026-04-07T00:54:37.099936Z","end":"2026-04-07T00:54:37.38772Z","steps":["trace[1952323409] 'agreement among raft nodes before linearized reading' (duration: 287.617567ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:37.588583Z","caller":"traceutil/trace.go:171","msg":"trace[451824326] transaction","detail":"{read_only:false; response_revision:24377; number_of_response:1; }","duration":"196.640583ms","start":"2026-04-07T00:54:37.391917Z","end":"2026-04-07T00:54:37.588558Z","steps":["trace[451824326] 'process raft request' (duration: 196.499029ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:37.603332Z","caller":"traceutil/trace.go:171","msg":"trace[1950439250] linearizableReadLoop","detail":"{readStateIndex:25185; appliedIndex:25183; }","duration":"208.90942ms","start":"2026-04-07T00:54:37.394396Z","end":"2026-04-07T00:54:37.603306Z","steps":["trace[1950439250] 'read index received' (duration: 194.06842ms)","trace[1950439250] 'applied index is now lower than readState.Index' (duration: 14.83953ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:37.603537Z","caller":"traceutil/trace.go:171","msg":"trace[349070724] transaction","detail":"{read_only:false; response_revision:24378; number_of_response:1; }","duration":"210.855697ms","start":"2026-04-07T00:54:37.392607Z","end":"2026-04-07T00:54:37.603463Z","steps":["trace[349070724] 'process raft request' (duration: 210.540019ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.603616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.673791ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/networkpolicies/\" range_end:\"/registry/networkpolicies0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:54:37.603667Z","caller":"traceutil/trace.go:171","msg":"trace[785502173] range","detail":"{range_begin:/registry/networkpolicies/; range_end:/registry/networkpolicies0; response_count:0; response_revision:24378; }","duration":"103.781643ms","start":"2026-04-07T00:54:37.499872Z","end":"2026-04-07T00:54:37.603653Z","steps":["trace[785502173] 'agreement among raft nodes before linearized reading' (duration: 103.684841ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:37.603637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.227247ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-07T00:54:37.604157Z","caller":"traceutil/trace.go:171","msg":"trace[207656594] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24378; }","duration":"209.372861ms","start":"2026-04-07T00:54:37.394323Z","end":"2026-04-07T00:54:37.603695Z","steps":["trace[207656594] 'agreement among raft nodes before linearized reading' (duration: 209.109625ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:37.847123Z","caller":"traceutil/trace.go:171","msg":"trace[340258823] transaction","detail":"{read_only:false; response_revision:24379; number_of_response:1; }","duration":"129.25884ms","start":"2026-04-07T00:54:37.71779Z","end":"2026-04-07T00:54:37.847049Z","steps":["trace[340258823] 'process raft request' (duration: 113.218421ms)","trace[340258823] 'compare' (duration: 15.873396ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:38.690881Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.152191ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T00:54:38.691168Z","caller":"traceutil/trace.go:171","msg":"trace[1121995343] linearizableReadLoop","detail":"{readStateIndex:25188; appliedIndex:25187; }","duration":"236.419446ms","start":"2026-04-07T00:54:38.454725Z","end":"2026-04-07T00:54:38.691145Z","steps":["trace[1121995343] 'read index received' (duration: 92.05435ms)","trace[1121995343] 'applied index is now lower than readState.Index' (duration: 144.363676ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:38.691305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.572899ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:38.691344Z","caller":"traceutil/trace.go:171","msg":"trace[1006520490] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:24380; }","duration":"236.683512ms","start":"2026-04-07T00:54:38.45465Z","end":"2026-04-07T00:54:38.691333Z","steps":["trace[1006520490] 'agreement among raft nodes before linearized reading' (duration: 236.59605ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:38.691498Z","caller":"traceutil/trace.go:171","msg":"trace[746285965] transaction","detail":"{read_only:false; response_revision:24381; number_of_response:1; }","duration":"139.13859ms","start":"2026-04-07T00:54:38.552326Z","end":"2026-04-07T00:54:38.691465Z","steps":["trace[746285965] 'process raft request' (duration: 138.685709ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:38.912609Z","caller":"traceutil/trace.go:171","msg":"trace[822055953] linearizableReadLoop","detail":"{readStateIndex:25190; appliedIndex:25189; }","duration":"221.365152ms","start":"2026-04-07T00:54:38.69121Z","end":"2026-04-07T00:54:38.912575Z","steps":["trace[822055953] 'read index received' (duration: 221.115296ms)","trace[822055953] 'applied index is now lower than readState.Index' (duration: 248.006µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:38.912769Z","caller":"traceutil/trace.go:171","msg":"trace[749650566] transaction","detail":"{read_only:false; response_revision:24382; number_of_response:1; }","duration":"336.423318ms","start":"2026-04-07T00:54:38.576297Z","end":"2026-04-07T00:54:38.91272Z","steps":["trace[749650566] 'process raft request' (duration: 336.013598ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:38.912817Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"356.962915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:54:38.912876Z","caller":"traceutil/trace.go:171","msg":"trace[1417977425] range","detail":"{range_begin:/registry/flowschemas/; range_end:/registry/flowschemas0; response_count:0; response_revision:24382; }","duration":"357.041447ms","start":"2026-04-07T00:54:38.555822Z","end":"2026-04-07T00:54:38.912863Z","steps":["trace[1417977425] 'agreement among raft nodes before linearized reading' (duration: 356.882903ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:38.912912Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:38.555738Z","time spent":"357.16743ms","remote":"127.0.0.1:56224","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":13,"response size":30,"request content":"key:\"/registry/flowschemas/\" range_end:\"/registry/flowschemas0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:54:38.912998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:38.576264Z","time spent":"336.610612ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:38.936902Z","caller":"traceutil/trace.go:171","msg":"trace[2092233643] transaction","detail":"{read_only:false; response_revision:24383; number_of_response:1; }","duration":"107.3445ms","start":"2026-04-07T00:54:38.829536Z","end":"2026-04-07T00:54:38.936881Z","steps":["trace[2092233643] 'process raft request' (duration: 107.209177ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:39.243167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.017724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:39.243262Z","caller":"traceutil/trace.go:171","msg":"trace[102254329] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24383; }","duration":"233.169868ms","start":"2026-04-07T00:54:39.010069Z","end":"2026-04-07T00:54:39.243238Z","steps":["trace[102254329] 'range keys from in-memory index tree' (duration: 232.847618ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:39.243421Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.906295ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:39.243528Z","caller":"traceutil/trace.go:171","msg":"trace[1936339432] transaction","detail":"{read_only:false; response_revision:24384; number_of_response:1; }","duration":"214.511746ms","start":"2026-04-07T00:54:39.028996Z","end":"2026-04-07T00:54:39.243507Z","steps":["trace[1936339432] 'process raft request' (duration: 87.397176ms)","trace[1936339432] 'compare' (duration: 126.469233ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:39.249668Z","caller":"traceutil/trace.go:171","msg":"trace[518068105] linearizableReadLoop","detail":"{readStateIndex:25194; appliedIndex:25191; }","duration":"200.195949ms","start":"2026-04-07T00:54:39.049446Z","end":"2026-04-07T00:54:39.249642Z","steps":["trace[518068105] 'read index received' (duration: 67.095835ms)","trace[518068105] 'applied index is now lower than readState.Index' (duration: 133.098564ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:39.249707Z","caller":"traceutil/trace.go:171","msg":"trace[1405185956] transaction","detail":"{read_only:false; response_revision:24385; number_of_response:1; }","duration":"219.869975ms","start":"2026-04-07T00:54:39.029813Z","end":"2026-04-07T00:54:39.249683Z","steps":["trace[1405185956] 'process raft request' (duration: 219.572258ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:39.249909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.068721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:440"} {"level":"info","ts":"2026-04-07T00:54:39.249768Z","caller":"traceutil/trace.go:171","msg":"trace[1792329349] transaction","detail":"{read_only:false; response_revision:24386; number_of_response:1; }","duration":"218.335528ms","start":"2026-04-07T00:54:39.031394Z","end":"2026-04-07T00:54:39.24973Z","steps":["trace[1792329349] 'process raft request' (duration: 218.183935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:39.24993Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.328856ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:54:39.249952Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.496066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-07T00:54:39.250015Z","caller":"traceutil/trace.go:171","msg":"trace[197463231] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24386; }","duration":"144.46823ms","start":"2026-04-07T00:54:39.105526Z","end":"2026-04-07T00:54:39.249995Z","steps":["trace[197463231] 'agreement among raft nodes before linearized reading' (duration: 144.281205ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:39.25004Z","caller":"traceutil/trace.go:171","msg":"trace[102333419] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24386; }","duration":"200.629049ms","start":"2026-04-07T00:54:39.04939Z","end":"2026-04-07T00:54:39.250019Z","steps":["trace[102333419] 'agreement among raft nodes before linearized reading' (duration: 200.373243ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:39.249952Z","caller":"traceutil/trace.go:171","msg":"trace[397987852] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24386; }","duration":"199.151343ms","start":"2026-04-07T00:54:39.050788Z","end":"2026-04-07T00:54:39.24994Z","steps":["trace[397987852] 'agreement among raft nodes before linearized reading' (duration: 198.989149ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:52.450483Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"291.182531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1866"} {"level":"info","ts":"2026-04-07T00:54:52.450654Z","caller":"traceutil/trace.go:171","msg":"trace[153895245] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:24540; }","duration":"291.399286ms","start":"2026-04-07T00:54:52.159228Z","end":"2026-04-07T00:54:52.450627Z","steps":["trace[153895245] 'range keys from in-memory index tree' (duration: 290.928834ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:52.650112Z","caller":"traceutil/trace.go:171","msg":"trace[117243323] transaction","detail":"{read_only:false; response_revision:24542; number_of_response:1; }","duration":"191.078169ms","start":"2026-04-07T00:54:52.458981Z","end":"2026-04-07T00:54:52.650059Z","steps":["trace[117243323] 'process raft request' (duration: 190.803642ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.014972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"259.32926ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/certificates/\" range_end:\"/registry/secretgen.k14s.io/certificates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:53.015059Z","caller":"traceutil/trace.go:171","msg":"trace[1981827528] range","detail":"{range_begin:/registry/secretgen.k14s.io/certificates/; range_end:/registry/secretgen.k14s.io/certificates0; response_count:0; response_revision:24542; }","duration":"259.496014ms","start":"2026-04-07T00:54:52.755542Z","end":"2026-04-07T00:54:53.015038Z","steps":["trace[1981827528] 'count revisions from in-memory index tree' (duration: 259.131855ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.015197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.050864ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:53.015298Z","caller":"traceutil/trace.go:171","msg":"trace[1469161048] linearizableReadLoop","detail":"{readStateIndex:25353; appliedIndex:25352; }","duration":"171.361953ms","start":"2026-04-07T00:54:52.84392Z","end":"2026-04-07T00:54:53.015282Z","steps":["trace[1469161048] 'read index received' (duration: 17.099904ms)","trace[1469161048] 'applied index is now lower than readState.Index' (duration: 154.259729ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:53.015365Z","caller":"traceutil/trace.go:171","msg":"trace[1987527139] transaction","detail":"{read_only:false; response_revision:24543; number_of_response:1; }","duration":"259.666858ms","start":"2026-04-07T00:54:52.755631Z","end":"2026-04-07T00:54:53.015298Z","steps":["trace[1987527139] 'process raft request' (duration: 105.434099ms)","trace[1987527139] 'compare' (duration: 153.715876ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:53.01546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.550977ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-07T00:54:53.015503Z","caller":"traceutil/trace.go:171","msg":"trace[465962723] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24543; }","duration":"171.64768ms","start":"2026-04-07T00:54:52.843845Z","end":"2026-04-07T00:54:53.015493Z","steps":["trace[465962723] 'agreement among raft nodes before linearized reading' (duration: 171.482966ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:53.098748Z","caller":"traceutil/trace.go:171","msg":"trace[1421565230] transaction","detail":"{read_only:false; response_revision:24544; number_of_response:1; }","duration":"138.502559ms","start":"2026-04-07T00:54:52.960221Z","end":"2026-04-07T00:54:53.098724Z","steps":["trace[1421565230] 'process raft request' (duration: 138.336204ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.382404Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.577282ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:54:53.382673Z","caller":"traceutil/trace.go:171","msg":"trace[938869254] linearizableReadLoop","detail":"{readStateIndex:25355; appliedIndex:25353; }","duration":"362.642608ms","start":"2026-04-07T00:54:53.020011Z","end":"2026-04-07T00:54:53.382653Z","steps":["trace[938869254] 'read index received' (duration: 78.57934ms)","trace[938869254] 'applied index is now lower than readState.Index' (duration: 284.062088ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:53.382717Z","caller":"traceutil/trace.go:171","msg":"trace[1396159473] transaction","detail":"{read_only:false; response_revision:24545; number_of_response:1; }","duration":"384.987319ms","start":"2026-04-07T00:54:52.997666Z","end":"2026-04-07T00:54:53.382654Z","steps":["trace[1396159473] 'process raft request' (duration: 157.063157ms)","trace[1396159473] 'compare' (duration: 227.318536ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:53.383094Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"363.060428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-07T00:54:53.382986Z","caller":"traceutil/trace.go:171","msg":"trace[896514224] transaction","detail":"{read_only:false; response_revision:24546; number_of_response:1; }","duration":"300.114756ms","start":"2026-04-07T00:54:53.082858Z","end":"2026-04-07T00:54:53.382972Z","steps":["trace[896514224] 'process raft request' (duration: 299.692476ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.383279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:52.997639Z","time spent":"385.499591ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:53.383358Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:53.082839Z","time spent":"300.421313ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:53.383193Z","caller":"traceutil/trace.go:171","msg":"trace[1921894718] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24546; }","duration":"363.246372ms","start":"2026-04-07T00:54:53.019926Z","end":"2026-04-07T00:54:53.383172Z","steps":["trace[1921894718] 'agreement among raft nodes before linearized reading' (duration: 362.967225ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.383464Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:53.019906Z","time spent":"363.540659ms","remote":"127.0.0.1:55956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"info","ts":"2026-04-07T00:54:53.424532Z","caller":"traceutil/trace.go:171","msg":"trace[1237876771] transaction","detail":"{read_only:false; response_revision:24547; number_of_response:1; }","duration":"136.09294ms","start":"2026-04-07T00:54:53.288415Z","end":"2026-04-07T00:54:53.424508Z","steps":["trace[1237876771] 'process raft request' (duration: 135.905996ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:53.685239Z","caller":"traceutil/trace.go:171","msg":"trace[2115545880] linearizableReadLoop","detail":"{readStateIndex:25359; appliedIndex:25356; }","duration":"302.499953ms","start":"2026-04-07T00:54:53.382712Z","end":"2026-04-07T00:54:53.685212Z","steps":["trace[2115545880] 'read index received' (duration: 41.626436ms)","trace[2115545880] 'applied index is now lower than readState.Index' (duration: 260.872457ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:53.685539Z","caller":"traceutil/trace.go:171","msg":"trace[1116768186] transaction","detail":"{read_only:false; response_revision:24548; number_of_response:1; }","duration":"381.603676ms","start":"2026-04-07T00:54:53.303883Z","end":"2026-04-07T00:54:53.685487Z","steps":["trace[1116768186] 'process raft request' (duration: 344.320934ms)","trace[1116768186] 'compare' (duration: 36.144704ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:53.68571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"615.349057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-533594ec-f28c-4945-99ea-dc7da1122157\" ","response":"range_response_count:1 size:3763"} {"level":"info","ts":"2026-04-07T00:54:53.685773Z","caller":"traceutil/trace.go:171","msg":"trace[1709626309] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-533594ec-f28c-4945-99ea-dc7da1122157; range_end:; response_count:1; response_revision:24549; }","duration":"615.490301ms","start":"2026-04-07T00:54:53.070267Z","end":"2026-04-07T00:54:53.685757Z","steps":["trace[1709626309] 'agreement among raft nodes before linearized reading' (duration: 615.299157ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.685796Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:53.30386Z","time spent":"381.795481ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:53.685816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:53.070241Z","time spent":"615.565272ms","remote":"127.0.0.1:55956","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3785,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-533594ec-f28c-4945-99ea-dc7da1122157\" "} {"level":"warn","ts":"2026-04-07T00:54:53.685816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"300.515486ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18570"} {"level":"warn","ts":"2026-04-07T00:54:53.685864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"331.370412ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.14\" ","response":"range_response_count:1 size:137"} {"level":"info","ts":"2026-04-07T00:54:53.685962Z","caller":"traceutil/trace.go:171","msg":"trace[1783509462] range","detail":"{range_begin:/registry/masterleases/199.19.213.14; range_end:; response_count:1; response_revision:24549; }","duration":"331.621038ms","start":"2026-04-07T00:54:53.354326Z","end":"2026-04-07T00:54:53.685947Z","steps":["trace[1783509462] 'agreement among raft nodes before linearized reading' (duration: 331.106116ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.686075Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.617749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:54:53.686108Z","caller":"traceutil/trace.go:171","msg":"trace[2147201386] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24549; }","duration":"238.6836ms","start":"2026-04-07T00:54:53.447416Z","end":"2026-04-07T00:54:53.6861Z","steps":["trace[2147201386] 'agreement among raft nodes before linearized reading' (duration: 238.608978ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.68611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"367.403712ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-07T00:54:53.686144Z","caller":"traceutil/trace.go:171","msg":"trace[1977781124] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24549; }","duration":"367.470534ms","start":"2026-04-07T00:54:53.318663Z","end":"2026-04-07T00:54:53.686134Z","steps":["trace[1977781124] 'agreement among raft nodes before linearized reading' (duration: 367.374202ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.686181Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:53.318653Z","time spent":"367.521385ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":463,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-04-07T00:54:53.68526Z","caller":"traceutil/trace.go:171","msg":"trace[1929148946] transaction","detail":"{read_only:false; response_revision:24549; number_of_response:1; }","duration":"379.785712ms","start":"2026-04-07T00:54:53.305444Z","end":"2026-04-07T00:54:53.68523Z","steps":["trace[1929148946] 'process raft request' (duration: 379.70306ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.686164Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:53.354305Z","time spent":"331.844783ms","remote":"127.0.0.1:55816","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":159,"request content":"key:\"/registry/masterleases/199.19.213.14\" "} {"level":"warn","ts":"2026-04-07T00:54:53.686419Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:53.3054Z","time spent":"380.861338ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:54:53.686554Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"371.944193ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-07T00:54:53.6859Z","caller":"traceutil/trace.go:171","msg":"trace[817593381] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24549; }","duration":"300.649269ms","start":"2026-04-07T00:54:53.385231Z","end":"2026-04-07T00:54:53.685881Z","steps":["trace[817593381] 'agreement among raft nodes before linearized reading' (duration: 300.393103ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.686627Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:53.385217Z","time spent":"301.397887ms","remote":"127.0.0.1:55952","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":18592,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-04-07T00:54:53.68663Z","caller":"traceutil/trace.go:171","msg":"trace[1045763978] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24549; }","duration":"372.147198ms","start":"2026-04-07T00:54:53.314455Z","end":"2026-04-07T00:54:53.686602Z","steps":["trace[1045763978] 'agreement among raft nodes before linearized reading' (duration: 371.837121ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.686679Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:53.314426Z","time spent":"372.243321ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-04-07T00:54:53.686Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.135186ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-07T00:54:53.686786Z","caller":"traceutil/trace.go:171","msg":"trace[1689668162] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24549; }","duration":"216.959716ms","start":"2026-04-07T00:54:53.469814Z","end":"2026-04-07T00:54:53.686773Z","steps":["trace[1689668162] 'agreement among raft nodes before linearized reading' (duration: 216.112965ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:53.880855Z","caller":"traceutil/trace.go:171","msg":"trace[964148422] transaction","detail":"{read_only:false; response_revision:24552; number_of_response:1; }","duration":"187.971664ms","start":"2026-04-07T00:54:53.692856Z","end":"2026-04-07T00:54:53.880828Z","steps":["trace[964148422] 'process raft request' (duration: 187.890012ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:53.880917Z","caller":"traceutil/trace.go:171","msg":"trace[903583234] transaction","detail":"{read_only:false; response_revision:24550; number_of_response:1; }","duration":"189.124622ms","start":"2026-04-07T00:54:53.691744Z","end":"2026-04-07T00:54:53.880869Z","steps":["trace[903583234] 'process raft request' (duration: 188.829005ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:53.880962Z","caller":"traceutil/trace.go:171","msg":"trace[1309322833] transaction","detail":"{read_only:false; response_revision:24551; number_of_response:1; }","duration":"189.093251ms","start":"2026-04-07T00:54:53.691826Z","end":"2026-04-07T00:54:53.880919Z","steps":["trace[1309322833] 'process raft request' (duration: 188.879866ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:53.881219Z","caller":"traceutil/trace.go:171","msg":"trace[581057739] transaction","detail":"{read_only:false; response_revision:24554; number_of_response:1; }","duration":"187.686668ms","start":"2026-04-07T00:54:53.693514Z","end":"2026-04-07T00:54:53.881201Z","steps":["trace[581057739] 'process raft request' (duration: 187.546285ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:53.881228Z","caller":"traceutil/trace.go:171","msg":"trace[2121007652] linearizableReadLoop","detail":"{readStateIndex:25365; appliedIndex:25359; }","duration":"187.437992ms","start":"2026-04-07T00:54:53.693759Z","end":"2026-04-07T00:54:53.881196Z","steps":["trace[2121007652] 'read index received' (duration: 170.843681ms)","trace[2121007652] 'applied index is now lower than readState.Index' (duration: 16.592791ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:53.881223Z","caller":"traceutil/trace.go:171","msg":"trace[1584141357] transaction","detail":"{read_only:false; response_revision:24553; number_of_response:1; }","duration":"187.728269ms","start":"2026-04-07T00:54:53.693447Z","end":"2026-04-07T00:54:53.881175Z","steps":["trace[1584141357] 'process raft request' (duration: 187.337859ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:53.881494Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.712858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-04-07T00:54:53.881493Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.697474ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:53.881557Z","caller":"traceutil/trace.go:171","msg":"trace[1267699914] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24554; }","duration":"187.806331ms","start":"2026-04-07T00:54:53.693732Z","end":"2026-04-07T00:54:53.881538Z","steps":["trace[1267699914] 'agreement among raft nodes before linearized reading' (duration: 187.572775ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:53.88161Z","caller":"traceutil/trace.go:171","msg":"trace[1249747629] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24554; }","duration":"126.928049ms","start":"2026-04-07T00:54:53.754656Z","end":"2026-04-07T00:54:53.881584Z","steps":["trace[1249747629] 'agreement among raft nodes before linearized reading' (duration: 126.711014ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:54.182273Z","caller":"traceutil/trace.go:171","msg":"trace[817351326] linearizableReadLoop","detail":"{readStateIndex:25367; appliedIndex:25366; }","duration":"204.099385ms","start":"2026-04-07T00:54:53.978137Z","end":"2026-04-07T00:54:54.182236Z","steps":["trace[817351326] 'read index received' (duration: 203.953861ms)","trace[817351326] 'applied index is now lower than readState.Index' (duration: 143.964µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:54:54.182343Z","caller":"traceutil/trace.go:171","msg":"trace[1388013456] transaction","detail":"{read_only:false; response_revision:24556; number_of_response:1; }","duration":"295.952285ms","start":"2026-04-07T00:54:53.886341Z","end":"2026-04-07T00:54:54.182294Z","steps":["trace[1388013456] 'process raft request' (duration: 295.681649ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:54.182562Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.409943ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/default/kubernetes\" ","response":"range_response_count:1 size:422"} {"level":"info","ts":"2026-04-07T00:54:54.182616Z","caller":"traceutil/trace.go:171","msg":"trace[1158987046] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:24556; }","duration":"204.529325ms","start":"2026-04-07T00:54:53.978069Z","end":"2026-04-07T00:54:54.182599Z","steps":["trace[1158987046] 'agreement among raft nodes before linearized reading' (duration: 204.31791ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:54.252588Z","caller":"traceutil/trace.go:171","msg":"trace[1733890769] transaction","detail":"{read_only:false; response_revision:24557; number_of_response:1; }","duration":"101.046533ms","start":"2026-04-07T00:54:54.151509Z","end":"2026-04-07T00:54:54.252555Z","steps":["trace[1733890769] 'process raft request' (duration: 100.50601ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:54.25269Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.61251ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:54.252748Z","caller":"traceutil/trace.go:171","msg":"trace[236650497] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24557; }","duration":"152.711202ms","start":"2026-04-07T00:54:54.100014Z","end":"2026-04-07T00:54:54.252725Z","steps":["trace[236650497] 'agreement among raft nodes before linearized reading' (duration: 152.642571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:54.2526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.501379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-07T00:54:54.252867Z","caller":"traceutil/trace.go:171","msg":"trace[937317685] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:24557; }","duration":"272.763825ms","start":"2026-04-07T00:54:53.980043Z","end":"2026-04-07T00:54:54.252807Z","steps":["trace[937317685] 'agreement among raft nodes before linearized reading' (duration: 272.244633ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:54.570959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.214829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephobjectstoreusers/\" range_end:\"/registry/ceph.rook.io/cephobjectstoreusers0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:54:54.571093Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.144075ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5684"} {"level":"info","ts":"2026-04-07T00:54:54.571121Z","caller":"traceutil/trace.go:171","msg":"trace[1702999164] range","detail":"{range_begin:/registry/ceph.rook.io/cephobjectstoreusers/; range_end:/registry/ceph.rook.io/cephobjectstoreusers0; response_count:0; response_revision:24557; }","duration":"130.471714ms","start":"2026-04-07T00:54:54.440612Z","end":"2026-04-07T00:54:54.571083Z","steps":["trace[1702999164] 'count revisions from in-memory index tree' (duration: 130.073975ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:54.571182Z","caller":"traceutil/trace.go:171","msg":"trace[590942714] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:24557; }","duration":"302.267859ms","start":"2026-04-07T00:54:54.268892Z","end":"2026-04-07T00:54:54.57116Z","steps":["trace[590942714] 'range keys from in-memory index tree' (duration: 301.94195ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:54.571247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:54.268878Z","time spent":"302.359941ms","remote":"127.0.0.1:42262","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5706,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"info","ts":"2026-04-07T00:54:55.457767Z","caller":"traceutil/trace.go:171","msg":"trace[174900383] transaction","detail":"{read_only:false; response_revision:24560; number_of_response:1; }","duration":"351.463428ms","start":"2026-04-07T00:54:55.106273Z","end":"2026-04-07T00:54:55.457737Z","steps":["trace[174900383] 'process raft request' (duration: 351.283894ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:55.457932Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:54:55.106243Z","time spent":"351.60107ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":497,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:54:55.525004Z","caller":"traceutil/trace.go:171","msg":"trace[291079744] transaction","detail":"{read_only:false; response_revision:24561; number_of_response:1; }","duration":"275.553512ms","start":"2026-04-07T00:54:55.249423Z","end":"2026-04-07T00:54:55.524977Z","steps":["trace[291079744] 'process raft request' (duration: 275.317786ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:55.525393Z","caller":"traceutil/trace.go:171","msg":"trace[1532148383] transaction","detail":"{read_only:false; response_revision:24562; number_of_response:1; }","duration":"135.898085ms","start":"2026-04-07T00:54:55.389472Z","end":"2026-04-07T00:54:55.52537Z","steps":["trace[1532148383] 'process raft request' (duration: 135.450024ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:55.694211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.276134ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cert-manager.io/certificates/\" range_end:\"/registry/cert-manager.io/certificates0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:54:55.694318Z","caller":"traceutil/trace.go:171","msg":"trace[2147443053] range","detail":"{range_begin:/registry/cert-manager.io/certificates/; range_end:/registry/cert-manager.io/certificates0; response_count:0; response_revision:24563; }","duration":"100.431338ms","start":"2026-04-07T00:54:55.593863Z","end":"2026-04-07T00:54:55.694294Z","steps":["trace[2147443053] 'count revisions from in-memory index tree' (duration: 100.211952ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:56.141524Z","caller":"traceutil/trace.go:171","msg":"trace[1461556983] transaction","detail":"{read_only:false; response_revision:24566; number_of_response:1; }","duration":"248.936109ms","start":"2026-04-07T00:54:55.892559Z","end":"2026-04-07T00:54:56.141495Z","steps":["trace[1461556983] 'process raft request' (duration: 248.745614ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:56.158199Z","caller":"traceutil/trace.go:171","msg":"trace[1414562048] transaction","detail":"{read_only:false; response_revision:24569; number_of_response:1; }","duration":"264.803143ms","start":"2026-04-07T00:54:55.89337Z","end":"2026-04-07T00:54:56.158173Z","steps":["trace[1414562048] 'process raft request' (duration: 264.744021ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:56.158345Z","caller":"traceutil/trace.go:171","msg":"trace[51355303] transaction","detail":"{read_only:false; response_revision:24567; number_of_response:1; }","duration":"265.143331ms","start":"2026-04-07T00:54:55.893194Z","end":"2026-04-07T00:54:56.158338Z","steps":["trace[51355303] 'process raft request' (duration: 264.757512ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:56.15841Z","caller":"traceutil/trace.go:171","msg":"trace[800487758] transaction","detail":"{read_only:false; response_revision:24568; number_of_response:1; }","duration":"265.11297ms","start":"2026-04-07T00:54:55.893291Z","end":"2026-04-07T00:54:56.158404Z","steps":["trace[800487758] 'process raft request' (duration: 264.792993ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:56.158554Z","caller":"traceutil/trace.go:171","msg":"trace[35235156] linearizableReadLoop","detail":"{readStateIndex:25381; appliedIndex:25377; }","duration":"196.603423ms","start":"2026-04-07T00:54:55.96189Z","end":"2026-04-07T00:54:56.158493Z","steps":["trace[35235156] 'read index received' (duration: 179.575801ms)","trace[35235156] 'applied index is now lower than readState.Index' (duration: 17.024752ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:54:56.158928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.027553ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:54:56.158976Z","caller":"traceutil/trace.go:171","msg":"trace[986519557] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:24570; }","duration":"197.128346ms","start":"2026-04-07T00:54:55.961838Z","end":"2026-04-07T00:54:56.158967Z","steps":["trace[986519557] 'agreement among raft nodes before linearized reading' (duration: 196.971592ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:54:56.158958Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.838035ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/openstack.k-orc.cloud/projects/\" range_end:\"/registry/openstack.k-orc.cloud/projects0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:54:56.159027Z","caller":"traceutil/trace.go:171","msg":"trace[1048622933] range","detail":"{range_begin:/registry/openstack.k-orc.cloud/projects/; range_end:/registry/openstack.k-orc.cloud/projects0; response_count:0; response_revision:24570; }","duration":"176.978668ms","start":"2026-04-07T00:54:55.982024Z","end":"2026-04-07T00:54:56.159003Z","steps":["trace[1048622933] 'agreement among raft nodes before linearized reading' (duration: 176.840415ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:56.159106Z","caller":"traceutil/trace.go:171","msg":"trace[1440641664] transaction","detail":"{read_only:false; response_revision:24570; number_of_response:1; }","duration":"265.620492ms","start":"2026-04-07T00:54:55.893478Z","end":"2026-04-07T00:54:56.159099Z","steps":["trace[1440641664] 'process raft request' (duration: 264.666359ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:54:56.354913Z","caller":"traceutil/trace.go:171","msg":"trace[1314906407] transaction","detail":"{read_only:false; response_revision:24571; number_of_response:1; }","duration":"161.441053ms","start":"2026-04-07T00:54:56.193438Z","end":"2026-04-07T00:54:56.354879Z","steps":["trace[1314906407] 'process raft request' (duration: 161.255939ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:00.550972Z","caller":"traceutil/trace.go:171","msg":"trace[771410571] transaction","detail":"{read_only:false; response_revision:24623; number_of_response:1; }","duration":"364.561386ms","start":"2026-04-07T00:55:00.186382Z","end":"2026-04-07T00:55:00.550944Z","steps":["trace[771410571] 'process raft request' (duration: 291.13027ms)","trace[771410571] 'compare' (duration: 72.436001ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:00.551109Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:00.186355Z","time spent":"364.686258ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:00.726325Z","caller":"traceutil/trace.go:171","msg":"trace[1257072528] transaction","detail":"{read_only:false; response_revision:24624; number_of_response:1; }","duration":"538.429779ms","start":"2026-04-07T00:55:00.187868Z","end":"2026-04-07T00:55:00.726298Z","steps":["trace[1257072528] 'process raft request' (duration: 538.179242ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:00.726555Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:00.187837Z","time spent":"538.579912ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":538,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:00.726742Z","caller":"traceutil/trace.go:171","msg":"trace[693133444] linearizableReadLoop","detail":"{readStateIndex:25437; appliedIndex:25434; }","duration":"455.491563ms","start":"2026-04-07T00:55:00.271219Z","end":"2026-04-07T00:55:00.726711Z","steps":["trace[693133444] 'read index received' (duration: 206.305278ms)","trace[693133444] 'applied index is now lower than readState.Index' (duration: 249.184375ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:00.726768Z","caller":"traceutil/trace.go:171","msg":"trace[1527315072] transaction","detail":"{read_only:false; response_revision:24625; number_of_response:1; }","duration":"538.793927ms","start":"2026-04-07T00:55:00.18792Z","end":"2026-04-07T00:55:00.726714Z","steps":["trace[1527315072] 'process raft request' (duration: 538.332736ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:00.726799Z","caller":"traceutil/trace.go:171","msg":"trace[617988351] transaction","detail":"{read_only:false; response_revision:24626; number_of_response:1; }","duration":"357.543484ms","start":"2026-04-07T00:55:00.369244Z","end":"2026-04-07T00:55:00.726787Z","steps":["trace[617988351] 'process raft request' (duration: 357.315869ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:00.726976Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"455.702677ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:55:00.727007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:00.187894Z","time spent":"538.993462ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:00.727022Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:00.369215Z","time spent":"357.694058ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:00.727041Z","caller":"traceutil/trace.go:171","msg":"trace[1741676266] range","detail":"{range_begin:/registry/ceph.rook.io/cephclients/; range_end:/registry/ceph.rook.io/cephclients0; response_count:0; response_revision:24626; }","duration":"455.80664ms","start":"2026-04-07T00:55:00.271215Z","end":"2026-04-07T00:55:00.727022Z","steps":["trace[1741676266] 'agreement among raft nodes before linearized reading' (duration: 455.645876ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:00.727086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:00.271189Z","time spent":"455.886682ms","remote":"127.0.0.1:55250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":28,"request content":"key:\"/registry/ceph.rook.io/cephclients/\" range_end:\"/registry/ceph.rook.io/cephclients0\" count_only:true "} {"level":"info","ts":"2026-04-07T00:55:00.79296Z","caller":"traceutil/trace.go:171","msg":"trace[384707914] transaction","detail":"{read_only:false; response_revision:24627; number_of_response:1; }","duration":"261.112003ms","start":"2026-04-07T00:55:00.531805Z","end":"2026-04-07T00:55:00.792917Z","steps":["trace[384707914] 'process raft request' (duration: 260.829826ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:00.792977Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"453.999766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"warn","ts":"2026-04-07T00:55:00.793072Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.499246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:10358"} {"level":"info","ts":"2026-04-07T00:55:00.793156Z","caller":"traceutil/trace.go:171","msg":"trace[1324871780] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24627; }","duration":"183.641249ms","start":"2026-04-07T00:55:00.609495Z","end":"2026-04-07T00:55:00.793137Z","steps":["trace[1324871780] 'agreement among raft nodes before linearized reading' (duration: 183.378543ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:00.79326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.400647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0\" ","response":"range_response_count:1 size:1860"} {"level":"info","ts":"2026-04-07T00:55:00.79328Z","caller":"traceutil/trace.go:171","msg":"trace[1962728208] range","detail":"{range_begin:/registry/storageclasses/; range_end:/registry/storageclasses0; response_count:0; response_revision:24627; }","duration":"454.178141ms","start":"2026-04-07T00:55:00.338902Z","end":"2026-04-07T00:55:00.79308Z","steps":["trace[1962728208] 'agreement among raft nodes before linearized reading' (duration: 453.942705ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:00.793336Z","caller":"traceutil/trace.go:171","msg":"trace[485581419] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-manila-server-0; range_end:; response_count:1; response_revision:24627; }","duration":"124.5165ms","start":"2026-04-07T00:55:00.668797Z","end":"2026-04-07T00:55:00.793313Z","steps":["trace[485581419] 'agreement among raft nodes before linearized reading' (duration: 124.273695ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:00.793362Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:00.338887Z","time spent":"454.454887ms","remote":"127.0.0.1:56154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":1,"response size":30,"request content":"key:\"/registry/storageclasses/\" range_end:\"/registry/storageclasses0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:55:00.793527Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.166074ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/openstack/heat-engine-cleaner\" ","response":"range_response_count:1 size:541"} {"level":"info","ts":"2026-04-07T00:55:00.793574Z","caller":"traceutil/trace.go:171","msg":"trace[1702715343] range","detail":"{range_begin:/registry/serviceaccounts/openstack/heat-engine-cleaner; range_end:; response_count:1; response_revision:24627; }","duration":"450.278846ms","start":"2026-04-07T00:55:00.343282Z","end":"2026-04-07T00:55:00.793561Z","steps":["trace[1702715343] 'agreement among raft nodes before linearized reading' (duration: 450.089161ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:00.793571Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"418.066619ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"warn","ts":"2026-04-07T00:55:00.793608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:00.343247Z","time spent":"450.352228ms","remote":"127.0.0.1:55974","response type":"/etcdserverpb.KV/Range","request count":0,"request size":57,"response count":1,"response size":563,"request content":"key:\"/registry/serviceaccounts/openstack/heat-engine-cleaner\" "} {"level":"info","ts":"2026-04-07T00:55:00.793637Z","caller":"traceutil/trace.go:171","msg":"trace[274605247] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24627; }","duration":"418.205302ms","start":"2026-04-07T00:55:00.375413Z","end":"2026-04-07T00:55:00.793618Z","steps":["trace[274605247] 'agreement among raft nodes before linearized reading' (duration: 417.964125ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:00.79368Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:00.375385Z","time spent":"418.285174ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T00:55:03.994087Z","caller":"traceutil/trace.go:171","msg":"trace[280521259] transaction","detail":"{read_only:false; response_revision:24666; number_of_response:1; }","duration":"123.964807ms","start":"2026-04-07T00:55:03.870096Z","end":"2026-04-07T00:55:03.99406Z","steps":["trace[280521259] 'process raft request' (duration: 123.805403ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:04.286488Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.207225ms","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-04-07T00:55:04.286653Z","caller":"traceutil/trace.go:171","msg":"trace[628508060] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24666; }","duration":"103.42496ms","start":"2026-04-07T00:55:04.183207Z","end":"2026-04-07T00:55:04.286631Z","steps":["trace[628508060] 'range keys from in-memory index tree' (duration: 103.00191ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:04.480807Z","caller":"traceutil/trace.go:171","msg":"trace[2004578185] linearizableReadLoop","detail":"{readStateIndex:25481; appliedIndex:25480; }","duration":"119.173482ms","start":"2026-04-07T00:55:04.361614Z","end":"2026-04-07T00:55:04.480788Z","steps":["trace[2004578185] 'read index received' (duration: 119.030178ms)","trace[2004578185] 'applied index is now lower than readState.Index' (duration: 142.354µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:04.480933Z","caller":"traceutil/trace.go:171","msg":"trace[210333630] transaction","detail":"{read_only:false; response_revision:24668; number_of_response:1; }","duration":"187.835891ms","start":"2026-04-07T00:55:04.293059Z","end":"2026-04-07T00:55:04.480894Z","steps":["trace[210333630] 'process raft request' (duration: 187.587025ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:04.481062Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.317605ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:04.481103Z","caller":"traceutil/trace.go:171","msg":"trace[464346988] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesetbindings0; response_count:0; response_revision:24668; }","duration":"119.52428ms","start":"2026-04-07T00:55:04.361568Z","end":"2026-04-07T00:55:04.481092Z","steps":["trace[464346988] 'agreement among raft nodes before linearized reading' (duration: 119.299764ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:07.838183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.51895ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/\" range_end:\"/registry/jobs0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:55:07.838303Z","caller":"traceutil/trace.go:171","msg":"trace[1305634014] range","detail":"{range_begin:/registry/jobs/; range_end:/registry/jobs0; response_count:0; response_revision:24702; }","duration":"131.701164ms","start":"2026-04-07T00:55:07.706577Z","end":"2026-04-07T00:55:07.838279Z","steps":["trace[1305634014] 'count revisions from in-memory index tree' (duration: 131.434198ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:07.838718Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.682457ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:55:07.83877Z","caller":"traceutil/trace.go:171","msg":"trace[347696635] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:24702; }","duration":"151.77916ms","start":"2026-04-07T00:55:07.686979Z","end":"2026-04-07T00:55:07.838758Z","steps":["trace[347696635] 'count revisions from in-memory index tree' (duration: 151.562914ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:08.225453Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.112842ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/\" range_end:\"/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:08.225487Z","caller":"traceutil/trace.go:171","msg":"trace[1883493963] linearizableReadLoop","detail":"{readStateIndex:25517; appliedIndex:25516; }","duration":"127.197855ms","start":"2026-04-07T00:55:08.098263Z","end":"2026-04-07T00:55:08.225461Z","steps":["trace[1883493963] 'read index received' (duration: 35.741223ms)","trace[1883493963] 'applied index is now lower than readState.Index' (duration: 91.454732ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:08.225542Z","caller":"traceutil/trace.go:171","msg":"trace[256458661] range","detail":"{range_begin:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs/; range_end:/registry/bootstrap.cluster.x-k8s.io/kubeadmconfigs0; response_count:0; response_revision:24703; }","duration":"232.250355ms","start":"2026-04-07T00:55:07.993273Z","end":"2026-04-07T00:55:08.225523Z","steps":["trace[256458661] 'count revisions from in-memory index tree' (duration: 232.0358ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:08.22554Z","caller":"traceutil/trace.go:171","msg":"trace[325766501] transaction","detail":"{read_only:false; response_revision:24704; number_of_response:1; }","duration":"215.498001ms","start":"2026-04-07T00:55:08.009996Z","end":"2026-04-07T00:55:08.225494Z","steps":["trace[325766501] 'process raft request' (duration: 124.031079ms)","trace[325766501] 'compare' (duration: 91.043341ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:08.225611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.39613ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:08.225666Z","caller":"traceutil/trace.go:171","msg":"trace[1508058326] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24704; }","duration":"127.492102ms","start":"2026-04-07T00:55:08.098159Z","end":"2026-04-07T00:55:08.225651Z","steps":["trace[1508058326] 'agreement among raft nodes before linearized reading' (duration: 127.406ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:10.007258Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.132858ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:18570"} {"level":"info","ts":"2026-04-07T00:55:10.007412Z","caller":"traceutil/trace.go:171","msg":"trace[999854418] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:24720; }","duration":"150.324765ms","start":"2026-04-07T00:55:09.857053Z","end":"2026-04-07T00:55:10.007378Z","steps":["trace[999854418] 'range keys from in-memory index tree' (duration: 149.894823ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:30.845778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.302182ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:55:30.845886Z","caller":"traceutil/trace.go:171","msg":"trace[360667041] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24893; }","duration":"104.506126ms","start":"2026-04-07T00:55:30.741361Z","end":"2026-04-07T00:55:30.845867Z","steps":["trace[360667041] 'range keys from in-memory index tree' (duration: 103.979654ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:31.359421Z","caller":"traceutil/trace.go:171","msg":"trace[1457714273] transaction","detail":"{read_only:false; response_revision:24900; number_of_response:1; }","duration":"142.833153ms","start":"2026-04-07T00:55:31.216557Z","end":"2026-04-07T00:55:31.35939Z","steps":["trace[1457714273] 'process raft request' (duration: 142.635779ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:36.577608Z","caller":"traceutil/trace.go:171","msg":"trace[593476331] transaction","detail":"{read_only:false; response_revision:24936; number_of_response:1; }","duration":"197.806683ms","start":"2026-04-07T00:55:36.379765Z","end":"2026-04-07T00:55:36.577572Z","steps":["trace[593476331] 'process raft request' (duration: 197.484105ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:36.967711Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"314.170706ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-07T00:55:36.967796Z","caller":"traceutil/trace.go:171","msg":"trace[1078407804] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24937; }","duration":"314.32388ms","start":"2026-04-07T00:55:36.653454Z","end":"2026-04-07T00:55:36.967778Z","steps":["trace[1078407804] 'range keys from in-memory index tree' (duration: 314.009391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:36.967838Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:36.653434Z","time spent":"314.394732ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-04-07T00:55:37.342233Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.641385ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:37.342371Z","caller":"traceutil/trace.go:171","msg":"trace[1187470666] transaction","detail":"{read_only:false; response_revision:24939; number_of_response:1; }","duration":"369.205636ms","start":"2026-04-07T00:55:36.973139Z","end":"2026-04-07T00:55:37.342345Z","steps":["trace[1187470666] 'process raft request' (duration: 179.355205ms)","trace[1187470666] 'compare' (duration: 189.324338ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:37.342443Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:36.973123Z","time spent":"369.288758ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:37.394551Z","caller":"traceutil/trace.go:171","msg":"trace[2024526356] transaction","detail":"{read_only:false; response_revision:24940; number_of_response:1; }","duration":"421.109001ms","start":"2026-04-07T00:55:36.973411Z","end":"2026-04-07T00:55:37.39452Z","steps":["trace[2024526356] 'process raft request' (duration: 420.813844ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:37.394742Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:36.973403Z","time spent":"421.244654ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":539,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:37.395023Z","caller":"traceutil/trace.go:171","msg":"trace[1955297385] linearizableReadLoop","detail":"{readStateIndex:25762; appliedIndex:25758; }","duration":"352.219715ms","start":"2026-04-07T00:55:37.042766Z","end":"2026-04-07T00:55:37.394985Z","steps":["trace[1955297385] 'read index received' (duration: 109.724792ms)","trace[1955297385] 'applied index is now lower than readState.Index' (duration: 242.493123ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:37.395107Z","caller":"traceutil/trace.go:171","msg":"trace[1240211004] transaction","detail":"{read_only:false; response_revision:24941; number_of_response:1; }","duration":"421.507742ms","start":"2026-04-07T00:55:36.973578Z","end":"2026-04-07T00:55:37.395086Z","steps":["trace[1240211004] 'process raft request' (duration: 420.865316ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:37.395158Z","caller":"traceutil/trace.go:171","msg":"trace[734876908] transaction","detail":"{read_only:false; response_revision:24942; number_of_response:1; }","duration":"420.439755ms","start":"2026-04-07T00:55:36.974706Z","end":"2026-04-07T00:55:37.395146Z","steps":["trace[734876908] 'process raft request' (duration: 420.133967ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:37.395235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:36.973546Z","time spent":"421.610424ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:37.395278Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:36.974697Z","time spent":"420.542547ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:37.395369Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"352.573284ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"warn","ts":"2026-04-07T00:55:37.395418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"297.505263ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:37.395423Z","caller":"traceutil/trace.go:171","msg":"trace[681466655] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24942; }","duration":"352.686617ms","start":"2026-04-07T00:55:37.042717Z","end":"2026-04-07T00:55:37.395403Z","steps":["trace[681466655] 'agreement among raft nodes before linearized reading' (duration: 352.436841ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:37.395459Z","caller":"traceutil/trace.go:171","msg":"trace[1208172986] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24942; }","duration":"297.574525ms","start":"2026-04-07T00:55:37.097874Z","end":"2026-04-07T00:55:37.395449Z","steps":["trace[1208172986] 'agreement among raft nodes before linearized reading' (duration: 297.504444ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:37.39548Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:37.042694Z","time spent":"352.77087ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"info","ts":"2026-04-07T00:55:37.572688Z","caller":"traceutil/trace.go:171","msg":"trace[1779755450] transaction","detail":"{read_only:false; response_revision:24943; number_of_response:1; }","duration":"171.441874ms","start":"2026-04-07T00:55:37.401198Z","end":"2026-04-07T00:55:37.57264Z","steps":["trace[1779755450] 'process raft request' (duration: 142.379802ms)","trace[1779755450] 'compare' (duration: 28.606371ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:37.573504Z","caller":"traceutil/trace.go:171","msg":"trace[362187987] transaction","detail":"{read_only:false; response_revision:24944; number_of_response:1; }","duration":"167.145661ms","start":"2026-04-07T00:55:37.406336Z","end":"2026-04-07T00:55:37.573481Z","steps":["trace[362187987] 'process raft request' (duration: 166.189147ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:37.941841Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.947068ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:37.942052Z","caller":"traceutil/trace.go:171","msg":"trace[233255655] transaction","detail":"{read_only:false; response_revision:24946; number_of_response:1; }","duration":"335.594164ms","start":"2026-04-07T00:55:37.606429Z","end":"2026-04-07T00:55:37.942023Z","steps":["trace[233255655] 'process raft request' (duration: 151.325778ms)","trace[233255655] 'compare' (duration: 183.731133ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:37.942157Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:37.606407Z","time spent":"335.700686ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":498,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:38.012058Z","caller":"traceutil/trace.go:171","msg":"trace[894376884] linearizableReadLoop","detail":"{readStateIndex:25768; appliedIndex:25765; }","duration":"298.688151ms","start":"2026-04-07T00:55:37.713284Z","end":"2026-04-07T00:55:38.011972Z","steps":["trace[894376884] 'read index received' (duration: 44.569488ms)","trace[894376884] 'applied index is now lower than readState.Index' (duration: 254.117443ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:38.01219Z","caller":"traceutil/trace.go:171","msg":"trace[133814841] transaction","detail":"{read_only:false; response_revision:24948; number_of_response:1; }","duration":"317.446374ms","start":"2026-04-07T00:55:37.69473Z","end":"2026-04-07T00:55:38.012176Z","steps":["trace[133814841] 'process raft request' (duration: 317.182058ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:38.01214Z","caller":"traceutil/trace.go:171","msg":"trace[1226487] transaction","detail":"{read_only:false; response_revision:24947; number_of_response:1; }","duration":"318.593231ms","start":"2026-04-07T00:55:37.693497Z","end":"2026-04-07T00:55:38.01209Z","steps":["trace[1226487] 'process raft request' (duration: 318.181961ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:38.012323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:37.694698Z","time spent":"317.530287ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:38.01251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:37.693463Z","time spent":"318.886399ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":522,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:38.012512Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"299.183784ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/\" range_end:\"/registry/monitoring.coreos.com/alertmanagers0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:55:38.012736Z","caller":"traceutil/trace.go:171","msg":"trace[9706078] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/; range_end:/registry/monitoring.coreos.com/alertmanagers0; response_count:0; response_revision:24948; }","duration":"299.462271ms","start":"2026-04-07T00:55:37.713237Z","end":"2026-04-07T00:55:38.012699Z","steps":["trace[9706078] 'agreement among raft nodes before linearized reading' (duration: 299.069711ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:38.305113Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.288467ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:38.305262Z","caller":"traceutil/trace.go:171","msg":"trace[977150423] linearizableReadLoop","detail":"{readStateIndex:25769; appliedIndex:25768; }","duration":"293.109367ms","start":"2026-04-07T00:55:38.012131Z","end":"2026-04-07T00:55:38.30524Z","steps":["trace[977150423] 'read index received' (duration: 110.545504ms)","trace[977150423] 'applied index is now lower than readState.Index' (duration: 182.561923ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:38.305382Z","caller":"traceutil/trace.go:171","msg":"trace[918647311] transaction","detail":"{read_only:false; response_revision:24949; number_of_response:1; }","duration":"329.022674ms","start":"2026-04-07T00:55:37.976346Z","end":"2026-04-07T00:55:38.305368Z","steps":["trace[918647311] 'process raft request' (duration: 146.338358ms)","trace[918647311] 'compare' (duration: 182.02054ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:38.305457Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:37.976317Z","time spent":"329.101897ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:38.30546Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.332825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:38.305516Z","caller":"traceutil/trace.go:171","msg":"trace[1784860857] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24949; }","duration":"205.434417ms","start":"2026-04-07T00:55:38.100067Z","end":"2026-04-07T00:55:38.305501Z","steps":["trace[1784860857] 'agreement among raft nodes before linearized reading' (duration: 205.332335ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:38.305667Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"295.000332ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumpodippools/\" range_end:\"/registry/cilium.io/ciliumpodippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:38.305809Z","caller":"traceutil/trace.go:171","msg":"trace[1877344598] range","detail":"{range_begin:/registry/cilium.io/ciliumpodippools/; range_end:/registry/cilium.io/ciliumpodippools0; response_count:0; response_revision:24949; }","duration":"295.198368ms","start":"2026-04-07T00:55:38.010572Z","end":"2026-04-07T00:55:38.30577Z","steps":["trace[1877344598] 'agreement among raft nodes before linearized reading' (duration: 294.966032ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:38.643622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.468192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/vhosts/\" range_end:\"/registry/rabbitmq.com/vhosts0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:38.643744Z","caller":"traceutil/trace.go:171","msg":"trace[1317043812] range","detail":"{range_begin:/registry/rabbitmq.com/vhosts/; range_end:/registry/rabbitmq.com/vhosts0; response_count:0; response_revision:24949; }","duration":"225.673436ms","start":"2026-04-07T00:55:38.418045Z","end":"2026-04-07T00:55:38.643718Z","steps":["trace[1317043812] 'count revisions from in-memory index tree' (duration: 225.343319ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:38.809345Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.520368ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:28"} {"level":"info","ts":"2026-04-07T00:55:38.810087Z","caller":"traceutil/trace.go:171","msg":"trace[477276826] transaction","detail":"{read_only:false; response_revision:24950; number_of_response:1; }","duration":"225.547444ms","start":"2026-04-07T00:55:38.584492Z","end":"2026-04-07T00:55:38.810039Z","steps":["trace[477276826] 'process raft request' (duration: 225.143464ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:38.810368Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.948874ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:508"} {"level":"info","ts":"2026-04-07T00:55:38.810412Z","caller":"traceutil/trace.go:171","msg":"trace[516979497] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:24950; }","duration":"188.007855ms","start":"2026-04-07T00:55:38.622392Z","end":"2026-04-07T00:55:38.8104Z","steps":["trace[516979497] 'agreement among raft nodes before linearized reading' (duration: 187.831411ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:38.810171Z","caller":"traceutil/trace.go:171","msg":"trace[117768398] linearizableReadLoop","detail":"{readStateIndex:25771; appliedIndex:25769; }","duration":"187.529124ms","start":"2026-04-07T00:55:38.622406Z","end":"2026-04-07T00:55:38.809935Z","steps":["trace[117768398] 'read index received' (duration: 3.377922ms)","trace[117768398] 'applied index is now lower than readState.Index' (duration: 184.149562ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:38.861065Z","caller":"traceutil/trace.go:171","msg":"trace[97512559] transaction","detail":"{read_only:false; response_revision:24951; number_of_response:1; }","duration":"158.364079ms","start":"2026-04-07T00:55:38.702659Z","end":"2026-04-07T00:55:38.861023Z","steps":["trace[97512559] 'process raft request' (duration: 158.129793ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:38.861078Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.142171ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:38.861343Z","caller":"traceutil/trace.go:171","msg":"trace[1728653267] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:24951; }","duration":"203.440029ms","start":"2026-04-07T00:55:38.657847Z","end":"2026-04-07T00:55:38.861287Z","steps":["trace[1728653267] 'agreement among raft nodes before linearized reading' (duration: 203.126351ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:39.318967Z","caller":"traceutil/trace.go:171","msg":"trace[801425253] transaction","detail":"{read_only:false; response_revision:24952; number_of_response:1; }","duration":"503.803971ms","start":"2026-04-07T00:55:38.815133Z","end":"2026-04-07T00:55:39.318937Z","steps":["trace[801425253] 'process raft request' (duration: 503.654498ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:39.319124Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:38.815108Z","time spent":"503.935925ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:39.523362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.883634ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:39.523466Z","caller":"traceutil/trace.go:171","msg":"trace[1200536603] linearizableReadLoop","detail":"{readStateIndex:25774; appliedIndex:25773; }","duration":"420.341163ms","start":"2026-04-07T00:55:39.103109Z","end":"2026-04-07T00:55:39.52345Z","steps":["trace[1200536603] 'read index received' (duration: 216.33479ms)","trace[1200536603] 'applied index is now lower than readState.Index' (duration: 204.005273ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:39.52356Z","caller":"traceutil/trace.go:171","msg":"trace[1090256245] transaction","detail":"{read_only:false; response_revision:24953; number_of_response:1; }","duration":"548.382638ms","start":"2026-04-07T00:55:38.975165Z","end":"2026-04-07T00:55:39.523548Z","steps":["trace[1090256245] 'process raft request' (duration: 393.213706ms)","trace[1090256245] 'compare' (duration: 154.635318ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:39.523608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:38.975133Z","time spent":"548.45328ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":608,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:39.523779Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"420.661741ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:39.52387Z","caller":"traceutil/trace.go:171","msg":"trace[98673846] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24953; }","duration":"420.812314ms","start":"2026-04-07T00:55:39.103037Z","end":"2026-04-07T00:55:39.52385Z","steps":["trace[98673846] 'agreement among raft nodes before linearized reading' (duration: 420.671261ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:39.523894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"155.796166ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumloadbalancerippools/\" range_end:\"/registry/cilium.io/ciliumloadbalancerippools0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"warn","ts":"2026-04-07T00:55:39.523916Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:39.103Z","time spent":"420.907836ms","remote":"127.0.0.1:55804","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-04-07T00:55:39.523924Z","caller":"traceutil/trace.go:171","msg":"trace[1244626026] range","detail":"{range_begin:/registry/cilium.io/ciliumloadbalancerippools/; range_end:/registry/cilium.io/ciliumloadbalancerippools0; response_count:0; response_revision:24953; }","duration":"155.888639ms","start":"2026-04-07T00:55:39.368025Z","end":"2026-04-07T00:55:39.523914Z","steps":["trace[1244626026] 'agreement among raft nodes before linearized reading' (duration: 155.824237ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:39.52392Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.918156ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:557"} {"level":"info","ts":"2026-04-07T00:55:39.523994Z","caller":"traceutil/trace.go:171","msg":"trace[1796770625] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:24953; }","duration":"124.056199ms","start":"2026-04-07T00:55:39.399917Z","end":"2026-04-07T00:55:39.523973Z","steps":["trace[1796770625] 'agreement among raft nodes before linearized reading' (duration: 123.813493ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:39.524126Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.554148ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-07T00:55:39.524293Z","caller":"traceutil/trace.go:171","msg":"trace[54147491] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24953; }","duration":"176.800304ms","start":"2026-04-07T00:55:39.347465Z","end":"2026-04-07T00:55:39.524265Z","steps":["trace[54147491] 'agreement among raft nodes before linearized reading' (duration: 176.249391ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:39.524146Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"123.68427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-04-07T00:55:39.52455Z","caller":"traceutil/trace.go:171","msg":"trace[729601198] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:24953; }","duration":"124.155711ms","start":"2026-04-07T00:55:39.400373Z","end":"2026-04-07T00:55:39.524528Z","steps":["trace[729601198] 'agreement among raft nodes before linearized reading' (duration: 123.474935ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:39.525208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.585197ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-04-07T00:55:39.52533Z","caller":"traceutil/trace.go:171","msg":"trace[1706054032] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:24953; }","duration":"125.781451ms","start":"2026-04-07T00:55:39.399524Z","end":"2026-04-07T00:55:39.525306Z","steps":["trace[1706054032] 'agreement among raft nodes before linearized reading' (duration: 124.318176ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:39.705793Z","caller":"traceutil/trace.go:171","msg":"trace[1993099992] transaction","detail":"{read_only:false; response_revision:24955; number_of_response:1; }","duration":"177.144743ms","start":"2026-04-07T00:55:39.52861Z","end":"2026-04-07T00:55:39.705754Z","steps":["trace[1993099992] 'process raft request' (duration: 138.738965ms)","trace[1993099992] 'compare' (duration: 38.110951ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:39.70622Z","caller":"traceutil/trace.go:171","msg":"trace[329223617] transaction","detail":"{read_only:false; response_revision:24956; number_of_response:1; }","duration":"177.562142ms","start":"2026-04-07T00:55:39.528628Z","end":"2026-04-07T00:55:39.70619Z","steps":["trace[329223617] 'process raft request' (duration: 177.03733ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:39.706335Z","caller":"traceutil/trace.go:171","msg":"trace[2101269800] linearizableReadLoop","detail":"{readStateIndex:25778; appliedIndex:25775; }","duration":"129.815498ms","start":"2026-04-07T00:55:39.576504Z","end":"2026-04-07T00:55:39.706319Z","steps":["trace[2101269800] 'read index received' (duration: 90.861597ms)","trace[2101269800] 'applied index is now lower than readState.Index' (duration: 38.952621ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:39.706428Z","caller":"traceutil/trace.go:171","msg":"trace[826956121] transaction","detail":"{read_only:false; response_revision:24957; number_of_response:1; }","duration":"177.702086ms","start":"2026-04-07T00:55:39.528713Z","end":"2026-04-07T00:55:39.706415Z","steps":["trace[826956121] 'process raft request' (duration: 177.359337ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:39.706723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"130.191218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-07T00:55:39.70681Z","caller":"traceutil/trace.go:171","msg":"trace[2106616711] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:24957; }","duration":"130.339191ms","start":"2026-04-07T00:55:39.576449Z","end":"2026-04-07T00:55:39.706788Z","steps":["trace[2106616711] 'agreement among raft nodes before linearized reading' (duration: 130.067635ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:39.94481Z","caller":"traceutil/trace.go:171","msg":"trace[1325132485] linearizableReadLoop","detail":"{readStateIndex:25779; appliedIndex:25778; }","duration":"238.408784ms","start":"2026-04-07T00:55:39.706372Z","end":"2026-04-07T00:55:39.944781Z","steps":["trace[1325132485] 'read index received' (duration: 238.183959ms)","trace[1325132485] 'applied index is now lower than readState.Index' (duration: 223.045µs)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:39.945034Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.620793ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:39.945086Z","caller":"traceutil/trace.go:171","msg":"trace[1283830286] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/udproutes/; range_end:/registry/gateway.networking.k8s.io/udproutes0; response_count:0; response_revision:24958; }","duration":"349.716366ms","start":"2026-04-07T00:55:39.59535Z","end":"2026-04-07T00:55:39.945067Z","steps":["trace[1283830286] 'agreement among raft nodes before linearized reading' (duration: 349.562522ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:39.94514Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:39.59534Z","time spent":"349.786757ms","remote":"127.0.0.1:53230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":98,"response count":0,"response size":28,"request content":"key:\"/registry/gateway.networking.k8s.io/udproutes/\" range_end:\"/registry/gateway.networking.k8s.io/udproutes0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:55:39.945513Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"285.525653ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/\" range_end:\"/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:39.945564Z","caller":"traceutil/trace.go:171","msg":"trace[1082481859] range","detail":"{range_begin:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates/; range_end:/registry/infrastructure.cluster.x-k8s.io/openstackmachinetemplates0; response_count:0; response_revision:24958; }","duration":"285.574934ms","start":"2026-04-07T00:55:39.659977Z","end":"2026-04-07T00:55:39.945552Z","steps":["trace[1082481859] 'agreement among raft nodes before linearized reading' (duration: 285.507633ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:39.945551Z","caller":"traceutil/trace.go:171","msg":"trace[2128450808] transaction","detail":"{read_only:false; response_revision:24958; number_of_response:1; }","duration":"367.349891ms","start":"2026-04-07T00:55:39.57815Z","end":"2026-04-07T00:55:39.945499Z","steps":["trace[2128450808] 'process raft request' (duration: 366.433399ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:39.94578Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:39.578128Z","time spent":"367.525345ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:40.128502Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.55572ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:40.128608Z","caller":"traceutil/trace.go:171","msg":"trace[13007936] transaction","detail":"{read_only:false; response_revision:24959; number_of_response:1; }","duration":"415.987267ms","start":"2026-04-07T00:55:39.712598Z","end":"2026-04-07T00:55:40.128586Z","steps":["trace[13007936] 'process raft request' (duration: 277.262954ms)","trace[13007936] 'compare' (duration: 138.286283ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:40.12867Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:39.71257Z","time spent":"416.07103ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-04-07T00:55:40.133867Z","caller":"traceutil/trace.go:171","msg":"trace[74198490] transaction","detail":"{read_only:false; response_revision:24960; number_of_response:1; }","duration":"184.662015ms","start":"2026-04-07T00:55:39.949167Z","end":"2026-04-07T00:55:40.133829Z","steps":["trace[74198490] 'process raft request' (duration: 184.420468ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:40.293796Z","caller":"traceutil/trace.go:171","msg":"trace[1631903655] transaction","detail":"{read_only:false; response_revision:24961; number_of_response:1; }","duration":"276.334461ms","start":"2026-04-07T00:55:40.017431Z","end":"2026-04-07T00:55:40.293766Z","steps":["trace[1631903655] 'process raft request' (duration: 258.978482ms)","trace[1631903655] 'compare' (duration: 16.98486ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:40.29396Z","caller":"traceutil/trace.go:171","msg":"trace[470237613] transaction","detail":"{read_only:false; response_revision:24962; number_of_response:1; }","duration":"275.07415ms","start":"2026-04-07T00:55:40.018876Z","end":"2026-04-07T00:55:40.29395Z","steps":["trace[470237613] 'process raft request' (duration: 274.801544ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:40.638629Z","caller":"traceutil/trace.go:171","msg":"trace[827905560] linearizableReadLoop","detail":"{readStateIndex:25784; appliedIndex:25783; }","duration":"203.401798ms","start":"2026-04-07T00:55:40.435188Z","end":"2026-04-07T00:55:40.63859Z","steps":["trace[827905560] 'read index received' (duration: 168.470633ms)","trace[827905560] 'applied index is now lower than readState.Index' (duration: 34.928375ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:40.638942Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"203.718625ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumexternalworkloads/\" range_end:\"/registry/cilium.io/ciliumexternalworkloads0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:40.638991Z","caller":"traceutil/trace.go:171","msg":"trace[36486089] range","detail":"{range_begin:/registry/cilium.io/ciliumexternalworkloads/; range_end:/registry/cilium.io/ciliumexternalworkloads0; response_count:0; response_revision:24963; }","duration":"203.815237ms","start":"2026-04-07T00:55:40.435162Z","end":"2026-04-07T00:55:40.638977Z","steps":["trace[36486089] 'agreement among raft nodes before linearized reading' (duration: 203.563011ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:40.639036Z","caller":"traceutil/trace.go:171","msg":"trace[185291375] transaction","detail":"{read_only:false; response_revision:24963; number_of_response:1; }","duration":"328.069661ms","start":"2026-04-07T00:55:40.31094Z","end":"2026-04-07T00:55:40.639009Z","steps":["trace[185291375] 'process raft request' (duration: 292.806809ms)","trace[185291375] 'compare' (duration: 34.573265ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:40.639176Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:40.310917Z","time spent":"328.187365ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:41.177834Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"171.386384ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:41.177974Z","caller":"traceutil/trace.go:171","msg":"trace[700248602] linearizableReadLoop","detail":"{readStateIndex:25786; appliedIndex:25785; }","duration":"263.376447ms","start":"2026-04-07T00:55:40.914576Z","end":"2026-04-07T00:55:41.177952Z","steps":["trace[700248602] 'read index received' (duration: 91.726367ms)","trace[700248602] 'applied index is now lower than readState.Index' (duration: 171.6483ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:41.178232Z","caller":"traceutil/trace.go:171","msg":"trace[1144951439] transaction","detail":"{read_only:false; response_revision:24965; number_of_response:1; }","duration":"314.848222ms","start":"2026-04-07T00:55:40.863368Z","end":"2026-04-07T00:55:41.178216Z","steps":["trace[1144951439] 'process raft request' (duration: 142.953456ms)","trace[1144951439] 'compare' (duration: 170.692947ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:41.178315Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:40.863348Z","time spent":"314.926924ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":627,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:41.17863Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.051774ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:41.178678Z","caller":"traceutil/trace.go:171","msg":"trace[705550908] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:24965; }","duration":"264.174407ms","start":"2026-04-07T00:55:40.914492Z","end":"2026-04-07T00:55:41.178666Z","steps":["trace[705550908] 'agreement among raft nodes before linearized reading' (duration: 264.084945ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:41.178894Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.598057ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:41.178931Z","caller":"traceutil/trace.go:171","msg":"trace[979456559] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:24965; }","duration":"209.667079ms","start":"2026-04-07T00:55:40.969255Z","end":"2026-04-07T00:55:41.178922Z","steps":["trace[979456559] 'agreement among raft nodes before linearized reading' (duration: 209.606708ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:44.465713Z","caller":"traceutil/trace.go:171","msg":"trace[1750545662] transaction","detail":"{read_only:false; response_revision:24989; number_of_response:1; }","duration":"155.91514ms","start":"2026-04-07T00:55:44.309769Z","end":"2026-04-07T00:55:44.465684Z","steps":["trace[1750545662] 'process raft request' (duration: 155.726815ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:44.8324Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.304181ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:44.833096Z","caller":"traceutil/trace.go:171","msg":"trace[165015895] transaction","detail":"{read_only:false; response_revision:24990; number_of_response:1; }","duration":"520.431412ms","start":"2026-04-07T00:55:44.312624Z","end":"2026-04-07T00:55:44.833056Z","steps":["trace[165015895] 'process raft request' (duration: 322.354443ms)","trace[165015895] 'compare' (duration: 196.477211ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:44.833165Z","caller":"traceutil/trace.go:171","msg":"trace[926938853] transaction","detail":"{read_only:false; response_revision:24991; number_of_response:1; }","duration":"180.622626ms","start":"2026-04-07T00:55:44.652523Z","end":"2026-04-07T00:55:44.833146Z","steps":["trace[926938853] 'process raft request' (duration: 180.540484ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:44.833229Z","caller":"traceutil/trace.go:171","msg":"trace[70551219] linearizableReadLoop","detail":"{readStateIndex:25812; appliedIndex:25811; }","duration":"487.852205ms","start":"2026-04-07T00:55:44.345348Z","end":"2026-04-07T00:55:44.8332Z","steps":["trace[70551219] 'read index received' (duration: 120.373651ms)","trace[70551219] 'applied index is now lower than readState.Index' (duration: 367.475244ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:44.833279Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:44.312596Z","time spent":"520.584166ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:44.833516Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"488.135792ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:55:44.833611Z","caller":"traceutil/trace.go:171","msg":"trace[591090859] range","detail":"{range_begin:/registry/poddisruptionbudgets/; range_end:/registry/poddisruptionbudgets0; response_count:0; response_revision:24991; }","duration":"488.279206ms","start":"2026-04-07T00:55:44.345306Z","end":"2026-04-07T00:55:44.833585Z","steps":["trace[591090859] 'agreement among raft nodes before linearized reading' (duration: 488.027699ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:44.833665Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:44.34529Z","time spent":"488.364837ms","remote":"127.0.0.1:56100","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":20,"response size":30,"request content":"key:\"/registry/poddisruptionbudgets/\" range_end:\"/registry/poddisruptionbudgets0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:55:44.841649Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.557886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets/\" range_end:\"/registry/addons.cluster.x-k8s.io/clusterresourcesets0\" count_only:true ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:44.841714Z","caller":"traceutil/trace.go:171","msg":"trace[75652817] range","detail":"{range_begin:/registry/addons.cluster.x-k8s.io/clusterresourcesets/; range_end:/registry/addons.cluster.x-k8s.io/clusterresourcesets0; response_count:0; response_revision:24992; }","duration":"264.689509ms","start":"2026-04-07T00:55:44.577008Z","end":"2026-04-07T00:55:44.841698Z","steps":["trace[75652817] 'agreement among raft nodes before linearized reading' (duration: 264.529165ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:44.841667Z","caller":"traceutil/trace.go:171","msg":"trace[877970869] transaction","detail":"{read_only:false; response_revision:24992; number_of_response:1; }","duration":"171.420224ms","start":"2026-04-07T00:55:44.670233Z","end":"2026-04-07T00:55:44.841653Z","steps":["trace[877970869] 'process raft request' (duration: 171.175508ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:44.841763Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.413395ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/openstack/rook-ceph-pdbstatemap\" ","response":"range_response_count:1 size:357"} {"level":"info","ts":"2026-04-07T00:55:44.841857Z","caller":"traceutil/trace.go:171","msg":"trace[874739235] range","detail":"{range_begin:/registry/configmaps/openstack/rook-ceph-pdbstatemap; range_end:; response_count:1; response_revision:24992; }","duration":"164.564229ms","start":"2026-04-07T00:55:44.677283Z","end":"2026-04-07T00:55:44.841847Z","steps":["trace[874739235] 'agreement among raft nodes before linearized reading' (duration: 164.385674ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:44.99712Z","caller":"traceutil/trace.go:171","msg":"trace[1293954063] transaction","detail":"{read_only:false; response_revision:24993; number_of_response:1; }","duration":"133.651522ms","start":"2026-04-07T00:55:44.863435Z","end":"2026-04-07T00:55:44.997087Z","steps":["trace[1293954063] 'process raft request' (duration: 132.454622ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:45.272852Z","caller":"traceutil/trace.go:171","msg":"trace[572541815] transaction","detail":"{read_only:false; response_revision:24994; number_of_response:1; }","duration":"191.436739ms","start":"2026-04-07T00:55:45.081365Z","end":"2026-04-07T00:55:45.272802Z","steps":["trace[572541815] 'process raft request' (duration: 188.861626ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:45.572213Z","caller":"traceutil/trace.go:171","msg":"trace[713307402] transaction","detail":"{read_only:false; response_revision:24995; number_of_response:1; }","duration":"112.434689ms","start":"2026-04-07T00:55:45.459746Z","end":"2026-04-07T00:55:45.57218Z","steps":["trace[713307402] 'process raft request' (duration: 112.217783ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:45.789335Z","caller":"traceutil/trace.go:171","msg":"trace[1815358492] linearizableReadLoop","detail":"{readStateIndex:25818; appliedIndex:25817; }","duration":"189.811889ms","start":"2026-04-07T00:55:45.59949Z","end":"2026-04-07T00:55:45.789302Z","steps":["trace[1815358492] 'read index received' (duration: 189.480841ms)","trace[1815358492] 'applied index is now lower than readState.Index' (duration: 328.998µs)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:45.789628Z","caller":"traceutil/trace.go:171","msg":"trace[1447485061] transaction","detail":"{read_only:false; response_revision:24996; number_of_response:1; }","duration":"234.294335ms","start":"2026-04-07T00:55:45.555302Z","end":"2026-04-07T00:55:45.789596Z","steps":["trace[1447485061] 'process raft request' (duration: 233.752842ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:45.789728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.218958ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:441"} {"level":"info","ts":"2026-04-07T00:55:45.789817Z","caller":"traceutil/trace.go:171","msg":"trace[797137496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:24996; }","duration":"190.371612ms","start":"2026-04-07T00:55:45.599428Z","end":"2026-04-07T00:55:45.7898Z","steps":["trace[797137496] 'agreement among raft nodes before linearized reading' (duration: 190.126046ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:46.087362Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"124.059179ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:46.087613Z","caller":"traceutil/trace.go:171","msg":"trace[1810867527] transaction","detail":"{read_only:false; response_revision:24998; number_of_response:1; }","duration":"292.130264ms","start":"2026-04-07T00:55:45.795448Z","end":"2026-04-07T00:55:46.087578Z","steps":["trace[1810867527] 'process raft request' (duration: 167.730466ms)","trace[1810867527] 'compare' (duration: 123.824323ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:46.087646Z","caller":"traceutil/trace.go:171","msg":"trace[711478033] transaction","detail":"{read_only:false; response_revision:24999; number_of_response:1; }","duration":"292.129833ms","start":"2026-04-07T00:55:45.795495Z","end":"2026-04-07T00:55:46.087625Z","steps":["trace[711478033] 'process raft request' (duration: 291.977409ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:46.087779Z","caller":"traceutil/trace.go:171","msg":"trace[1129440494] linearizableReadLoop","detail":"{readStateIndex:25822; appliedIndex:25818; }","duration":"290.434932ms","start":"2026-04-07T00:55:45.797314Z","end":"2026-04-07T00:55:46.087749Z","steps":["trace[1129440494] 'read index received' (duration: 19.145142ms)","trace[1129440494] 'applied index is now lower than readState.Index' (duration: 271.28706ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:46.087816Z","caller":"traceutil/trace.go:171","msg":"trace[1291033016] transaction","detail":"{read_only:false; response_revision:25000; number_of_response:1; }","duration":"291.294423ms","start":"2026-04-07T00:55:45.796499Z","end":"2026-04-07T00:55:46.087793Z","steps":["trace[1291033016] 'process raft request' (duration: 291.078908ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:46.087987Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.644766ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/magnum-system/\" range_end:\"/registry/services/specs/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:46.088079Z","caller":"traceutil/trace.go:171","msg":"trace[1801364120] range","detail":"{range_begin:/registry/services/specs/magnum-system/; range_end:/registry/services/specs/magnum-system0; response_count:0; response_revision:25000; }","duration":"290.761769ms","start":"2026-04-07T00:55:45.797298Z","end":"2026-04-07T00:55:46.088059Z","steps":["trace[1801364120] 'agreement among raft nodes before linearized reading' (duration: 290.568435ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:46.392321Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.16993ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:46.392544Z","caller":"traceutil/trace.go:171","msg":"trace[9295160] transaction","detail":"{read_only:false; response_revision:25003; number_of_response:1; }","duration":"198.888179ms","start":"2026-04-07T00:55:46.193639Z","end":"2026-04-07T00:55:46.392527Z","steps":["trace[9295160] 'process raft request' (duration: 198.852758ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:46.392686Z","caller":"traceutil/trace.go:171","msg":"trace[948260308] linearizableReadLoop","detail":"{readStateIndex:25823; appliedIndex:25822; }","duration":"304.79154ms","start":"2026-04-07T00:55:46.087859Z","end":"2026-04-07T00:55:46.392651Z","steps":["trace[948260308] 'read index received' (duration: 166.323812ms)","trace[948260308] 'applied index is now lower than readState.Index' (duration: 138.463878ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:46.392817Z","caller":"traceutil/trace.go:171","msg":"trace[196396468] transaction","detail":"{read_only:false; response_revision:25002; number_of_response:1; }","duration":"228.566646ms","start":"2026-04-07T00:55:46.164236Z","end":"2026-04-07T00:55:46.392803Z","steps":["trace[196396468] 'process raft request' (duration: 228.198868ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:46.392828Z","caller":"traceutil/trace.go:171","msg":"trace[770364760] transaction","detail":"{read_only:false; response_revision:25001; number_of_response:1; }","duration":"405.937896ms","start":"2026-04-07T00:55:45.986876Z","end":"2026-04-07T00:55:46.392814Z","steps":["trace[770364760] 'process raft request' (duration: 267.171081ms)","trace[770364760] 'compare' (duration: 137.869672ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:46.392955Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:45.986851Z","time spent":"405.998076ms","remote":"127.0.0.1:56040","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":523,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-04-07T00:55:46.393188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.287698ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-04-07T00:55:46.393234Z","caller":"traceutil/trace.go:171","msg":"trace[1311473091] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:25003; }","duration":"364.37461ms","start":"2026-04-07T00:55:46.02885Z","end":"2026-04-07T00:55:46.393225Z","steps":["trace[1311473091] 'agreement among raft nodes before linearized reading' (duration: 364.285118ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:46.393263Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-04-07T00:55:46.028832Z","time spent":"364.425521ms","remote":"127.0.0.1:42172","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":30,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-04-07T00:55:46.393396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.230371ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/magnum-system/\" range_end:\"/registry/endpointslices/magnum-system0\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:46.393419Z","caller":"traceutil/trace.go:171","msg":"trace[663798030] range","detail":"{range_begin:/registry/endpointslices/magnum-system/; range_end:/registry/endpointslices/magnum-system0; response_count:0; response_revision:25003; }","duration":"296.288823ms","start":"2026-04-07T00:55:46.097122Z","end":"2026-04-07T00:55:46.393411Z","steps":["trace[663798030] 'agreement among raft nodes before linearized reading' (duration: 296.245922ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:46.393693Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.525682ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12213"} {"level":"info","ts":"2026-04-07T00:55:46.393906Z","caller":"traceutil/trace.go:171","msg":"trace[704745464] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:25003; }","duration":"182.604625ms","start":"2026-04-07T00:55:46.211104Z","end":"2026-04-07T00:55:46.393709Z","steps":["trace[704745464] 'agreement among raft nodes before linearized reading' (duration: 182.454571ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:46.393934Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"226.803313ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:494"} {"level":"info","ts":"2026-04-07T00:55:46.393967Z","caller":"traceutil/trace.go:171","msg":"trace[664899219] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:25003; }","duration":"226.879334ms","start":"2026-04-07T00:55:46.167079Z","end":"2026-04-07T00:55:46.393958Z","steps":["trace[664899219] 'agreement among raft nodes before linearized reading' (duration: 226.559277ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:46.394081Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.827266ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:46.394166Z","caller":"traceutil/trace.go:171","msg":"trace[950755992] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:25003; }","duration":"296.96483ms","start":"2026-04-07T00:55:46.097181Z","end":"2026-04-07T00:55:46.394146Z","steps":["trace[950755992] 'agreement among raft nodes before linearized reading' (duration: 296.815275ms)"],"step_count":1} {"level":"warn","ts":"2026-04-07T00:55:46.751813Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"179.131151ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"info","ts":"2026-04-07T00:55:46.751966Z","caller":"traceutil/trace.go:171","msg":"trace[316790328] linearizableReadLoop","detail":"{readStateIndex:25827; appliedIndex:25826; }","duration":"208.702256ms","start":"2026-04-07T00:55:46.543239Z","end":"2026-04-07T00:55:46.751941Z","steps":["trace[316790328] 'read index received' (duration: 29.313129ms)","trace[316790328] 'applied index is now lower than readState.Index' (duration: 179.386777ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:46.752015Z","caller":"traceutil/trace.go:171","msg":"trace[826828517] transaction","detail":"{read_only:false; response_revision:25005; number_of_response:1; }","duration":"278.318228ms","start":"2026-04-07T00:55:46.473642Z","end":"2026-04-07T00:55:46.75196Z","steps":["trace[826828517] 'process raft request' (duration: 98.898661ms)","trace[826828517] 'compare' (duration: 178.809953ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:46.752129Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"208.914481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:6"} {"level":"info","ts":"2026-04-07T00:55:46.752171Z","caller":"traceutil/trace.go:171","msg":"trace[619154115] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:25005; }","duration":"209.003753ms","start":"2026-04-07T00:55:46.543155Z","end":"2026-04-07T00:55:46.752159Z","steps":["trace[619154115] 'agreement among raft nodes before linearized reading' (duration: 208.917481ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:50.570939Z","caller":"traceutil/trace.go:171","msg":"trace[1438196595] linearizableReadLoop","detail":"{readStateIndex:25854; appliedIndex:25853; }","duration":"143.90621ms","start":"2026-04-07T00:55:50.426995Z","end":"2026-04-07T00:55:50.570901Z","steps":["trace[1438196595] 'read index received' (duration: 49.746592ms)","trace[1438196595] 'applied index is now lower than readState.Index' (duration: 94.157868ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:55:50.571002Z","caller":"traceutil/trace.go:171","msg":"trace[1732978663] transaction","detail":"{read_only:false; response_revision:25031; number_of_response:1; }","duration":"154.291891ms","start":"2026-04-07T00:55:50.416665Z","end":"2026-04-07T00:55:50.570957Z","steps":["trace[1732978663] 'process raft request' (duration: 71.679484ms)","trace[1732978663] 'compare' (duration: 82.221267ms)"],"step_count":2} {"level":"warn","ts":"2026-04-07T00:55:50.571229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.202387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:562"} {"level":"info","ts":"2026-04-07T00:55:50.571313Z","caller":"traceutil/trace.go:171","msg":"trace[1687842469] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:25031; }","duration":"144.33956ms","start":"2026-04-07T00:55:50.426952Z","end":"2026-04-07T00:55:50.571291Z","steps":["trace[1687842469] 'agreement among raft nodes before linearized reading' (duration: 144.076084ms)"],"step_count":1} {"level":"info","ts":"2026-04-07T00:55:50.765221Z","caller":"traceutil/trace.go:171","msg":"trace[829234637] transaction","detail":"{read_only:false; response_revision:25033; number_of_response:1; }","duration":"188.501028ms","start":"2026-04-07T00:55:50.576683Z","end":"2026-04-07T00:55:50.765184Z","steps":["trace[829234637] 'process raft request' (duration: 143.761966ms)","trace[829234637] 'compare' (duration: 44.570707ms)"],"step_count":2} {"level":"info","ts":"2026-04-07T00:58:00.1638Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":23435} {"level":"info","ts":"2026-04-07T00:58:00.312365Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":23435,"took":"142.043274ms","hash":866571637,"current-db-size-bytes":58683392,"current-db-size":"59 MB","current-db-size-in-use-bytes":36347904,"current-db-size-in-use":"36 MB"} {"level":"info","ts":"2026-04-07T00:58:00.312443Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":866571637,"revision":23435,"compact-revision":20330} {"level":"info","ts":"2026-04-07T00:58:02.06539Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000002-0000000000006a27.wal"} {"level":"info","ts":"2026-04-07T01:03:00.171322Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":26291} {"level":"info","ts":"2026-04-07T01:03:00.338336Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":26291,"took":"160.884271ms","hash":2210862525,"current-db-size-bytes":59105280,"current-db-size":"59 MB","current-db-size-in-use-bytes":32985088,"current-db-size-in-use":"33 MB"} {"level":"info","ts":"2026-04-07T01:03:00.338412Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2210862525,"revision":26291,"compact-revision":23435} {"level":"info","ts":"2026-04-07T01:03:20.800409Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"329f95029c2a91ed","local-member-applied-index":30003,"local-member-snapshot-index":20002,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T01:03:20.805688Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":30003} {"level":"info","ts":"2026-04-07T01:03:20.805816Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":25003} {"level":"info","ts":"2026-04-07T01:08:00.178074Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":28917} {"level":"info","ts":"2026-04-07T01:08:00.318045Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":28917,"took":"136.895491ms","hash":2456570772,"current-db-size-bytes":59105280,"current-db-size":"59 MB","current-db-size-in-use-bytes":30023680,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-07T01:08:00.318127Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2456570772,"revision":28917,"compact-revision":26291} {"level":"info","ts":"2026-04-07T01:13:00.185214Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":31173} {"level":"info","ts":"2026-04-07T01:13:00.340204Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":31173,"took":"148.637343ms","hash":549670916,"current-db-size-bytes":59105280,"current-db-size":"59 MB","current-db-size-in-use-bytes":30224384,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-04-07T01:13:00.340304Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":549670916,"revision":31173,"compact-revision":28917} {"level":"info","ts":"2026-04-07T01:18:00.191466Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":33470} {"level":"info","ts":"2026-04-07T01:18:00.342179Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":33470,"took":"146.207825ms","hash":1474308308,"current-db-size-bytes":60567552,"current-db-size":"61 MB","current-db-size-in-use-bytes":32317440,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-04-07T01:18:00.342272Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1474308308,"revision":33470,"compact-revision":31173} {"level":"info","ts":"2026-04-07T01:23:00.205401Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":35732} {"level":"info","ts":"2026-04-07T01:23:00.340273Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":35732,"took":"131.045919ms","hash":3516292714,"current-db-size-bytes":61673472,"current-db-size":"62 MB","current-db-size-in-use-bytes":30859264,"current-db-size-in-use":"31 MB"} {"level":"info","ts":"2026-04-07T01:23:00.340344Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3516292714,"revision":35732,"compact-revision":33470} {"level":"info","ts":"2026-04-07T01:24:47.283485Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"329f95029c2a91ed","local-member-applied-index":40004,"local-member-snapshot-index":30003,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-04-07T01:24:47.290256Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":40004} {"level":"info","ts":"2026-04-07T01:24:47.290435Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":35004}