{"level":"warn","ts":"2026-05-05T16:49:50.713976Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T16:49:50.714074Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.157:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.157:2380","--initial-cluster=instance=https://199.204.45.157:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.157:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.157: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-05-05T16:49:50.714147Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-05-05T16:49:50.714155Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-05T16:49:50.714172Z","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-05-05T16:49:50.714897Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"]} {"level":"info","ts":"2026-05-05T16:49:50.714974Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.157: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-05-05T16:49:50.720141Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.952644ms"} {"level":"info","ts":"2026-05-05T16:49:50.729333Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5f170c75a1d97cc","cluster-id":"44a3176ec85feb9"} {"level":"info","ts":"2026-05-05T16:49:50.729425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=()"} {"level":"info","ts":"2026-05-05T16:49:50.729451Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 0"} {"level":"info","ts":"2026-05-05T16:49:50.729462Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5f170c75a1d97cc [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-05-05T16:49:50.72948Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became follower at term 1"} {"level":"info","ts":"2026-05-05T16:49:50.729536Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"warn","ts":"2026-05-05T16:49:50.740535Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-05-05T16:49:50.743515Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-05-05T16:49:50.745799Z","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-05-05T16:49:50.749587Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5f170c75a1d97cc","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-05-05T16:49:50.750691Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5f170c75a1d97cc","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-05-05T16:49:50.751073Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc switched to configuration voters=(428247441089599436)"} {"level":"info","ts":"2026-05-05T16:49:50.751236Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","added-peer-id":"5f170c75a1d97cc","added-peer-peer-urls":["https://199.204.45.157:2380"]} {"level":"info","ts":"2026-05-05T16:49:50.751397Z","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-05-05T16:49:50.75145Z","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-05-05T16:49:50.751462Z","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-05-05T16:49:50.75575Z","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-05-05T16:49:50.756004Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5f170c75a1d97cc","initial-advertise-peer-urls":["https://199.204.45.157:2380"],"listen-peer-urls":["https://199.204.45.157:2380"],"advertise-client-urls":["https://199.204.45.157:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.157:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-05-05T16:49:50.75604Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-05-05T16:49:50.756037Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-05T16:49:50.756186Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.157:2380"} {"level":"info","ts":"2026-05-05T16:49:50.830693Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc is starting a new election at term 1"} {"level":"info","ts":"2026-05-05T16:49:50.830755Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became pre-candidate at term 1"} {"level":"info","ts":"2026-05-05T16:49:50.830795Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgPreVoteResp from 5f170c75a1d97cc at term 1"} {"level":"info","ts":"2026-05-05T16:49:50.83081Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became candidate at term 2"} {"level":"info","ts":"2026-05-05T16:49:50.830821Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc received MsgVoteResp from 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-05T16:49:50.830833Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5f170c75a1d97cc became leader at term 2"} {"level":"info","ts":"2026-05-05T16:49:50.830847Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5f170c75a1d97cc elected leader 5f170c75a1d97cc at term 2"} {"level":"info","ts":"2026-05-05T16:49:52.416288Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"warn","ts":"2026-05-05T16:49:52.416636Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.287326129s","expected-duration":"100ms","prefix":"","request":"ID:10938291287945808129 Method:\"PUT\" Path:\"/0/members/5f170c75a1d97cc/attributes\" Val:\"{\\\"name\\\":\\\"instance\\\",\\\"clientURLs\\\":[\\\"https://199.204.45.157:2379\\\"]}\" ","response":""} {"level":"info","ts":"2026-05-05T16:49:52.4168Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T16:49:52.416848Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-05-05T16:49:52.416798Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5f170c75a1d97cc","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.157:2379]}","request-path":"/0/members/5f170c75a1d97cc/attributes","cluster-id":"44a3176ec85feb9","publish-timeout":"7s"} {"level":"info","ts":"2026-05-05T16:49:52.417697Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-05-05T16:49:52.417832Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-05-05T16:49:52.420089Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.157:2379"} {"level":"info","ts":"2026-05-05T16:49:52.421064Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-05-05T16:49:52.697703Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"44a3176ec85feb9","local-member-id":"5f170c75a1d97cc","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:49:52.697951Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:49:52.698063Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-05-05T16:49:52.698064Z","caller":"traceutil/trace.go:171","msg":"trace[909678425] linearizableReadLoop","detail":"{readStateIndex:4; appliedIndex:3; }","duration":"236.725178ms","start":"2026-05-05T16:49:52.461316Z","end":"2026-05-05T16:49:52.698041Z","steps":["trace[909678425] 'read index received' (duration: 236.349584ms)","trace[909678425] 'applied index is now lower than readState.Index' (duration: 373.324µs)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:49:52.698211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.857076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.698246Z","caller":"traceutil/trace.go:171","msg":"trace[497467112] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"236.92595ms","start":"2026-05-05T16:49:52.461309Z","end":"2026-05-05T16:49:52.698235Z","steps":["trace[497467112] 'agreement among raft nodes before linearized reading' (duration: 236.806383ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.698274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.722127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/\" range_end:\"/registry/apiextensions.k8s.io/customresourcedefinitions0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.698315Z","caller":"traceutil/trace.go:171","msg":"trace[642668582] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/; range_end:/registry/apiextensions.k8s.io/customresourcedefinitions0; response_count:0; response_revision:1; }","duration":"236.907669ms","start":"2026-05-05T16:49:52.461394Z","end":"2026-05-05T16:49:52.698301Z","steps":["trace[642668582] 'agreement among raft nodes before linearized reading' (duration: 236.785702ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.698769Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.786902ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.698806Z","caller":"traceutil/trace.go:171","msg":"trace[1296817287] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"117.880558ms","start":"2026-05-05T16:49:52.580914Z","end":"2026-05-05T16:49:52.698794Z","steps":["trace[1296817287] 'agreement among raft nodes before linearized reading' (duration: 117.820645ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.698827Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.9046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.698861Z","caller":"traceutil/trace.go:171","msg":"trace[1029121416] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1; }","duration":"117.967594ms","start":"2026-05-05T16:49:52.580884Z","end":"2026-05-05T16:49:52.698851Z","steps":["trace[1029121416] 'agreement among raft nodes before linearized reading' (duration: 117.915021ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.698963Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.304524ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.698987Z","caller":"traceutil/trace.go:171","msg":"trace[1488709344] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"132.369807ms","start":"2026-05-05T16:49:52.56661Z","end":"2026-05-05T16:49:52.69898Z","steps":["trace[1488709344] 'agreement among raft nodes before linearized reading' (duration: 132.330905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.699128Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.507417ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.699157Z","caller":"traceutil/trace.go:171","msg":"trace[851642644] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:1; }","duration":"132.559951ms","start":"2026-05-05T16:49:52.566586Z","end":"2026-05-05T16:49:52.699146Z","steps":["trace[851642644] 'agreement among raft nodes before linearized reading' (duration: 132.517498ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.69927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.615535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.699293Z","caller":"traceutil/trace.go:171","msg":"trace[1171091697] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"144.640376ms","start":"2026-05-05T16:49:52.554646Z","end":"2026-05-05T16:49:52.699286Z","steps":["trace[1171091697] 'agreement among raft nodes before linearized reading' (duration: 144.600084ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.699433Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.809427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.699458Z","caller":"traceutil/trace.go:171","msg":"trace[174106284] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:1; }","duration":"144.84893ms","start":"2026-05-05T16:49:52.554602Z","end":"2026-05-05T16:49:52.699451Z","steps":["trace[174106284] 'agreement among raft nodes before linearized reading' (duration: 144.811417ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.699575Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"156.928076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.699599Z","caller":"traceutil/trace.go:171","msg":"trace[2132932294] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"156.962928ms","start":"2026-05-05T16:49:52.542629Z","end":"2026-05-05T16:49:52.699591Z","steps":["trace[2132932294] 'agreement among raft nodes before linearized reading' (duration: 156.925085ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.699745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.123948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.69977Z","caller":"traceutil/trace.go:171","msg":"trace[1512129012] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:1; }","duration":"157.16226ms","start":"2026-05-05T16:49:52.5426Z","end":"2026-05-05T16:49:52.699762Z","steps":["trace[1512129012] 'agreement among raft nodes before linearized reading' (duration: 157.123338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.69989Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.642258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.699914Z","caller":"traceutil/trace.go:171","msg":"trace[1860594027] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"168.676621ms","start":"2026-05-05T16:49:52.53123Z","end":"2026-05-05T16:49:52.699907Z","steps":["trace[1860594027] 'agreement among raft nodes before linearized reading' (duration: 168.639458ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.699984Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.864387ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.700019Z","caller":"traceutil/trace.go:171","msg":"trace[961108085] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"105.92154ms","start":"2026-05-05T16:49:52.594088Z","end":"2026-05-05T16:49:52.700009Z","steps":["trace[961108085] 'agreement among raft nodes before linearized reading' (duration: 105.865857ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.700084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.855292ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/\" range_end:\"/registry/secrets0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.700112Z","caller":"traceutil/trace.go:171","msg":"trace[797454106] range","detail":"{range_begin:/registry/secrets/; range_end:/registry/secrets0; response_count:0; response_revision:1; }","duration":"168.901575ms","start":"2026-05-05T16:49:52.531203Z","end":"2026-05-05T16:49:52.700105Z","steps":["trace[797454106] 'agreement among raft nodes before linearized reading' (duration: 168.851542ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.700147Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.067979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.700175Z","caller":"traceutil/trace.go:171","msg":"trace[1592612444] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:1; }","duration":"106.122663ms","start":"2026-05-05T16:49:52.594045Z","end":"2026-05-05T16:49:52.700167Z","steps":["trace[1592612444] 'agreement among raft nodes before linearized reading' (duration: 106.07982ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.700218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.85191ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.700244Z","caller":"traceutil/trace.go:171","msg":"trace[1453527764] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"182.887342ms","start":"2026-05-05T16:49:52.51735Z","end":"2026-05-05T16:49:52.700237Z","steps":["trace[1453527764] 'agreement among raft nodes before linearized reading' (duration: 182.84896ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.700384Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.106946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.700412Z","caller":"traceutil/trace.go:171","msg":"trace[1782651800] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:1; }","duration":"183.149529ms","start":"2026-05-05T16:49:52.517252Z","end":"2026-05-05T16:49:52.700402Z","steps":["trace[1782651800] 'agreement among raft nodes before linearized reading' (duration: 183.109066ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.700544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"197.673461ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.700575Z","caller":"traceutil/trace.go:171","msg":"trace[1706152491] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:1; }","duration":"197.745085ms","start":"2026-05-05T16:49:52.502823Z","end":"2026-05-05T16:49:52.700568Z","steps":["trace[1706152491] 'agreement among raft nodes before linearized reading' (duration: 197.701883ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.877602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.611427ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T16:49:52.877628Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.377102ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.877753Z","caller":"traceutil/trace.go:171","msg":"trace[638220143] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"113.534592ms","start":"2026-05-05T16:49:52.764205Z","end":"2026-05-05T16:49:52.87774Z","steps":["trace[638220143] 'count revisions from in-memory index tree' (duration: 113.348461ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:49:52.877677Z","caller":"traceutil/trace.go:171","msg":"trace[1861662938] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"113.739415ms","start":"2026-05-05T16:49:52.763922Z","end":"2026-05-05T16:49:52.877661Z","steps":["trace[1861662938] 'range keys from in-memory index tree' (duration: 113.492529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:52.878153Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.946076ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" limit:10000 ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-05-05T16:49:52.878211Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.759155ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:52.878266Z","caller":"traceutil/trace.go:171","msg":"trace[261195401] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"127.834509ms","start":"2026-05-05T16:49:52.750419Z","end":"2026-05-05T16:49:52.878253Z","steps":["trace[261195401] 'count revisions from in-memory index tree' (duration: 127.715382ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:49:52.878197Z","caller":"traceutil/trace.go:171","msg":"trace[1102421644] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:1; }","duration":"127.993839ms","start":"2026-05-05T16:49:52.750191Z","end":"2026-05-05T16:49:52.878185Z","steps":["trace[1102421644] 'range keys from in-memory index tree' (duration: 127.898264ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:49:53.875736Z","caller":"traceutil/trace.go:171","msg":"trace[1551838155] transaction","detail":"{read_only:false; response_revision:2; number_of_response:1; }","duration":"114.81533ms","start":"2026-05-05T16:49:53.760899Z","end":"2026-05-05T16:49:53.875714Z","steps":["trace[1551838155] 'process raft request' (duration: 114.633888ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:49:53.930897Z","caller":"traceutil/trace.go:171","msg":"trace[876164016] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"169.789975ms","start":"2026-05-05T16:49:53.76109Z","end":"2026-05-05T16:49:53.93088Z","steps":["trace[876164016] 'process raft request' (duration: 169.318606ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:49:53.931298Z","caller":"traceutil/trace.go:171","msg":"trace[562421331] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"170.119485ms","start":"2026-05-05T16:49:53.761086Z","end":"2026-05-05T16:49:53.931205Z","steps":["trace[562421331] 'process raft request' (duration: 169.71874ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:49:53.931626Z","caller":"traceutil/trace.go:171","msg":"trace[1751300929] linearizableReadLoop","detail":"{readStateIndex:7; appliedIndex:4; }","duration":"168.56737ms","start":"2026-05-05T16:49:53.762971Z","end":"2026-05-05T16:49:53.931538Z","steps":["trace[1751300929] 'read index received' (duration: 112.54418ms)","trace[1751300929] 'applied index is now lower than readState.Index' (duration: 56.0219ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:49:53.93198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.992558ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-05-05T16:49:53.933913Z","caller":"traceutil/trace.go:171","msg":"trace[1853109416] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"170.945047ms","start":"2026-05-05T16:49:53.762946Z","end":"2026-05-05T16:49:53.933891Z","steps":["trace[1853109416] 'agreement among raft nodes before linearized reading' (duration: 168.929954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:53.932025Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.921253ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3171"} {"level":"info","ts":"2026-05-05T16:49:53.934189Z","caller":"traceutil/trace.go:171","msg":"trace[1896709274] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:11; }","duration":"171.109967ms","start":"2026-05-05T16:49:53.763066Z","end":"2026-05-05T16:49:53.934176Z","steps":["trace[1896709274] 'agreement among raft nodes before linearized reading' (duration: 168.822437ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:49:53.93272Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.223498ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-05-05T16:49:53.934425Z","caller":"traceutil/trace.go:171","msg":"trace[1067583149] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"118.986026ms","start":"2026-05-05T16:49:53.815426Z","end":"2026-05-05T16:49:53.934412Z","steps":["trace[1067583149] 'agreement among raft nodes before linearized reading' (duration: 117.234839ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:02.524782Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.363302ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:50:02.524965Z","caller":"traceutil/trace.go:171","msg":"trace[1345519018] transaction","detail":"{read_only:false; response_revision:360; number_of_response:1; }","duration":"218.218241ms","start":"2026-05-05T16:50:02.306691Z","end":"2026-05-05T16:50:02.52491Z","steps":["trace[1345519018] 'process raft request' (duration: 23.153238ms)","trace[1345519018] 'compare' (duration: 194.166513ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:50:02.52518Z","caller":"traceutil/trace.go:171","msg":"trace[655604958] transaction","detail":"{read_only:false; response_revision:361; number_of_response:1; }","duration":"209.310714ms","start":"2026-05-05T16:50:02.315861Z","end":"2026-05-05T16:50:02.525172Z","steps":["trace[655604958] 'process raft request' (duration: 209.061733ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:02.525279Z","caller":"traceutil/trace.go:171","msg":"trace[399369395] transaction","detail":"{read_only:false; response_revision:365; number_of_response:1; }","duration":"202.148447ms","start":"2026-05-05T16:50:02.323113Z","end":"2026-05-05T16:50:02.525262Z","steps":["trace[399369395] 'process raft request' (duration: 202.094105ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:02.5253Z","caller":"traceutil/trace.go:171","msg":"trace[849795729] transaction","detail":"{read_only:false; response_revision:362; number_of_response:1; }","duration":"208.558989ms","start":"2026-05-05T16:50:02.316683Z","end":"2026-05-05T16:50:02.525242Z","steps":["trace[849795729] 'process raft request' (duration: 208.330529ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:02.525476Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"212.05446ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T16:50:02.525559Z","caller":"traceutil/trace.go:171","msg":"trace[14851088] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:365; }","duration":"212.170595ms","start":"2026-05-05T16:50:02.313378Z","end":"2026-05-05T16:50:02.525549Z","steps":["trace[14851088] 'agreement among raft nodes before linearized reading' (duration: 212.017628ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:02.525573Z","caller":"traceutil/trace.go:171","msg":"trace[2054824786] transaction","detail":"{read_only:false; response_revision:364; number_of_response:1; }","duration":"202.753265ms","start":"2026-05-05T16:50:02.322804Z","end":"2026-05-05T16:50:02.525557Z","steps":["trace[2054824786] 'process raft request' (duration: 202.348046ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:02.525598Z","caller":"traceutil/trace.go:171","msg":"trace[967212674] transaction","detail":"{read_only:false; response_revision:363; number_of_response:1; }","duration":"202.765255ms","start":"2026-05-05T16:50:02.322804Z","end":"2026-05-05T16:50:02.525569Z","steps":["trace[967212674] 'process raft request' (duration: 202.290664ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:02.525372Z","caller":"traceutil/trace.go:171","msg":"trace[1432384680] linearizableReadLoop","detail":"{readStateIndex:369; appliedIndex:368; }","duration":"211.953406ms","start":"2026-05-05T16:50:02.313408Z","end":"2026-05-05T16:50:02.525361Z","steps":["trace[1432384680] 'read index received' (duration: 16.450032ms)","trace[1432384680] 'applied index is now lower than readState.Index' (duration: 195.502264ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:50:02.526162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.190934ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/daemon-set-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-05T16:50:02.526235Z","caller":"traceutil/trace.go:171","msg":"trace[1558540077] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/daemon-set-controller; range_end:; response_count:1; response_revision:365; }","duration":"175.30864ms","start":"2026-05-05T16:50:02.350912Z","end":"2026-05-05T16:50:02.52622Z","steps":["trace[1558540077] 'agreement among raft nodes before linearized reading' (duration: 175.197925ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:02.526451Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.465722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/replicaset-controller\" ","response":"range_response_count:1 size:207"} {"level":"info","ts":"2026-05-05T16:50:02.527544Z","caller":"traceutil/trace.go:171","msg":"trace[78116842] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/replicaset-controller; range_end:; response_count:1; response_revision:365; }","duration":"126.607546ms","start":"2026-05-05T16:50:02.400908Z","end":"2026-05-05T16:50:02.527516Z","steps":["trace[78116842] 'agreement among raft nodes before linearized reading' (duration: 125.285035ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:02.738514Z","caller":"traceutil/trace.go:171","msg":"trace[1192095382] transaction","detail":"{read_only:false; response_revision:371; number_of_response:1; }","duration":"148.107028ms","start":"2026-05-05T16:50:02.590371Z","end":"2026-05-05T16:50:02.738479Z","steps":["trace[1192095382] 'process raft request' (duration: 120.305238ms)","trace[1192095382] 'compare' (duration: 27.486096ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:50:02.738732Z","caller":"traceutil/trace.go:171","msg":"trace[1750528777] transaction","detail":"{read_only:false; response_revision:372; number_of_response:1; }","duration":"148.194181ms","start":"2026-05-05T16:50:02.590528Z","end":"2026-05-05T16:50:02.738722Z","steps":["trace[1750528777] 'process raft request' (duration: 147.856196ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:02.738884Z","caller":"traceutil/trace.go:171","msg":"trace[904965436] transaction","detail":"{read_only:false; response_revision:373; number_of_response:1; }","duration":"147.321021ms","start":"2026-05-05T16:50:02.591551Z","end":"2026-05-05T16:50:02.738872Z","steps":["trace[904965436] 'process raft request' (duration: 146.873811ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:02.916558Z","caller":"traceutil/trace.go:171","msg":"trace[1771122465] transaction","detail":"{read_only:false; response_revision:376; number_of_response:1; }","duration":"170.416277ms","start":"2026-05-05T16:50:02.746112Z","end":"2026-05-05T16:50:02.916528Z","steps":["trace[1771122465] 'process raft request' (duration: 146.020312ms)","trace[1771122465] 'compare' (duration: 24.050049ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:50:02.917006Z","caller":"traceutil/trace.go:171","msg":"trace[1593292630] transaction","detail":"{read_only:false; response_revision:378; number_of_response:1; }","duration":"170.726352ms","start":"2026-05-05T16:50:02.74624Z","end":"2026-05-05T16:50:02.916966Z","steps":["trace[1593292630] 'process raft request' (duration: 170.388097ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:02.916994Z","caller":"traceutil/trace.go:171","msg":"trace[2138420431] transaction","detail":"{read_only:false; response_revision:377; number_of_response:1; }","duration":"170.759263ms","start":"2026-05-05T16:50:02.746184Z","end":"2026-05-05T16:50:02.916943Z","steps":["trace[2138420431] 'process raft request' (duration: 170.313003ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:02.917038Z","caller":"traceutil/trace.go:171","msg":"trace[24648757] transaction","detail":"{read_only:false; response_revision:380; number_of_response:1; }","duration":"170.727832ms","start":"2026-05-05T16:50:02.746283Z","end":"2026-05-05T16:50:02.91701Z","steps":["trace[24648757] 'process raft request' (duration: 170.599656ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:02.917034Z","caller":"traceutil/trace.go:171","msg":"trace[1605753159] transaction","detail":"{read_only:false; response_revision:379; number_of_response:1; }","duration":"170.743683ms","start":"2026-05-05T16:50:02.746237Z","end":"2026-05-05T16:50:02.916981Z","steps":["trace[1605753159] 'process raft request' (duration: 170.446659ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:02.917319Z","caller":"traceutil/trace.go:171","msg":"trace[542231664] linearizableReadLoop","detail":"{readStateIndex:391; appliedIndex:382; }","duration":"144.100544ms","start":"2026-05-05T16:50:02.773203Z","end":"2026-05-05T16:50:02.917303Z","steps":["trace[542231664] 'read index received' (duration: 54.092601ms)","trace[542231664] 'applied index is now lower than readState.Index' (duration: 90.006623ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:50:02.917386Z","caller":"traceutil/trace.go:171","msg":"trace[2072559501] transaction","detail":"{read_only:false; response_revision:382; number_of_response:1; }","duration":"163.444009ms","start":"2026-05-05T16:50:02.753897Z","end":"2026-05-05T16:50:02.917341Z","steps":["trace[2072559501] 'process raft request' (duration: 163.305033ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:02.917447Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.23236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:50:02.917348Z","caller":"traceutil/trace.go:171","msg":"trace[170093287] transaction","detail":"{read_only:false; response_revision:381; number_of_response:1; }","duration":"170.02943ms","start":"2026-05-05T16:50:02.747277Z","end":"2026-05-05T16:50:02.917306Z","steps":["trace[170093287] 'process raft request' (duration: 169.661244ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:02.917482Z","caller":"traceutil/trace.go:171","msg":"trace[2057920545] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:382; }","duration":"144.324795ms","start":"2026-05-05T16:50:02.773146Z","end":"2026-05-05T16:50:02.917471Z","steps":["trace[2057920545] 'agreement among raft nodes before linearized reading' (duration: 144.249401ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:03.574244Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"555.814814ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:50:03.574681Z","caller":"traceutil/trace.go:171","msg":"trace[374978063] transaction","detail":"{read_only:false; response_revision:388; number_of_response:1; }","duration":"630.432728ms","start":"2026-05-05T16:50:02.944222Z","end":"2026-05-05T16:50:03.574655Z","steps":["trace[374978063] 'process raft request' (duration: 630.382335ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:03.574997Z","caller":"traceutil/trace.go:171","msg":"trace[1887901463] transaction","detail":"{read_only:false; response_revision:387; number_of_response:1; }","duration":"645.35871ms","start":"2026-05-05T16:50:02.929595Z","end":"2026-05-05T16:50:03.574954Z","steps":["trace[1887901463] 'process raft request' (duration: 644.938831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:03.575183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"650.989797ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/kube-proxy-vbppk\" ","response":"range_response_count:1 size:3439"} {"level":"info","ts":"2026-05-05T16:50:03.575245Z","caller":"traceutil/trace.go:171","msg":"trace[511705809] range","detail":"{range_begin:/registry/pods/kube-system/kube-proxy-vbppk; range_end:; response_count:1; response_revision:388; }","duration":"651.134474ms","start":"2026-05-05T16:50:02.924099Z","end":"2026-05-05T16:50:03.575233Z","steps":["trace[511705809] 'agreement among raft nodes before linearized reading' (duration: 650.971427ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:03.574998Z","caller":"traceutil/trace.go:171","msg":"trace[590841563] transaction","detail":"{read_only:false; response_revision:385; number_of_response:1; }","duration":"649.034318ms","start":"2026-05-05T16:50:02.925918Z","end":"2026-05-05T16:50:03.574952Z","steps":["trace[590841563] 'process raft request' (duration: 648.443022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:03.575296Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:02.924077Z","time spent":"651.207587ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":3461,"request content":"key:\"/registry/pods/kube-system/kube-proxy-vbppk\" "} {"level":"info","ts":"2026-05-05T16:50:03.57498Z","caller":"traceutil/trace.go:171","msg":"trace[331516173] linearizableReadLoop","detail":"{readStateIndex:393; appliedIndex:391; }","duration":"650.775228ms","start":"2026-05-05T16:50:02.924176Z","end":"2026-05-05T16:50:03.574951Z","steps":["trace[331516173] 'read index received' (duration: 16.334336ms)","trace[331516173] 'applied index is now lower than readState.Index' (duration: 634.438802ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:50:03.575013Z","caller":"traceutil/trace.go:171","msg":"trace[253796300] transaction","detail":"{read_only:false; response_revision:384; number_of_response:1; }","duration":"650.945556ms","start":"2026-05-05T16:50:02.924004Z","end":"2026-05-05T16:50:03.574949Z","steps":["trace[253796300] 'process raft request' (duration: 94.319525ms)","trace[253796300] 'compare' (duration: 555.650917ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:50:03.57555Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"510.154085ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" ","response":"range_response_count:1 size:185"} {"level":"info","ts":"2026-05-05T16:50:03.575664Z","caller":"traceutil/trace.go:171","msg":"trace[914237800] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/kube-proxy; range_end:; response_count:1; response_revision:388; }","duration":"510.315582ms","start":"2026-05-05T16:50:03.065333Z","end":"2026-05-05T16:50:03.575648Z","steps":["trace[914237800] 'agreement among raft nodes before linearized reading' (duration: 510.163796ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:03.575692Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.785678ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-05T16:50:03.575735Z","caller":"traceutil/trace.go:171","msg":"trace[1564449592] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:388; }","duration":"414.886553ms","start":"2026-05-05T16:50:03.160836Z","end":"2026-05-05T16:50:03.575722Z","steps":["trace[1564449592] 'agreement among raft nodes before linearized reading' (duration: 414.778759ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:03.575773Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:03.160809Z","time spent":"414.957446ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-05-05T16:50:03.575711Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:03.065294Z","time spent":"510.406477ms","remote":"127.0.0.1:58300","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":1,"response size":207,"request content":"key:\"/registry/serviceaccounts/kube-system/kube-proxy\" "} {"level":"warn","ts":"2026-05-05T16:50:03.576046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:02.929586Z","time spent":"645.601971ms","remote":"127.0.0.1:58602","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2886,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:50:03.576145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:02.925898Z","time spent":"649.425085ms","remote":"127.0.0.1:58606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3759,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:50:03.575554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:02.923991Z","time spent":"651.51774ms","remote":"127.0.0.1:58122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":704,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T16:50:03.575093Z","caller":"traceutil/trace.go:171","msg":"trace[511878321] transaction","detail":"{read_only:false; response_revision:386; number_of_response:1; }","duration":"648.21675ms","start":"2026-05-05T16:50:02.926865Z","end":"2026-05-05T16:50:03.575082Z","steps":["trace[511878321] 'process raft request' (duration: 647.610434ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:03.576586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:02.926856Z","time spent":"649.686455ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4001,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:50:03.574944Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:02.9442Z","time spent":"630.534552ms","remote":"127.0.0.1:58122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":830,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T16:50:04.052086Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"252.595275ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:50:04.052216Z","caller":"traceutil/trace.go:171","msg":"trace[1925175358] transaction","detail":"{read_only:false; response_revision:389; number_of_response:1; }","duration":"470.431527ms","start":"2026-05-05T16:50:03.581761Z","end":"2026-05-05T16:50:04.052192Z","steps":["trace[1925175358] 'process raft request' (duration: 217.624333ms)","trace[1925175358] 'compare' (duration: 252.338914ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:50:04.052308Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:03.581731Z","time spent":"470.545051ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:50:04.085333Z","caller":"traceutil/trace.go:171","msg":"trace[741755657] linearizableReadLoop","detail":"{readStateIndex:400; appliedIndex:397; }","duration":"311.765093ms","start":"2026-05-05T16:50:03.773539Z","end":"2026-05-05T16:50:04.085304Z","steps":["trace[741755657] 'read index received' (duration: 25.911095ms)","trace[741755657] 'applied index is now lower than readState.Index' (duration: 285.853038ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:50:04.085544Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"311.943721ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:50:04.085457Z","caller":"traceutil/trace.go:171","msg":"trace[601066307] transaction","detail":"{read_only:false; response_revision:390; number_of_response:1; }","duration":"501.273542ms","start":"2026-05-05T16:50:03.584097Z","end":"2026-05-05T16:50:04.08537Z","steps":["trace[601066307] 'process raft request' (duration: 500.977059ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:04.085588Z","caller":"traceutil/trace.go:171","msg":"trace[1564927206] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:391; }","duration":"312.051166ms","start":"2026-05-05T16:50:03.773526Z","end":"2026-05-05T16:50:04.085577Z","steps":["trace[1564927206] 'agreement among raft nodes before linearized reading' (duration: 311.887629ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:04.08563Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:03.773431Z","time spent":"312.188422ms","remote":"127.0.0.1:58048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T16:50:04.08583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:03.584072Z","time spent":"501.572035ms","remote":"127.0.0.1:58606","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3804,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:50:04.085924Z","caller":"traceutil/trace.go:171","msg":"trace[419479865] transaction","detail":"{read_only:false; response_revision:391; number_of_response:1; }","duration":"500.55317ms","start":"2026-05-05T16:50:03.585359Z","end":"2026-05-05T16:50:04.085912Z","steps":["trace[419479865] 'process raft request' (duration: 499.884112ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:04.08604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:03.585346Z","time spent":"500.607652ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4451,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:50:09.062225Z","caller":"traceutil/trace.go:171","msg":"trace[867696183] transaction","detail":"{read_only:false; response_revision:418; number_of_response:1; }","duration":"100.059718ms","start":"2026-05-05T16:50:08.96213Z","end":"2026-05-05T16:50:09.06219Z","steps":["trace[867696183] 'process raft request' (duration: 99.898022ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:26.005656Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.533983ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium\" ","response":"range_response_count:1 size:520"} {"level":"warn","ts":"2026-05-05T16:50:26.005657Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"280.849913ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/kube-system/\" range_end:\"/registry/secrets/kube-system0\" ","response":"range_response_count:4 size:329952"} {"level":"info","ts":"2026-05-05T16:50:26.005797Z","caller":"traceutil/trace.go:171","msg":"trace[50484588] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium; range_end:; response_count:1; response_revision:518; }","duration":"277.706446ms","start":"2026-05-05T16:50:25.728059Z","end":"2026-05-05T16:50:26.005765Z","steps":["trace[50484588] 'range keys from in-memory index tree' (duration: 277.204586ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:26.005809Z","caller":"traceutil/trace.go:171","msg":"trace[1597880606] range","detail":"{range_begin:/registry/secrets/kube-system/; range_end:/registry/secrets/kube-system0; response_count:4; response_revision:518; }","duration":"281.067318ms","start":"2026-05-05T16:50:25.724714Z","end":"2026-05-05T16:50:26.005782Z","steps":["trace[1597880606] 'range keys from in-memory index tree' (duration: 280.574928ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:26.00579Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"277.116444ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/cilium-operator\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-05-05T16:50:26.005908Z","caller":"traceutil/trace.go:171","msg":"trace[937020860] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/cilium-operator; range_end:; response_count:1; response_revision:518; }","duration":"277.262206ms","start":"2026-05-05T16:50:25.728613Z","end":"2026-05-05T16:50:26.005876Z","steps":["trace[937020860] 'range keys from in-memory index tree' (duration: 276.92977ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:28.079786Z","caller":"traceutil/trace.go:171","msg":"trace[586330536] transaction","detail":"{read_only:false; response_revision:549; number_of_response:1; }","duration":"557.823009ms","start":"2026-05-05T16:50:27.521915Z","end":"2026-05-05T16:50:28.079738Z","steps":["trace[586330536] 'process raft request' (duration: 548.977371ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:28.079883Z","caller":"traceutil/trace.go:171","msg":"trace[1739192697] transaction","detail":"{read_only:false; response_revision:551; number_of_response:1; }","duration":"556.174726ms","start":"2026-05-05T16:50:27.523664Z","end":"2026-05-05T16:50:28.079839Z","steps":["trace[1739192697] 'process raft request' (duration: 555.959632ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:28.079797Z","caller":"traceutil/trace.go:171","msg":"trace[195779258] transaction","detail":"{read_only:false; response_revision:550; number_of_response:1; }","duration":"557.520353ms","start":"2026-05-05T16:50:27.52223Z","end":"2026-05-05T16:50:28.07975Z","steps":["trace[195779258] 'process raft request' (duration: 557.158547ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:28.080046Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:27.521891Z","time spent":"558.012542ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7693,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:50:28.08028Z","caller":"traceutil/trace.go:171","msg":"trace[1062928237] linearizableReadLoop","detail":"{readStateIndex:572; appliedIndex:566; }","duration":"307.917561ms","start":"2026-05-05T16:50:27.772337Z","end":"2026-05-05T16:50:28.080255Z","steps":["trace[1062928237] 'read index received' (duration: 298.512142ms)","trace[1062928237] 'applied index is now lower than readState.Index' (duration: 9.403749ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:50:28.080297Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:27.522203Z","time spent":"557.812988ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":58844,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:50:28.080359Z","caller":"traceutil/trace.go:171","msg":"trace[995593920] transaction","detail":"{read_only:false; response_revision:553; number_of_response:1; }","duration":"542.567938ms","start":"2026-05-05T16:50:27.53773Z","end":"2026-05-05T16:50:28.080298Z","steps":["trace[995593920] 'process raft request' (duration: 542.116929ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:28.080374Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:27.523608Z","time spent":"556.351469ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":64462,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:50:28.080396Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.056243ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:50:28.08045Z","caller":"traceutil/trace.go:171","msg":"trace[889722902] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:554; }","duration":"308.157375ms","start":"2026-05-05T16:50:27.772278Z","end":"2026-05-05T16:50:28.080435Z","steps":["trace[889722902] 'agreement among raft nodes before linearized reading' (duration: 308.067633ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:28.080462Z","caller":"traceutil/trace.go:171","msg":"trace[1102545164] transaction","detail":"{read_only:false; response_revision:552; number_of_response:1; }","duration":"555.827329ms","start":"2026-05-05T16:50:27.524613Z","end":"2026-05-05T16:50:28.08044Z","steps":["trace[1102545164] 'process raft request' (duration: 555.124866ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:28.080491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:27.772246Z","time spent":"308.234757ms","remote":"127.0.0.1:58048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T16:50:28.080505Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:27.537713Z","time spent":"542.717201ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":79947,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:50:28.080594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:27.524573Z","time spent":"555.934162ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29546,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:50:28.080643Z","caller":"traceutil/trace.go:171","msg":"trace[109262465] transaction","detail":"{read_only:false; response_revision:554; number_of_response:1; }","duration":"456.440514ms","start":"2026-05-05T16:50:27.624181Z","end":"2026-05-05T16:50:28.080621Z","steps":["trace[109262465] 'process raft request' (duration: 455.982975ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:28.080714Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:27.624143Z","time spent":"456.536916ms","remote":"127.0.0.1:58098","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":149907,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-05-05T16:50:31.855966Z","caller":"traceutil/trace.go:171","msg":"trace[620055159] linearizableReadLoop","detail":"{readStateIndex:632; appliedIndex:631; }","duration":"114.039113ms","start":"2026-05-05T16:50:31.741887Z","end":"2026-05-05T16:50:31.855926Z","steps":["trace[620055159] 'read index received' (duration: 112.64995ms)","trace[620055159] 'applied index is now lower than readState.Index' (duration: 1.387433ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:50:31.856062Z","caller":"traceutil/trace.go:171","msg":"trace[253835741] transaction","detail":"{read_only:false; response_revision:613; number_of_response:1; }","duration":"117.39575ms","start":"2026-05-05T16:50:31.738636Z","end":"2026-05-05T16:50:31.856032Z","steps":["trace[253835741] 'process raft request' (duration: 116.237831ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:31.85629Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.962411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:50:31.856369Z","caller":"traceutil/trace.go:171","msg":"trace[1250182236] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:613; }","duration":"114.071032ms","start":"2026-05-05T16:50:31.742277Z","end":"2026-05-05T16:50:31.856348Z","steps":["trace[1250182236] 'agreement among raft nodes before linearized reading' (duration: 113.91325ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:31.856538Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.655925ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:50:31.856571Z","caller":"traceutil/trace.go:171","msg":"trace[1573477830] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:613; }","duration":"113.720116ms","start":"2026-05-05T16:50:31.742841Z","end":"2026-05-05T16:50:31.856561Z","steps":["trace[1573477830] 'agreement among raft nodes before linearized reading' (duration: 113.649285ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:31.856943Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"115.05192ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:50:31.856983Z","caller":"traceutil/trace.go:171","msg":"trace[1217516598] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:613; }","duration":"115.109861ms","start":"2026-05-05T16:50:31.741861Z","end":"2026-05-05T16:50:31.85697Z","steps":["trace[1217516598] 'agreement among raft nodes before linearized reading' (duration: 114.207076ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:35.186428Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291287945809463,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-05-05T16:50:35.535436Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.058519928s","expected-duration":"1s"} {"level":"info","ts":"2026-05-05T16:50:35.536699Z","caller":"traceutil/trace.go:171","msg":"trace[1382279178] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"1.060981395s","start":"2026-05-05T16:50:34.475684Z","end":"2026-05-05T16:50:35.536665Z","steps":["trace[1382279178] 'process raft request' (duration: 1.060303756s)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:35.536846Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:34.475657Z","time spent":"1.061098198s","remote":"127.0.0.1:58148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036281,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:50:35.550122Z","caller":"traceutil/trace.go:171","msg":"trace[163425829] linearizableReadLoop","detail":"{readStateIndex:651; appliedIndex:649; }","duration":"863.994114ms","start":"2026-05-05T16:50:34.686103Z","end":"2026-05-05T16:50:35.550097Z","steps":["trace[163425829] 'read index received' (duration: 849.656671ms)","trace[163425829] 'applied index is now lower than readState.Index' (duration: 14.335893ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:50:35.550375Z","caller":"traceutil/trace.go:171","msg":"trace[1900760771] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"603.606584ms","start":"2026-05-05T16:50:34.946758Z","end":"2026-05-05T16:50:35.550364Z","steps":["trace[1900760771] 'process raft request' (duration: 603.216048ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:35.550482Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:34.946725Z","time spent":"603.678566ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":673,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:50:35.5507Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.597014ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-n4wvj\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-05-05T16:50:35.550729Z","caller":"traceutil/trace.go:171","msg":"trace[2134846876] range","detail":"{range_begin:/registry/pods/kube-system/cilium-n4wvj; range_end:; response_count:1; response_revision:632; }","duration":"864.670024ms","start":"2026-05-05T16:50:34.686051Z","end":"2026-05-05T16:50:35.550721Z","steps":["trace[2134846876] 'agreement among raft nodes before linearized reading' (duration: 864.564673ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:35.550752Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:34.686024Z","time spent":"864.722926ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16987,"request content":"key:\"/registry/pods/kube-system/cilium-n4wvj\" "} {"level":"warn","ts":"2026-05-05T16:50:35.55087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"779.185507ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:50:35.550893Z","caller":"traceutil/trace.go:171","msg":"trace[248606753] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:632; }","duration":"779.222478ms","start":"2026-05-05T16:50:34.771665Z","end":"2026-05-05T16:50:35.550887Z","steps":["trace[248606753] 'agreement among raft nodes before linearized reading' (duration: 779.189787ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:35.550911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:34.771655Z","time spent":"779.253299ms","remote":"127.0.0.1:58050","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T16:50:35.551205Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.696411ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.157\" ","response":"range_response_count:1 size:135"} {"level":"info","ts":"2026-05-05T16:50:35.551232Z","caller":"traceutil/trace.go:171","msg":"trace[1197483514] range","detail":"{range_begin:/registry/masterleases/199.204.45.157; range_end:; response_count:1; response_revision:632; }","duration":"103.756053ms","start":"2026-05-05T16:50:35.447469Z","end":"2026-05-05T16:50:35.551225Z","steps":["trace[1197483514] 'agreement among raft nodes before linearized reading' (duration: 103.706692ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:35.551418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.034214ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T16:50:35.551444Z","caller":"traceutil/trace.go:171","msg":"trace[465792030] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:632; }","duration":"229.096926ms","start":"2026-05-05T16:50:35.322341Z","end":"2026-05-05T16:50:35.551438Z","steps":["trace[465792030] 'agreement among raft nodes before linearized reading' (duration: 229.011824ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:35.551691Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"309.361847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"info","ts":"2026-05-05T16:50:35.551723Z","caller":"traceutil/trace.go:171","msg":"trace[2079145908] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:632; }","duration":"309.425409ms","start":"2026-05-05T16:50:35.242291Z","end":"2026-05-05T16:50:35.551716Z","steps":["trace[2079145908] 'agreement among raft nodes before linearized reading' (duration: 309.334177ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:35.551744Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:35.242271Z","time spent":"309.466899ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":454,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T16:50:35.551862Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.632561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T16:50:35.551885Z","caller":"traceutil/trace.go:171","msg":"trace[1427248835] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:632; }","duration":"708.674321ms","start":"2026-05-05T16:50:34.843205Z","end":"2026-05-05T16:50:35.551879Z","steps":["trace[1427248835] 'agreement among raft nodes before linearized reading' (duration: 708.61601ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:35.551908Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:34.843194Z","time spent":"708.710612ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T16:50:35.923166Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"349.347853ms","expected-duration":"100ms","prefix":"","request":"header: lease_grant:","response":"size:39"} {"level":"warn","ts":"2026-05-05T16:50:35.923423Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:35.553585Z","time spent":"369.822847ms","remote":"127.0.0.1:58084","response type":"/etcdserverpb.Lease/LeaseGrant","request count":-1,"request size":-1,"response count":-1,"response size":-1,"request content":""} {"level":"info","ts":"2026-05-05T16:50:35.923737Z","caller":"traceutil/trace.go:171","msg":"trace[1341820915] transaction","detail":"{read_only:false; response_revision:633; number_of_response:1; }","duration":"363.852919ms","start":"2026-05-05T16:50:35.559825Z","end":"2026-05-05T16:50:35.923678Z","steps":["trace[1341820915] 'process raft request' (duration: 363.504274ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:35.923842Z","caller":"traceutil/trace.go:171","msg":"trace[511519982] transaction","detail":"{read_only:false; response_revision:634; number_of_response:1; }","duration":"363.836058ms","start":"2026-05-05T16:50:35.559997Z","end":"2026-05-05T16:50:35.923833Z","steps":["trace[511519982] 'process raft request' (duration: 363.616725ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:35.923946Z","caller":"traceutil/trace.go:171","msg":"trace[2057779357] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"361.372622ms","start":"2026-05-05T16:50:35.562544Z","end":"2026-05-05T16:50:35.923916Z","steps":["trace[2057779357] 'process raft request' (duration: 361.122038ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:35.923972Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:35.559991Z","time spent":"363.895879ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:50:35.923959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:35.55903Z","time spent":"364.815233ms","remote":"127.0.0.1:58382","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-05-05T16:50:35.923791Z","caller":"traceutil/trace.go:171","msg":"trace[547383259] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"352.289157ms","start":"2026-05-05T16:50:35.571479Z","end":"2026-05-05T16:50:35.923768Z","steps":["trace[547383259] 'process raft request' (duration: 352.227126ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:35.924139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:35.562537Z","time spent":"361.485203ms","remote":"127.0.0.1:58382","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-05-05T16:50:35.924351Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:35.571443Z","time spent":"352.766124ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:50:35.959497Z","caller":"traceutil/trace.go:171","msg":"trace[106974735] linearizableReadLoop","detail":"{readStateIndex:657; appliedIndex:651; }","duration":"186.633794ms","start":"2026-05-05T16:50:35.772836Z","end":"2026-05-05T16:50:35.95947Z","steps":["trace[106974735] 'read index received' (duration: 115.238222ms)","trace[106974735] 'applied index is now lower than readState.Index' (duration: 71.394332ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:50:35.959679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"186.821258ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:50:35.95971Z","caller":"traceutil/trace.go:171","msg":"trace[707611522] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:637; }","duration":"186.932909ms","start":"2026-05-05T16:50:35.772768Z","end":"2026-05-05T16:50:35.959701Z","steps":["trace[707611522] 'agreement among raft nodes before linearized reading' (duration: 186.803897ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:35.959621Z","caller":"traceutil/trace.go:171","msg":"trace[1470519123] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"355.726968ms","start":"2026-05-05T16:50:35.603832Z","end":"2026-05-05T16:50:35.959559Z","steps":["trace[1470519123] 'process raft request' (duration: 355.491954ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:35.959998Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:35.603818Z","time spent":"356.055922ms","remote":"127.0.0.1:58638","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-05-05T16:50:37.007135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"744.157415ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-05T16:50:37.007231Z","caller":"traceutil/trace.go:171","msg":"trace[1420961601] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:638; }","duration":"744.308586ms","start":"2026-05-05T16:50:36.262902Z","end":"2026-05-05T16:50:37.007211Z","steps":["trace[1420961601] 'range keys from in-memory index tree' (duration: 743.424905ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:37.007268Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:36.262881Z","time spent":"744.379498ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":526,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"info","ts":"2026-05-05T16:50:37.091912Z","caller":"traceutil/trace.go:171","msg":"trace[161855365] transaction","detail":"{read_only:false; response_revision:639; number_of_response:1; }","duration":"723.806489ms","start":"2026-05-05T16:50:36.368078Z","end":"2026-05-05T16:50:37.091885Z","steps":["trace[161855365] 'process raft request' (duration: 713.353204ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:37.091945Z","caller":"traceutil/trace.go:171","msg":"trace[579398730] linearizableReadLoop","detail":"{readStateIndex:659; appliedIndex:658; }","duration":"316.712451ms","start":"2026-05-05T16:50:36.775201Z","end":"2026-05-05T16:50:37.091913Z","steps":["trace[579398730] 'read index received' (duration: 305.368934ms)","trace[579398730] 'applied index is now lower than readState.Index' (duration: 11.342927ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:50:37.092041Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:36.368042Z","time spent":"723.931362ms","remote":"127.0.0.1:58148","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036305,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:50:37.092202Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.001825ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:50:37.092245Z","caller":"traceutil/trace.go:171","msg":"trace[434278000] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:640; }","duration":"317.074265ms","start":"2026-05-05T16:50:36.775157Z","end":"2026-05-05T16:50:37.092232Z","steps":["trace[434278000] 'agreement among raft nodes before linearized reading' (duration: 316.820962ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:37.092267Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:50:36.775138Z","time spent":"317.124276ms","remote":"127.0.0.1:58048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T16:50:54.197701Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"183.08984ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T16:50:54.197838Z","caller":"traceutil/trace.go:171","msg":"trace[1750376488] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:804; }","duration":"183.294151ms","start":"2026-05-05T16:50:54.014511Z","end":"2026-05-05T16:50:54.197805Z","steps":["trace[1750376488] 'range keys from in-memory index tree' (duration: 182.809087ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:50:54.198073Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.840149ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:432"} {"level":"warn","ts":"2026-05-05T16:50:54.198136Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.875481ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T16:50:54.198391Z","caller":"traceutil/trace.go:171","msg":"trace[2055312566] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:804; }","duration":"186.189744ms","start":"2026-05-05T16:50:54.012174Z","end":"2026-05-05T16:50:54.198363Z","steps":["trace[2055312566] 'range keys from in-memory index tree' (duration: 185.650039ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:50:54.19821Z","caller":"traceutil/trace.go:171","msg":"trace[302697166] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:804; }","duration":"179.05361ms","start":"2026-05-05T16:50:54.019125Z","end":"2026-05-05T16:50:54.198178Z","steps":["trace[302697166] 'range keys from in-memory index tree' (duration: 178.531996ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:51:44.361909Z","caller":"traceutil/trace.go:171","msg":"trace[1634727537] transaction","detail":"{read_only:false; response_revision:1457; number_of_response:1; }","duration":"165.917361ms","start":"2026-05-05T16:51:44.195947Z","end":"2026-05-05T16:51:44.361864Z","steps":["trace[1634727537] 'process raft request' (duration: 165.606032ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:00.003395Z","caller":"traceutil/trace.go:171","msg":"trace[707586341] transaction","detail":"{read_only:false; response_revision:1724; number_of_response:1; }","duration":"132.098628ms","start":"2026-05-05T16:51:59.871257Z","end":"2026-05-05T16:52:00.003355Z","steps":["trace[707586341] 'process raft request' (duration: 131.841376ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:10.922523Z","caller":"traceutil/trace.go:171","msg":"trace[1438323292] transaction","detail":"{read_only:false; response_revision:1870; number_of_response:1; }","duration":"651.237961ms","start":"2026-05-05T16:52:10.27126Z","end":"2026-05-05T16:52:10.922498Z","steps":["trace[1438323292] 'process raft request' (duration: 651.058592ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:10.922682Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:10.271229Z","time spent":"651.376188ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3732,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:52:11.221713Z","caller":"traceutil/trace.go:171","msg":"trace[656749342] transaction","detail":"{read_only:false; response_revision:1871; number_of_response:1; }","duration":"933.032688ms","start":"2026-05-05T16:52:10.288618Z","end":"2026-05-05T16:52:11.221651Z","steps":["trace[656749342] 'process raft request' (duration: 932.779806ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:11.221884Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:10.288585Z","time spent":"933.215037ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:52:11.241689Z","caller":"traceutil/trace.go:171","msg":"trace[733421981] transaction","detail":"{read_only:false; response_revision:1872; number_of_response:1; }","duration":"666.95194ms","start":"2026-05-05T16:52:10.574711Z","end":"2026-05-05T16:52:11.241663Z","steps":["trace[733421981] 'process raft request' (duration: 666.718709ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:11.241859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:10.574688Z","time spent":"667.080426ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:52:11.242166Z","caller":"traceutil/trace.go:171","msg":"trace[892625153] transaction","detail":"{read_only:false; response_revision:1873; number_of_response:1; }","duration":"597.657584ms","start":"2026-05-05T16:52:10.644489Z","end":"2026-05-05T16:52:11.242147Z","steps":["trace[892625153] 'process raft request' (duration: 597.12649ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:11.242317Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:10.644431Z","time spent":"597.792841ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:52:11.242394Z","caller":"traceutil/trace.go:171","msg":"trace[853983082] linearizableReadLoop","detail":"{readStateIndex:1939; appliedIndex:1938; }","duration":"772.445586ms","start":"2026-05-05T16:52:10.469934Z","end":"2026-05-05T16:52:11.242379Z","steps":["trace[853983082] 'read index received' (duration: 452.897308ms)","trace[853983082] 'applied index is now lower than readState.Index' (duration: 319.547508ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:52:11.242552Z","caller":"traceutil/trace.go:171","msg":"trace[1945997185] transaction","detail":"{read_only:false; response_revision:1874; number_of_response:1; }","duration":"455.680192ms","start":"2026-05-05T16:52:10.786862Z","end":"2026-05-05T16:52:11.242543Z","steps":["trace[1945997185] 'process raft request' (duration: 455.180138ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:11.242628Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:10.786842Z","time spent":"455.745555ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:52:11.242985Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"490.343322ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T16:52:11.243081Z","caller":"traceutil/trace.go:171","msg":"trace[1055839021] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1874; }","duration":"490.494919ms","start":"2026-05-05T16:52:10.752564Z","end":"2026-05-05T16:52:11.243059Z","steps":["trace[1055839021] 'agreement among raft nodes before linearized reading' (duration: 490.215335ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:11.243132Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:10.752542Z","time spent":"490.581643ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T16:52:11.243151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.013679ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" ","response":"range_response_count:1 size:10630"} {"level":"info","ts":"2026-05-05T16:52:11.243231Z","caller":"traceutil/trace.go:171","msg":"trace[1439148079] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-haproxy-0; range_end:; response_count:1; response_revision:1874; }","duration":"316.100454ms","start":"2026-05-05T16:52:10.927089Z","end":"2026-05-05T16:52:11.243189Z","steps":["trace[1439148079] 'agreement among raft nodes before linearized reading' (duration: 315.905805ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:11.243275Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:10.927073Z","time spent":"316.192658ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":51,"response count":1,"response size":10652,"request content":"key:\"/registry/pods/openstack/percona-xtradb-haproxy-0\" "} {"level":"warn","ts":"2026-05-05T16:52:11.243415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"468.27252ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T16:52:11.243457Z","caller":"traceutil/trace.go:171","msg":"trace[889950238] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:1874; }","duration":"468.343255ms","start":"2026-05-05T16:52:10.775104Z","end":"2026-05-05T16:52:11.243447Z","steps":["trace[889950238] 'agreement among raft nodes before linearized reading' (duration: 468.24204ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:11.243486Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:10.775095Z","time spent":"468.384686ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T16:52:11.243603Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.767561ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:52:11.243661Z","caller":"traceutil/trace.go:171","msg":"trace[687153108] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1874; }","duration":"469.871716ms","start":"2026-05-05T16:52:10.773773Z","end":"2026-05-05T16:52:11.243645Z","steps":["trace[687153108] 'agreement among raft nodes before linearized reading' (duration: 469.789102ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:11.243696Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:10.773748Z","time spent":"469.94105ms","remote":"127.0.0.1:58048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T16:52:11.243774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"489.016788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T16:52:11.24384Z","caller":"traceutil/trace.go:171","msg":"trace[1270602141] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1874; }","duration":"489.097681ms","start":"2026-05-05T16:52:10.754731Z","end":"2026-05-05T16:52:11.243829Z","steps":["trace[1270602141] 'agreement among raft nodes before linearized reading' (duration: 488.796077ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:11.243907Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"229.227038ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"warn","ts":"2026-05-05T16:52:11.243886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:10.754726Z","time spent":"489.150514ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":459,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-05-05T16:52:11.243961Z","caller":"traceutil/trace.go:171","msg":"trace[827051725] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1874; }","duration":"229.347063ms","start":"2026-05-05T16:52:11.014597Z","end":"2026-05-05T16:52:11.243945Z","steps":["trace[827051725] 'agreement among raft nodes before linearized reading' (duration: 229.213937ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:11.244112Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"246.466611ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-05T16:52:11.244252Z","caller":"traceutil/trace.go:171","msg":"trace[871170013] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:1874; }","duration":"246.787616ms","start":"2026-05-05T16:52:10.997438Z","end":"2026-05-05T16:52:11.244226Z","steps":["trace[871170013] 'agreement among raft nodes before linearized reading' (duration: 246.154747ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:11.24542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"775.475508ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d198b8e6-23e1-4124-9e6c-d8a9fa465de9\" ","response":"range_response_count:1 size:3747"} {"level":"info","ts":"2026-05-05T16:52:11.245486Z","caller":"traceutil/trace.go:171","msg":"trace[1263032389] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-d198b8e6-23e1-4124-9e6c-d8a9fa465de9; range_end:; response_count:1; response_revision:1874; }","duration":"775.588043ms","start":"2026-05-05T16:52:10.469884Z","end":"2026-05-05T16:52:11.245472Z","steps":["trace[1263032389] 'agreement among raft nodes before linearized reading' (duration: 772.829634ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:11.245554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:10.469856Z","time spent":"775.688018ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3769,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-d198b8e6-23e1-4124-9e6c-d8a9fa465de9\" "} {"level":"info","ts":"2026-05-05T16:52:11.364745Z","caller":"traceutil/trace.go:171","msg":"trace[121838318] transaction","detail":"{read_only:false; response_revision:1876; number_of_response:1; }","duration":"113.555394ms","start":"2026-05-05T16:52:11.251156Z","end":"2026-05-05T16:52:11.364711Z","steps":["trace[121838318] 'process raft request' (duration: 106.880822ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:11.364788Z","caller":"traceutil/trace.go:171","msg":"trace[1657698468] transaction","detail":"{read_only:false; response_revision:1877; number_of_response:1; }","duration":"113.367645ms","start":"2026-05-05T16:52:11.251363Z","end":"2026-05-05T16:52:11.364731Z","steps":["trace[1657698468] 'process raft request' (duration: 113.247289ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:11.365491Z","caller":"traceutil/trace.go:171","msg":"trace[1179634626] transaction","detail":"{read_only:false; response_revision:1881; number_of_response:1; }","duration":"109.899632ms","start":"2026-05-05T16:52:11.255523Z","end":"2026-05-05T16:52:11.365423Z","steps":["trace[1179634626] 'process raft request' (duration: 109.573067ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:11.36515Z","caller":"traceutil/trace.go:171","msg":"trace[1453474936] transaction","detail":"{read_only:false; response_revision:1878; number_of_response:1; }","duration":"113.665949ms","start":"2026-05-05T16:52:11.251454Z","end":"2026-05-05T16:52:11.36512Z","steps":["trace[1453474936] 'process raft request' (duration: 113.207908ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:11.36538Z","caller":"traceutil/trace.go:171","msg":"trace[664319433] transaction","detail":"{read_only:false; response_revision:1880; number_of_response:1; }","duration":"111.787971ms","start":"2026-05-05T16:52:11.25355Z","end":"2026-05-05T16:52:11.365338Z","steps":["trace[664319433] 'process raft request' (duration: 111.468076ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:11.365251Z","caller":"traceutil/trace.go:171","msg":"trace[379062088] transaction","detail":"{read_only:false; response_revision:1879; number_of_response:1; }","duration":"112.726915ms","start":"2026-05-05T16:52:11.252487Z","end":"2026-05-05T16:52:11.365214Z","steps":["trace[379062088] 'process raft request' (duration: 112.452903ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:11.544338Z","caller":"traceutil/trace.go:171","msg":"trace[117388168] transaction","detail":"{read_only:false; response_revision:1882; number_of_response:1; }","duration":"171.347083ms","start":"2026-05-05T16:52:11.372952Z","end":"2026-05-05T16:52:11.544299Z","steps":["trace[117388168] 'process raft request' (duration: 171.182325ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:11.954398Z","caller":"traceutil/trace.go:171","msg":"trace[1637525132] transaction","detail":"{read_only:false; response_revision:1883; number_of_response:1; }","duration":"581.058872ms","start":"2026-05-05T16:52:11.373304Z","end":"2026-05-05T16:52:11.954363Z","steps":["trace[1637525132] 'process raft request' (duration: 579.5096ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:11.954619Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:11.373296Z","time spent":"581.211649ms","remote":"127.0.0.1:58388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1472,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:52:11.954853Z","caller":"traceutil/trace.go:171","msg":"trace[898305038] transaction","detail":"{read_only:false; response_revision:1884; number_of_response:1; }","duration":"581.390199ms","start":"2026-05-05T16:52:11.373434Z","end":"2026-05-05T16:52:11.954824Z","steps":["trace[898305038] 'process raft request' (duration: 580.79315ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:11.955029Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:11.373409Z","time spent":"581.521305ms","remote":"127.0.0.1:58388","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1530,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:52:11.955426Z","caller":"traceutil/trace.go:171","msg":"trace[460797331] transaction","detail":"{read_only:false; response_revision:1885; number_of_response:1; }","duration":"580.873725ms","start":"2026-05-05T16:52:11.374504Z","end":"2026-05-05T16:52:11.955378Z","steps":["trace[460797331] 'process raft request' (duration: 580.223444ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:11.955756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:11.374495Z","time spent":"581.102336ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":894,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:52:11.955438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"216.28216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T16:52:11.956531Z","caller":"traceutil/trace.go:171","msg":"trace[1708177191] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1885; }","duration":"217.432193ms","start":"2026-05-05T16:52:11.739081Z","end":"2026-05-05T16:52:11.956513Z","steps":["trace[1708177191] 'agreement among raft nodes before linearized reading' (duration: 216.140193ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:11.954905Z","caller":"traceutil/trace.go:171","msg":"trace[1123216513] linearizableReadLoop","detail":"{readStateIndex:1954; appliedIndex:1951; }","duration":"215.742055ms","start":"2026-05-05T16:52:11.739136Z","end":"2026-05-05T16:52:11.954878Z","steps":["trace[1123216513] 'read index received' (duration: 213.597105ms)","trace[1123216513] 'applied index is now lower than readState.Index' (duration: 2.14374ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:52:18.868877Z","caller":"traceutil/trace.go:171","msg":"trace[1831952364] transaction","detail":"{read_only:false; response_revision:1950; number_of_response:1; }","duration":"120.166952ms","start":"2026-05-05T16:52:18.748671Z","end":"2026-05-05T16:52:18.868838Z","steps":["trace[1831952364] 'process raft request' (duration: 119.485863ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:19.633776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.307147ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:52:19.633986Z","caller":"traceutil/trace.go:171","msg":"trace[1622774254] transaction","detail":"{read_only:false; response_revision:1957; number_of_response:1; }","duration":"190.59507ms","start":"2026-05-05T16:52:19.443356Z","end":"2026-05-05T16:52:19.633951Z","steps":["trace[1622774254] 'process raft request' (duration: 69.376544ms)","trace[1622774254] 'compare' (duration: 120.070927ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:52:19.634048Z","caller":"traceutil/trace.go:171","msg":"trace[1047473333] transaction","detail":"{read_only:false; response_revision:1958; number_of_response:1; }","duration":"188.496993ms","start":"2026-05-05T16:52:19.445533Z","end":"2026-05-05T16:52:19.63403Z","steps":["trace[1047473333] 'process raft request' (duration: 188.362917ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:19.634268Z","caller":"traceutil/trace.go:171","msg":"trace[993016247] transaction","detail":"{read_only:false; response_revision:1959; number_of_response:1; }","duration":"188.640088ms","start":"2026-05-05T16:52:19.445613Z","end":"2026-05-05T16:52:19.634253Z","steps":["trace[993016247] 'process raft request' (duration: 188.373837ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:19.634358Z","caller":"traceutil/trace.go:171","msg":"trace[755474749] transaction","detail":"{read_only:false; response_revision:1960; number_of_response:1; }","duration":"188.636727ms","start":"2026-05-05T16:52:19.445712Z","end":"2026-05-05T16:52:19.634349Z","steps":["trace[755474749] 'process raft request' (duration: 188.450679ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:21.808191Z","caller":"traceutil/trace.go:171","msg":"trace[49427001] transaction","detail":"{read_only:false; response_revision:1972; number_of_response:1; }","duration":"165.07339ms","start":"2026-05-05T16:52:21.643095Z","end":"2026-05-05T16:52:21.808168Z","steps":["trace[49427001] 'process raft request' (duration: 164.406843ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:21.808435Z","caller":"traceutil/trace.go:171","msg":"trace[524465924] transaction","detail":"{read_only:false; response_revision:1973; number_of_response:1; }","duration":"165.025508ms","start":"2026-05-05T16:52:21.643393Z","end":"2026-05-05T16:52:21.808418Z","steps":["trace[524465924] 'process raft request' (duration: 164.728596ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:21.808704Z","caller":"traceutil/trace.go:171","msg":"trace[1275332376] transaction","detail":"{read_only:false; response_revision:1974; number_of_response:1; }","duration":"164.895573ms","start":"2026-05-05T16:52:21.643792Z","end":"2026-05-05T16:52:21.808687Z","steps":["trace[1275332376] 'process raft request' (duration: 164.5748ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:37.481574Z","caller":"traceutil/trace.go:171","msg":"trace[1019957193] transaction","detail":"{read_only:false; response_revision:2070; number_of_response:1; }","duration":"119.279634ms","start":"2026-05-05T16:52:37.362256Z","end":"2026-05-05T16:52:37.481535Z","steps":["trace[1019957193] 'process raft request' (duration: 23.348982ms)","trace[1019957193] 'compare' (duration: 95.719855ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:52:37.513131Z","caller":"traceutil/trace.go:171","msg":"trace[1397459591] linearizableReadLoop","detail":"{readStateIndex:2146; appliedIndex:2145; }","duration":"118.675504ms","start":"2026-05-05T16:52:37.394433Z","end":"2026-05-05T16:52:37.513109Z","steps":["trace[1397459591] 'read index received' (duration: 118.590081ms)","trace[1397459591] 'applied index is now lower than readState.Index' (duration: 84.603µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:52:37.513214Z","caller":"traceutil/trace.go:171","msg":"trace[1490252438] transaction","detail":"{read_only:false; response_revision:2071; number_of_response:1; }","duration":"148.653773ms","start":"2026-05-05T16:52:37.364517Z","end":"2026-05-05T16:52:37.513171Z","steps":["trace[1490252438] 'process raft request' (duration: 148.441916ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:37.513593Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.955993ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T16:52:37.513642Z","caller":"traceutil/trace.go:171","msg":"trace[1751955276] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2071; }","duration":"119.233741ms","start":"2026-05-05T16:52:37.394392Z","end":"2026-05-05T16:52:37.513626Z","steps":["trace[1751955276] 'agreement among raft nodes before linearized reading' (duration: 118.86836ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:37.648863Z","caller":"traceutil/trace.go:171","msg":"trace[934196661] transaction","detail":"{read_only:false; response_revision:2073; number_of_response:1; }","duration":"107.996566ms","start":"2026-05-05T16:52:37.540817Z","end":"2026-05-05T16:52:37.648813Z","steps":["trace[934196661] 'process raft request' (duration: 57.165247ms)","trace[934196661] 'compare' (duration: 50.524639ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:52:38.096704Z","caller":"traceutil/trace.go:171","msg":"trace[398484570] transaction","detail":"{read_only:false; response_revision:2076; number_of_response:1; }","duration":"101.816092ms","start":"2026-05-05T16:52:37.994854Z","end":"2026-05-05T16:52:38.09667Z","steps":["trace[398484570] 'process raft request' (duration: 101.448821ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:39.665732Z","caller":"traceutil/trace.go:171","msg":"trace[1970448939] transaction","detail":"{read_only:false; response_revision:2082; number_of_response:1; }","duration":"116.257843ms","start":"2026-05-05T16:52:39.549455Z","end":"2026-05-05T16:52:39.665713Z","steps":["trace[1970448939] 'process raft request' (duration: 116.100008ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:46.014614Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.276512ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:52:46.015132Z","caller":"traceutil/trace.go:171","msg":"trace[1390373159] transaction","detail":"{read_only:false; response_revision:2112; number_of_response:1; }","duration":"481.571792ms","start":"2026-05-05T16:52:45.533261Z","end":"2026-05-05T16:52:46.014833Z","steps":["trace[1390373159] 'process raft request' (duration: 259.989241ms)","trace[1390373159] 'compare' (duration: 220.981744ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:52:46.015306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:45.533235Z","time spent":"481.991765ms","remote":"127.0.0.1:58084","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":120,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:52:46.394085Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.536967ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:52:46.394238Z","caller":"traceutil/trace.go:171","msg":"trace[79606330] linearizableReadLoop","detail":"{readStateIndex:2191; appliedIndex:2188; }","duration":"707.993034ms","start":"2026-05-05T16:52:45.68623Z","end":"2026-05-05T16:52:46.394223Z","steps":["trace[79606330] 'read index received' (duration: 107.094811ms)","trace[79606330] 'applied index is now lower than readState.Index' (duration: 600.897253ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:52:46.394245Z","caller":"traceutil/trace.go:171","msg":"trace[1371381659] transaction","detail":"{read_only:false; response_revision:2113; number_of_response:1; }","duration":"856.567349ms","start":"2026-05-05T16:52:45.537654Z","end":"2026-05-05T16:52:46.394221Z","steps":["trace[1371381659] 'process raft request' (duration: 698.760804ms)","trace[1371381659] 'compare' (duration: 157.365283ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:52:46.394401Z","caller":"traceutil/trace.go:171","msg":"trace[409127425] transaction","detail":"{read_only:false; response_revision:2114; number_of_response:1; }","duration":"829.884717ms","start":"2026-05-05T16:52:45.564509Z","end":"2026-05-05T16:52:46.394394Z","steps":["trace[409127425] 'process raft request' (duration: 829.665041ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:46.394451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:45.564469Z","time spent":"829.955779ms","remote":"127.0.0.1:58382","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-05-05T16:52:46.394447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:45.537638Z","time spent":"856.677942ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:52:46.39467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"708.443867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-05-05T16:52:46.394712Z","caller":"traceutil/trace.go:171","msg":"trace[616177554] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2114; }","duration":"708.525949ms","start":"2026-05-05T16:52:45.686175Z","end":"2026-05-05T16:52:46.394701Z","steps":["trace[616177554] 'agreement among raft nodes before linearized reading' (duration: 708.392775ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:46.394741Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:45.686153Z","time spent":"708.580081ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":501,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"warn","ts":"2026-05-05T16:52:46.395216Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"377.874902ms","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-05-05T16:52:46.395294Z","caller":"traceutil/trace.go:171","msg":"trace[341343067] range","detail":"{range_begin:/registry/services/endpoints/default/kubernetes; range_end:; response_count:1; response_revision:2114; }","duration":"377.962684ms","start":"2026-05-05T16:52:46.017312Z","end":"2026-05-05T16:52:46.395275Z","steps":["trace[341343067] 'agreement among raft nodes before linearized reading' (duration: 377.82312ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:46.395336Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:46.017273Z","time spent":"378.055027ms","remote":"127.0.0.1:58238","response type":"/etcdserverpb.KV/Range","request count":0,"request size":49,"response count":1,"response size":444,"request content":"key:\"/registry/services/endpoints/default/kubernetes\" "} {"level":"warn","ts":"2026-05-05T16:52:46.39542Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"257.810248ms","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":"warn","ts":"2026-05-05T16:52:46.395443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.460948ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-05-05T16:52:46.39547Z","caller":"traceutil/trace.go:171","msg":"trace[1282658580] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2114; }","duration":"202.53105ms","start":"2026-05-05T16:52:46.192933Z","end":"2026-05-05T16:52:46.395464Z","steps":["trace[1282658580] 'agreement among raft nodes before linearized reading' (duration: 202.459667ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:46.395473Z","caller":"traceutil/trace.go:171","msg":"trace[1515388729] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2114; }","duration":"257.89889ms","start":"2026-05-05T16:52:46.137562Z","end":"2026-05-05T16:52:46.395461Z","steps":["trace[1515388729] 'agreement among raft nodes before linearized reading' (duration: 257.756236ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:46.395477Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"392.044452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T16:52:46.395521Z","caller":"traceutil/trace.go:171","msg":"trace[20046036] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:2114; }","duration":"392.085103ms","start":"2026-05-05T16:52:46.003419Z","end":"2026-05-05T16:52:46.395504Z","steps":["trace[20046036] 'agreement among raft nodes before linearized reading' (duration: 392.029581ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:46.395418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"622.223718ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-05-05T16:52:46.395593Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:46.00335Z","time spent":"392.231017ms","remote":"127.0.0.1:58372","response type":"/etcdserverpb.KV/Range","request count":0,"request size":80,"response count":1,"response size":29,"request content":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true "} {"level":"warn","ts":"2026-05-05T16:52:46.395616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"637.368723ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"info","ts":"2026-05-05T16:52:46.395653Z","caller":"traceutil/trace.go:171","msg":"trace[1706962027] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2114; }","duration":"637.445985ms","start":"2026-05-05T16:52:45.758199Z","end":"2026-05-05T16:52:46.395645Z","steps":["trace[1706962027] 'agreement among raft nodes before linearized reading' (duration: 637.359802ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:46.395686Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:45.758178Z","time spent":"637.501517ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-05-05T16:52:46.395551Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"267.005695ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:555"} {"level":"info","ts":"2026-05-05T16:52:46.395589Z","caller":"traceutil/trace.go:171","msg":"trace[2111300050] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2114; }","duration":"622.443176ms","start":"2026-05-05T16:52:45.773137Z","end":"2026-05-05T16:52:46.395581Z","steps":["trace[2111300050] 'agreement among raft nodes before linearized reading' (duration: 622.23235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:46.395749Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"690.241492ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5444"} {"level":"warn","ts":"2026-05-05T16:52:46.395768Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:45.773116Z","time spent":"622.643441ms","remote":"127.0.0.1:58048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T16:52:46.395786Z","caller":"traceutil/trace.go:171","msg":"trace[1901328121] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2114; }","duration":"690.301134ms","start":"2026-05-05T16:52:45.705471Z","end":"2026-05-05T16:52:46.395772Z","steps":["trace[1901328121] 'agreement among raft nodes before linearized reading' (duration: 690.205542ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:46.395767Z","caller":"traceutil/trace.go:171","msg":"trace[56146618] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2114; }","duration":"267.253052ms","start":"2026-05-05T16:52:46.128504Z","end":"2026-05-05T16:52:46.395757Z","steps":["trace[56146618] 'agreement among raft nodes before linearized reading' (duration: 266.982604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:46.39567Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"700.723659ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"warn","ts":"2026-05-05T16:52:46.395825Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:45.705456Z","time spent":"690.361907ms","remote":"127.0.0.1:51898","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5466,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-05-05T16:52:46.396226Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.423238ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:436"} {"level":"info","ts":"2026-05-05T16:52:46.396274Z","caller":"traceutil/trace.go:171","msg":"trace[478251109] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2114; }","duration":"256.521821ms","start":"2026-05-05T16:52:46.139741Z","end":"2026-05-05T16:52:46.396263Z","steps":["trace[478251109] 'agreement among raft nodes before linearized reading' (duration: 255.401429ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:46.395962Z","caller":"traceutil/trace.go:171","msg":"trace[1752609868] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2114; }","duration":"701.10494ms","start":"2026-05-05T16:52:45.694838Z","end":"2026-05-05T16:52:46.395943Z","steps":["trace[1752609868] 'agreement among raft nodes before linearized reading' (duration: 700.313808ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:46.397011Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:45.694761Z","time spent":"702.234743ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":593,"request content":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" "} {"level":"warn","ts":"2026-05-05T16:52:46.901775Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":10938291287945812474,"retry-timeout":"500ms"} {"level":"info","ts":"2026-05-05T16:52:47.084764Z","caller":"traceutil/trace.go:171","msg":"trace[1645678168] transaction","detail":"{read_only:false; response_revision:2115; number_of_response:1; }","duration":"685.87815ms","start":"2026-05-05T16:52:46.398842Z","end":"2026-05-05T16:52:47.08472Z","steps":["trace[1645678168] 'process raft request' (duration: 678.705554ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:47.084993Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:46.398825Z","time spent":"686.078736ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:52:47.087692Z","caller":"traceutil/trace.go:171","msg":"trace[1162887610] transaction","detail":"{read_only:false; response_revision:2116; number_of_response:1; }","duration":"686.382743ms","start":"2026-05-05T16:52:46.401292Z","end":"2026-05-05T16:52:47.087674Z","steps":["trace[1162887610] 'process raft request' (duration: 686.167257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:47.087784Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:46.401281Z","time spent":"686.459855ms","remote":"127.0.0.1:58382","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-05-05T16:52:47.087915Z","caller":"traceutil/trace.go:171","msg":"trace[1782456383] transaction","detail":"{read_only:false; response_revision:2118; number_of_response:1; }","duration":"686.475055ms","start":"2026-05-05T16:52:46.401424Z","end":"2026-05-05T16:52:47.087899Z","steps":["trace[1782456383] 'process raft request' (duration: 686.222658ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:47.087887Z","caller":"traceutil/trace.go:171","msg":"trace[221588754] transaction","detail":"{read_only:false; response_revision:2117; number_of_response:1; }","duration":"686.467565ms","start":"2026-05-05T16:52:46.401358Z","end":"2026-05-05T16:52:47.087826Z","steps":["trace[221588754] 'process raft request' (duration: 686.229388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:47.087975Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:46.40141Z","time spent":"686.533717ms","remote":"127.0.0.1:58382","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-05-05T16:52:47.088026Z","caller":"traceutil/trace.go:171","msg":"trace[680228630] transaction","detail":"{read_only:false; response_revision:2119; number_of_response:1; }","duration":"685.885519ms","start":"2026-05-05T16:52:46.402112Z","end":"2026-05-05T16:52:47.087998Z","steps":["trace[680228630] 'process raft request' (duration: 685.618691ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:47.088062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:46.401351Z","time spent":"686.611299ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:52:47.088158Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:46.402099Z","time spent":"685.992182ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":422,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:52:47.088434Z","caller":"traceutil/trace.go:171","msg":"trace[1216322161] linearizableReadLoop","detail":"{readStateIndex:2198; appliedIndex:2191; }","duration":"687.228507ms","start":"2026-05-05T16:52:46.401191Z","end":"2026-05-05T16:52:47.08842Z","steps":["trace[1216322161] 'read index received' (duration: 676.406118ms)","trace[1216322161] 'applied index is now lower than readState.Index' (duration: 10.821569ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:52:47.088541Z","caller":"traceutil/trace.go:171","msg":"trace[1752669548] transaction","detail":"{read_only:false; response_revision:2120; number_of_response:1; }","duration":"685.491627ms","start":"2026-05-05T16:52:46.403038Z","end":"2026-05-05T16:52:47.08853Z","steps":["trace[1752669548] 'process raft request' (duration: 685.269351ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:47.088561Z","caller":"traceutil/trace.go:171","msg":"trace[1725704923] transaction","detail":"{read_only:false; response_revision:2121; number_of_response:1; }","duration":"685.450406ms","start":"2026-05-05T16:52:46.403086Z","end":"2026-05-05T16:52:47.088536Z","steps":["trace[1725704923] 'process raft request' (duration: 685.296682ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:47.088636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:46.40303Z","time spent":"685.539488ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:52:47.08872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:46.403081Z","time spent":"685.547229ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":476,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:52:47.088744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"687.543116ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/endpointslices/default/kubernetes\" ","response":"range_response_count:1 size:481"} {"level":"info","ts":"2026-05-05T16:52:47.088791Z","caller":"traceutil/trace.go:171","msg":"trace[1729175064] range","detail":"{range_begin:/registry/endpointslices/default/kubernetes; range_end:; response_count:1; response_revision:2121; }","duration":"687.611158ms","start":"2026-05-05T16:52:46.401167Z","end":"2026-05-05T16:52:47.088778Z","steps":["trace[1729175064] 'agreement among raft nodes before linearized reading' (duration: 687.522235ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:52:47.088851Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:52:46.401155Z","time spent":"687.68769ms","remote":"127.0.0.1:58388","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/endpointslices/default/kubernetes\" "} {"level":"warn","ts":"2026-05-05T16:52:47.088973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.430535ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:559"} {"level":"info","ts":"2026-05-05T16:52:47.089144Z","caller":"traceutil/trace.go:171","msg":"trace[479608673] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2121; }","duration":"134.606031ms","start":"2026-05-05T16:52:46.954485Z","end":"2026-05-05T16:52:47.089091Z","steps":["trace[479608673] 'agreement among raft nodes before linearized reading' (duration: 134.288962ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:51.267373Z","caller":"traceutil/trace.go:171","msg":"trace[161736831] transaction","detail":"{read_only:false; response_revision:2141; number_of_response:1; }","duration":"146.56717ms","start":"2026-05-05T16:52:51.120782Z","end":"2026-05-05T16:52:51.26735Z","steps":["trace[161736831] 'process raft request' (duration: 146.441336ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:51.267372Z","caller":"traceutil/trace.go:171","msg":"trace[2077729207] transaction","detail":"{read_only:false; response_revision:2140; number_of_response:1; }","duration":"146.618961ms","start":"2026-05-05T16:52:51.120712Z","end":"2026-05-05T16:52:51.267331Z","steps":["trace[2077729207] 'process raft request' (duration: 90.716407ms)","trace[2077729207] 'compare' (duration: 55.494873ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:52:51.267765Z","caller":"traceutil/trace.go:171","msg":"trace[1737834652] transaction","detail":"{read_only:false; response_revision:2142; number_of_response:1; }","duration":"146.489926ms","start":"2026-05-05T16:52:51.121252Z","end":"2026-05-05T16:52:51.267742Z","steps":["trace[1737834652] 'process raft request' (duration: 146.058845ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:52:51.267802Z","caller":"traceutil/trace.go:171","msg":"trace[1869059421] transaction","detail":"{read_only:false; response_revision:2143; number_of_response:1; }","duration":"146.515177ms","start":"2026-05-05T16:52:51.121274Z","end":"2026-05-05T16:52:51.26779Z","steps":["trace[1869059421] 'process raft request' (duration: 146.413435ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:53:26.937189Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.561396ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5460"} {"level":"info","ts":"2026-05-05T16:53:26.937298Z","caller":"traceutil/trace.go:171","msg":"trace[706110856] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2339; }","duration":"102.72866ms","start":"2026-05-05T16:53:26.834548Z","end":"2026-05-05T16:53:26.937276Z","steps":["trace[706110856] 'range keys from in-memory index tree' (duration: 102.378543ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:53:59.779741Z","caller":"traceutil/trace.go:171","msg":"trace[1358625405] transaction","detail":"{read_only:false; response_revision:2613; number_of_response:1; }","duration":"136.537972ms","start":"2026-05-05T16:53:59.643178Z","end":"2026-05-05T16:53:59.779716Z","steps":["trace[1358625405] 'process raft request' (duration: 115.12349ms)","trace[1358625405] 'compare' (duration: 21.164718ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:53:59.779775Z","caller":"traceutil/trace.go:171","msg":"trace[1597287303] transaction","detail":"{read_only:false; response_revision:2614; number_of_response:1; }","duration":"135.686231ms","start":"2026-05-05T16:53:59.644066Z","end":"2026-05-05T16:53:59.779752Z","steps":["trace[1597287303] 'process raft request' (duration: 135.56879ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:17.027932Z","caller":"traceutil/trace.go:171","msg":"trace[1279307773] transaction","detail":"{read_only:false; response_revision:2704; number_of_response:1; }","duration":"107.889113ms","start":"2026-05-05T16:54:16.920023Z","end":"2026-05-05T16:54:17.027912Z","steps":["trace[1279307773] 'process raft request' (duration: 107.749801ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:17.303978Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"204.747325ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:54:17.304505Z","caller":"traceutil/trace.go:171","msg":"trace[1861853724] transaction","detail":"{read_only:false; response_revision:2705; number_of_response:1; }","duration":"265.785256ms","start":"2026-05-05T16:54:17.038688Z","end":"2026-05-05T16:54:17.304473Z","steps":["trace[1861853724] 'process raft request' (duration: 60.468215ms)","trace[1861853724] 'compare' (duration: 204.651234ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:54:17.304507Z","caller":"traceutil/trace.go:171","msg":"trace[1323017967] linearizableReadLoop","detail":"{readStateIndex:2803; appliedIndex:2802; }","duration":"143.879296ms","start":"2026-05-05T16:54:17.160214Z","end":"2026-05-05T16:54:17.304093Z","steps":["trace[1323017967] 'read index received' (duration: 49.73µs)","trace[1323017967] 'applied index is now lower than readState.Index' (duration: 143.827456ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:54:17.304838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.626644ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-05-05T16:54:17.304889Z","caller":"traceutil/trace.go:171","msg":"trace[2105672635] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2705; }","duration":"144.712796ms","start":"2026-05-05T16:54:17.160159Z","end":"2026-05-05T16:54:17.304872Z","steps":["trace[2105672635] 'agreement among raft nodes before linearized reading' (duration: 144.379051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:17.304882Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.586061ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-cainjector-leader-election\" ","response":"range_response_count:1 size:558"} {"level":"info","ts":"2026-05-05T16:54:17.305049Z","caller":"traceutil/trace.go:171","msg":"trace[1790045487] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-cainjector-leader-election; range_end:; response_count:1; response_revision:2705; }","duration":"133.771702ms","start":"2026-05-05T16:54:17.171255Z","end":"2026-05-05T16:54:17.305026Z","steps":["trace[1790045487] 'agreement among raft nodes before linearized reading' (duration: 133.398148ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:54:23.349722Z","caller":"traceutil/trace.go:171","msg":"trace[1225027743] linearizableReadLoop","detail":"{readStateIndex:2835; appliedIndex:2834; }","duration":"187.61014ms","start":"2026-05-05T16:54:23.162091Z","end":"2026-05-05T16:54:23.349701Z","steps":["trace[1225027743] 'read index received' (duration: 187.511729ms)","trace[1225027743] 'applied index is now lower than readState.Index' (duration: 97.521µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:54:23.349836Z","caller":"traceutil/trace.go:171","msg":"trace[915173617] transaction","detail":"{read_only:false; response_revision:2736; number_of_response:1; }","duration":"305.88807ms","start":"2026-05-05T16:54:23.043921Z","end":"2026-05-05T16:54:23.349809Z","steps":["trace[915173617] 'process raft request' (duration: 305.638797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:23.349927Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"187.832872ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1366"} {"level":"info","ts":"2026-05-05T16:54:23.349966Z","caller":"traceutil/trace.go:171","msg":"trace[1322812573] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2736; }","duration":"187.920933ms","start":"2026-05-05T16:54:23.162035Z","end":"2026-05-05T16:54:23.349956Z","steps":["trace[1322812573] 'agreement among raft nodes before linearized reading' (duration: 187.757242ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:23.349977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:54:23.043893Z","time spent":"305.990261ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:54:24.009602Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"406.453796ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:54:24.009741Z","caller":"traceutil/trace.go:171","msg":"trace[815990709] linearizableReadLoop","detail":"{readStateIndex:2836; appliedIndex:2835; }","duration":"656.63276ms","start":"2026-05-05T16:54:23.35307Z","end":"2026-05-05T16:54:24.009702Z","steps":["trace[815990709] 'read index received' (duration: 249.851761ms)","trace[815990709] 'applied index is now lower than readState.Index' (duration: 406.779479ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:54:24.009853Z","caller":"traceutil/trace.go:171","msg":"trace[736285599] transaction","detail":"{read_only:false; response_revision:2737; number_of_response:1; }","duration":"685.507553ms","start":"2026-05-05T16:54:23.324309Z","end":"2026-05-05T16:54:24.009816Z","steps":["trace[736285599] 'process raft request' (duration: 278.670514ms)","trace[736285599] 'compare' (duration: 406.243734ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:54:24.009983Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"656.912873ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" ","response":"range_response_count:1 size:1447"} {"level":"info","ts":"2026-05-05T16:54:24.01003Z","caller":"traceutil/trace.go:171","msg":"trace[1339283055] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-metrics; range_end:; response_count:1; response_revision:2737; }","duration":"656.998983ms","start":"2026-05-05T16:54:23.353018Z","end":"2026-05-05T16:54:24.010017Z","steps":["trace[1339283055] 'agreement among raft nodes before linearized reading' (duration: 656.818821ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:24.010033Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:54:23.32429Z","time spent":"685.647705ms","remote":"127.0.0.1:58382","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-05-05T16:54:24.010078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:54:23.353Z","time spent":"657.067323ms","remote":"127.0.0.1:58284","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":1469,"request content":"key:\"/registry/services/specs/auth-system/keycloak-metrics\" "} {"level":"warn","ts":"2026-05-05T16:54:24.010432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.614364ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" ","response":"range_response_count:1 size:556"} {"level":"info","ts":"2026-05-05T16:54:24.010501Z","caller":"traceutil/trace.go:171","msg":"trace[45994197] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2737; }","duration":"112.700564ms","start":"2026-05-05T16:54:23.89778Z","end":"2026-05-05T16:54:24.010481Z","steps":["trace[45994197] 'agreement among raft nodes before linearized reading' (duration: 112.516333ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:24.010635Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"227.006336ms","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-05-05T16:54:24.010731Z","caller":"traceutil/trace.go:171","msg":"trace[1076046375] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2737; }","duration":"227.135347ms","start":"2026-05-05T16:54:23.783578Z","end":"2026-05-05T16:54:24.010713Z","steps":["trace[1076046375] 'agreement among raft nodes before linearized reading' (duration: 226.984785ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:24.010809Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.708066ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:571"} {"level":"info","ts":"2026-05-05T16:54:24.010863Z","caller":"traceutil/trace.go:171","msg":"trace[1407880996] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:2737; }","duration":"101.778268ms","start":"2026-05-05T16:54:23.909067Z","end":"2026-05-05T16:54:24.010845Z","steps":["trace[1407880996] 'agreement among raft nodes before linearized reading' (duration: 101.666567ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:24.010462Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"264.14081ms","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-05-05T16:54:24.011092Z","caller":"traceutil/trace.go:171","msg":"trace[929620467] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2737; }","duration":"264.825666ms","start":"2026-05-05T16:54:23.746252Z","end":"2026-05-05T16:54:24.011078Z","steps":["trace[929620467] 'agreement among raft nodes before linearized reading' (duration: 264.035659ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:24.011096Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"238.093275ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:54:24.011335Z","caller":"traceutil/trace.go:171","msg":"trace[1459360279] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2737; }","duration":"238.400888ms","start":"2026-05-05T16:54:23.772915Z","end":"2026-05-05T16:54:24.011315Z","steps":["trace[1459360279] 'agreement among raft nodes before linearized reading' (duration: 238.021744ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:54:24.010864Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.260428ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"info","ts":"2026-05-05T16:54:24.011829Z","caller":"traceutil/trace.go:171","msg":"trace[790621234] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2737; }","duration":"123.274818ms","start":"2026-05-05T16:54:23.888536Z","end":"2026-05-05T16:54:24.01181Z","steps":["trace[790621234] 'agreement among raft nodes before linearized reading' (duration: 122.158456ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:00.488193Z","caller":"traceutil/trace.go:171","msg":"trace[1246320283] transaction","detail":"{read_only:false; response_revision:2933; number_of_response:1; }","duration":"132.506019ms","start":"2026-05-05T16:55:00.35566Z","end":"2026-05-05T16:55:00.488166Z","steps":["trace[1246320283] 'process raft request' (duration: 84.38617ms)","trace[1246320283] 'compare' (duration: 47.765867ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:55:00.488524Z","caller":"traceutil/trace.go:171","msg":"trace[1867199567] linearizableReadLoop","detail":"{readStateIndex:3040; appliedIndex:3038; }","duration":"100.41294ms","start":"2026-05-05T16:55:00.388088Z","end":"2026-05-05T16:55:00.488501Z","steps":["trace[1867199567] 'read index received' (duration: 51.858588ms)","trace[1867199567] 'applied index is now lower than readState.Index' (duration: 48.552922ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:55:00.488747Z","caller":"traceutil/trace.go:171","msg":"trace[1639158923] transaction","detail":"{read_only:false; response_revision:2934; number_of_response:1; }","duration":"133.058673ms","start":"2026-05-05T16:55:00.35566Z","end":"2026-05-05T16:55:00.488719Z","steps":["trace[1639158923] 'process raft request' (duration: 132.422389ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:00.488793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.704402ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T16:55:00.488885Z","caller":"traceutil/trace.go:171","msg":"trace[1613369927] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2935; }","duration":"100.886374ms","start":"2026-05-05T16:55:00.387985Z","end":"2026-05-05T16:55:00.488872Z","steps":["trace[1613369927] 'agreement among raft nodes before linearized reading' (duration: 100.626442ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:34.326214Z","caller":"traceutil/trace.go:171","msg":"trace[1556274199] transaction","detail":"{read_only:false; response_revision:3107; number_of_response:1; }","duration":"111.526836ms","start":"2026-05-05T16:55:34.214659Z","end":"2026-05-05T16:55:34.326186Z","steps":["trace[1556274199] 'process raft request' (duration: 111.352005ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:55:34.78389Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.190357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T16:55:34.784252Z","caller":"traceutil/trace.go:171","msg":"trace[2094130277] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:3112; }","duration":"102.609671ms","start":"2026-05-05T16:55:34.681617Z","end":"2026-05-05T16:55:34.784227Z","steps":["trace[2094130277] 'count revisions from in-memory index tree' (duration: 102.076536ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:55:42.739635Z","caller":"traceutil/trace.go:171","msg":"trace[332168725] transaction","detail":"{read_only:false; response_revision:3151; number_of_response:1; }","duration":"179.386233ms","start":"2026-05-05T16:55:42.560223Z","end":"2026-05-05T16:55:42.73961Z","steps":["trace[332168725] 'process raft request' (duration: 113.144954ms)","trace[332168725] 'compare' (duration: 66.103758ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:57:13.48998Z","caller":"traceutil/trace.go:171","msg":"trace[32827941] transaction","detail":"{read_only:false; response_revision:3713; number_of_response:1; }","duration":"117.958004ms","start":"2026-05-05T16:57:13.371991Z","end":"2026-05-05T16:57:13.489949Z","steps":["trace[32827941] 'process raft request' (duration: 117.810179ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:43.855466Z","caller":"traceutil/trace.go:171","msg":"trace[1728630657] transaction","detail":"{read_only:false; response_revision:3909; number_of_response:1; }","duration":"130.837108ms","start":"2026-05-05T16:57:43.724586Z","end":"2026-05-05T16:57:43.855423Z","steps":["trace[1728630657] 'process raft request' (duration: 76.168911ms)","trace[1728630657] 'compare' (duration: 54.268676ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:57:44.109622Z","caller":"traceutil/trace.go:171","msg":"trace[513251644] transaction","detail":"{read_only:false; response_revision:3913; number_of_response:1; }","duration":"168.169471ms","start":"2026-05-05T16:57:43.941421Z","end":"2026-05-05T16:57:44.10959Z","steps":["trace[513251644] 'process raft request' (duration: 105.498006ms)","trace[513251644] 'compare' (duration: 62.391047ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:57:44.111158Z","caller":"traceutil/trace.go:171","msg":"trace[481125066] transaction","detail":"{read_only:false; response_revision:3914; number_of_response:1; }","duration":"124.942493ms","start":"2026-05-05T16:57:43.986183Z","end":"2026-05-05T16:57:44.111126Z","steps":["trace[481125066] 'process raft request' (duration: 124.539852ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:57:44.365829Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.563966ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/probes.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:57:44.365915Z","caller":"traceutil/trace.go:171","msg":"trace[1024750169] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/probes.monitoring.coreos.com; range_end:; response_count:0; response_revision:3916; }","duration":"153.663209ms","start":"2026-05-05T16:57:44.212232Z","end":"2026-05-05T16:57:44.365895Z","steps":["trace[1024750169] 'range keys from in-memory index tree' (duration: 153.491093ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:44.400092Z","caller":"traceutil/trace.go:171","msg":"trace[726500497] transaction","detail":"{read_only:false; response_revision:3917; number_of_response:1; }","duration":"171.28911ms","start":"2026-05-05T16:57:44.228781Z","end":"2026-05-05T16:57:44.40007Z","steps":["trace[726500497] 'process raft request' (duration: 170.879639ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:44.430358Z","caller":"traceutil/trace.go:171","msg":"trace[612097778] transaction","detail":"{read_only:false; response_revision:3918; number_of_response:1; }","duration":"176.099138ms","start":"2026-05-05T16:57:44.25423Z","end":"2026-05-05T16:57:44.43033Z","steps":["trace[612097778] 'process raft request' (duration: 175.784959ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:44.430542Z","caller":"traceutil/trace.go:171","msg":"trace[599584923] transaction","detail":"{read_only:false; response_revision:3919; number_of_response:1; }","duration":"169.86247ms","start":"2026-05-05T16:57:44.260651Z","end":"2026-05-05T16:57:44.430514Z","steps":["trace[599584923] 'process raft request' (duration: 169.632654ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:44.570038Z","caller":"traceutil/trace.go:171","msg":"trace[1172030465] transaction","detail":"{read_only:false; response_revision:3921; number_of_response:1; }","duration":"117.595121ms","start":"2026-05-05T16:57:44.452417Z","end":"2026-05-05T16:57:44.570012Z","steps":["trace[1172030465] 'process raft request' (duration: 43.092688ms)","trace[1172030465] 'compare' (duration: 74.369089ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:57:44.570031Z","caller":"traceutil/trace.go:171","msg":"trace[75795692] linearizableReadLoop","detail":"{readStateIndex:4069; appliedIndex:4068; }","duration":"100.132574ms","start":"2026-05-05T16:57:44.469874Z","end":"2026-05-05T16:57:44.570007Z","steps":["trace[75795692] 'read index received' (duration: 25.644861ms)","trace[75795692] 'applied index is now lower than readState.Index' (duration: 74.486653ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:57:44.570134Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"100.234647ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:57:44.570177Z","caller":"traceutil/trace.go:171","msg":"trace[1451258741] range","detail":"{range_begin:/registry/apiextensions.k8s.io/customresourcedefinitions/prometheusagents.monitoring.coreos.com; range_end:; response_count:0; response_revision:3921; }","duration":"100.322908ms","start":"2026-05-05T16:57:44.469841Z","end":"2026-05-05T16:57:44.570164Z","steps":["trace[1451258741] 'agreement among raft nodes before linearized reading' (duration: 100.237466ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:57:44.57032Z","caller":"traceutil/trace.go:171","msg":"trace[1265972139] transaction","detail":"{read_only:false; response_revision:3922; number_of_response:1; }","duration":"100.37764ms","start":"2026-05-05T16:57:44.469917Z","end":"2026-05-05T16:57:44.570295Z","steps":["trace[1265972139] 'process raft request' (duration: 100.200155ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:28.027284Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"106.54954ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:58:28.027618Z","caller":"traceutil/trace.go:171","msg":"trace[102623602] transaction","detail":"{read_only:false; response_revision:4494; number_of_response:1; }","duration":"119.032426ms","start":"2026-05-05T16:58:27.908557Z","end":"2026-05-05T16:58:28.02759Z","steps":["trace[102623602] 'process raft request' (duration: 12.111719ms)","trace[102623602] 'compare' (duration: 106.456396ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:33.354723Z","caller":"traceutil/trace.go:171","msg":"trace[1600638769] linearizableReadLoop","detail":"{readStateIndex:4843; appliedIndex:4842; }","duration":"360.121486ms","start":"2026-05-05T16:58:32.994522Z","end":"2026-05-05T16:58:33.354644Z","steps":["trace[1600638769] 'read index received' (duration: 268.097457ms)","trace[1600638769] 'applied index is now lower than readState.Index' (duration: 92.022819ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:33.354714Z","caller":"traceutil/trace.go:171","msg":"trace[363187631] transaction","detail":"{read_only:false; response_revision:4665; number_of_response:1; }","duration":"413.974166ms","start":"2026-05-05T16:58:32.940709Z","end":"2026-05-05T16:58:33.354683Z","steps":["trace[363187631] 'process raft request' (duration: 321.920627ms)","trace[363187631] 'compare' (duration: 91.908336ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:33.354923Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.372531ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-p7rkd\" ","response":"range_response_count:1 size:4810"} {"level":"info","ts":"2026-05-05T16:58:33.354952Z","caller":"traceutil/trace.go:171","msg":"trace[373118221] range","detail":"{range_begin:/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-p7rkd; range_end:; response_count:1; response_revision:4665; }","duration":"360.454982ms","start":"2026-05-05T16:58:32.994488Z","end":"2026-05-05T16:58:33.354943Z","steps":["trace[373118221] 'agreement among raft nodes before linearized reading' (duration: 360.312799ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:33.354977Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:32.994472Z","time spent":"360.499134ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":4832,"request content":"key:\"/registry/pods/monitoring/kube-prometheus-stack-kube-state-metrics-c7bd54d7-p7rkd\" "} {"level":"warn","ts":"2026-05-05T16:58:33.355082Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:32.940683Z","time spent":"414.297973ms","remote":"127.0.0.1:58356","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3932,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:33.930061Z","caller":"traceutil/trace.go:171","msg":"trace[114283979] linearizableReadLoop","detail":"{readStateIndex:4844; appliedIndex:4843; }","duration":"575.255576ms","start":"2026-05-05T16:58:33.354777Z","end":"2026-05-05T16:58:33.930032Z","steps":["trace[114283979] 'read index received' (duration: 496.016063ms)","trace[114283979] 'applied index is now lower than readState.Index' (duration: 79.238083ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:33.93027Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"926.415231ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:33.930328Z","caller":"traceutil/trace.go:171","msg":"trace[1767019920] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusterrestores/; range_end:/registry/pxc.percona.com/perconaxtradbclusterrestores0; response_count:0; response_revision:4666; }","duration":"926.528204ms","start":"2026-05-05T16:58:33.003783Z","end":"2026-05-05T16:58:33.930311Z","steps":["trace[1767019920] 'agreement among raft nodes before linearized reading' (duration: 926.408001ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:33.930383Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:33.003766Z","time spent":"926.601605ms","remote":"127.0.0.1:51926","response type":"/etcdserverpb.KV/Range","request count":0,"request size":116,"response count":0,"response size":27,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusterrestores/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusterrestores0\" count_only:true "} {"level":"info","ts":"2026-05-05T16:58:33.930096Z","caller":"traceutil/trace.go:171","msg":"trace[889094174] transaction","detail":"{read_only:false; response_revision:4666; number_of_response:1; }","duration":"669.72956ms","start":"2026-05-05T16:58:33.260315Z","end":"2026-05-05T16:58:33.930045Z","steps":["trace[889094174] 'process raft request' (duration: 590.462446ms)","trace[889094174] 'compare' (duration: 78.949017ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:33.930698Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:33.260301Z","time spent":"670.327653ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:33.932045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"674.109276ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a199ddac-d82f-4b08-9dae-081a62d0f4b2\" ","response":"range_response_count:1 size:4053"} {"level":"info","ts":"2026-05-05T16:58:33.932103Z","caller":"traceutil/trace.go:171","msg":"trace[284271810] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a199ddac-d82f-4b08-9dae-081a62d0f4b2; range_end:; response_count:1; response_revision:4666; }","duration":"674.205359ms","start":"2026-05-05T16:58:33.25788Z","end":"2026-05-05T16:58:33.932086Z","steps":["trace[284271810] 'agreement among raft nodes before linearized reading' (duration: 672.159604ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:33.932142Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:33.257861Z","time spent":"674.27039ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4075,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a199ddac-d82f-4b08-9dae-081a62d0f4b2\" "} {"level":"info","ts":"2026-05-05T16:58:34.195056Z","caller":"traceutil/trace.go:171","msg":"trace[8660286] transaction","detail":"{read_only:false; response_revision:4668; number_of_response:1; }","duration":"834.186669ms","start":"2026-05-05T16:58:33.360837Z","end":"2026-05-05T16:58:34.195023Z","steps":["trace[8660286] 'process raft request' (duration: 833.832011ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:34.195276Z","caller":"traceutil/trace.go:171","msg":"trace[976092409] transaction","detail":"{read_only:false; response_revision:4667; number_of_response:1; }","duration":"834.549897ms","start":"2026-05-05T16:58:33.360705Z","end":"2026-05-05T16:58:34.195255Z","steps":["trace[976092409] 'process raft request' (duration: 761.881999ms)","trace[976092409] 'compare' (duration: 71.777258ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:34.195306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:33.360798Z","time spent":"834.414994ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":5123,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:34.195435Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.029538ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:493"} {"level":"warn","ts":"2026-05-05T16:58:34.195447Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:33.360687Z","time spent":"834.674029ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":4554,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:34.195494Z","caller":"traceutil/trace.go:171","msg":"trace[246489903] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4668; }","duration":"269.13ms","start":"2026-05-05T16:58:33.926348Z","end":"2026-05-05T16:58:34.195478Z","steps":["trace[246489903] 'agreement among raft nodes before linearized reading' (duration: 269.003338ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:34.195312Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.625546ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-91de7d37-ee75-4ddf-a178-8a40897fcf20\" ","response":"range_response_count:1 size:4067"} {"level":"warn","ts":"2026-05-05T16:58:34.195616Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"412.969027ms","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-05-05T16:58:34.195682Z","caller":"traceutil/trace.go:171","msg":"trace[1023726774] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4668; }","duration":"413.09221ms","start":"2026-05-05T16:58:33.782574Z","end":"2026-05-05T16:58:34.195666Z","steps":["trace[1023726774] 'agreement among raft nodes before linearized reading' (duration: 412.841354ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:34.195699Z","caller":"traceutil/trace.go:171","msg":"trace[2137876284] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-91de7d37-ee75-4ddf-a178-8a40897fcf20; range_end:; response_count:1; response_revision:4668; }","duration":"348.056635ms","start":"2026-05-05T16:58:33.847623Z","end":"2026-05-05T16:58:34.195679Z","steps":["trace[2137876284] 'agreement among raft nodes before linearized reading' (duration: 347.471382ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:34.195799Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:33.847609Z","time spent":"348.169508ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":4089,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-91de7d37-ee75-4ddf-a178-8a40897fcf20\" "} {"level":"warn","ts":"2026-05-05T16:58:34.195716Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:33.782547Z","time spent":"413.162511ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":579,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-05-05T16:58:34.196743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"423.039673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:34.196832Z","caller":"traceutil/trace.go:171","msg":"trace[1580935084] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4668; }","duration":"423.168186ms","start":"2026-05-05T16:58:33.773651Z","end":"2026-05-05T16:58:34.196819Z","steps":["trace[1580935084] 'agreement among raft nodes before linearized reading' (duration: 423.050363ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:34.196897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:33.773631Z","time spent":"423.256127ms","remote":"127.0.0.1:58048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-05-05T16:58:34.197423Z","caller":"traceutil/trace.go:171","msg":"trace[1398974898] linearizableReadLoop","detail":"{readStateIndex:4846; appliedIndex:4844; }","duration":"264.628709ms","start":"2026-05-05T16:58:33.93016Z","end":"2026-05-05T16:58:34.194789Z","steps":["trace[1398974898] 'read index received' (duration: 192.33344ms)","trace[1398974898] 'applied index is now lower than readState.Index' (duration: 72.294219ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:34.197836Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.854155ms","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-05-05T16:58:34.197874Z","caller":"traceutil/trace.go:171","msg":"trace[270150487] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4668; }","duration":"261.474549ms","start":"2026-05-05T16:58:33.936389Z","end":"2026-05-05T16:58:34.197864Z","steps":["trace[270150487] 'agreement among raft nodes before linearized reading' (duration: 261.258054ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:34.198167Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"129.80526ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"info","ts":"2026-05-05T16:58:34.19821Z","caller":"traceutil/trace.go:171","msg":"trace[1231850153] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4668; }","duration":"129.884581ms","start":"2026-05-05T16:58:34.068316Z","end":"2026-05-05T16:58:34.1982Z","steps":["trace[1231850153] 'agreement among raft nodes before linearized reading' (duration: 129.780309ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:34.198279Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.839287ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/tlsroutes/\" range_end:\"/registry/gateway.networking.k8s.io/tlsroutes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:34.198334Z","caller":"traceutil/trace.go:171","msg":"trace[896446291] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/tlsroutes/; range_end:/registry/gateway.networking.k8s.io/tlsroutes0; response_count:0; response_revision:4668; }","duration":"176.94663ms","start":"2026-05-05T16:58:34.021373Z","end":"2026-05-05T16:58:34.198319Z","steps":["trace[896446291] 'agreement among raft nodes before linearized reading' (duration: 176.845247ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:34.198445Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.085956ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-05-05T16:58:34.198483Z","caller":"traceutil/trace.go:171","msg":"trace[1104240992] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4668; }","duration":"152.150357ms","start":"2026-05-05T16:58:34.046323Z","end":"2026-05-05T16:58:34.198474Z","steps":["trace[1104240992] 'agreement among raft nodes before linearized reading' (duration: 152.033884ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:36.051912Z","caller":"traceutil/trace.go:171","msg":"trace[1743466953] linearizableReadLoop","detail":"{readStateIndex:4893; appliedIndex:4892; }","duration":"109.515463ms","start":"2026-05-05T16:58:35.942377Z","end":"2026-05-05T16:58:36.051893Z","steps":["trace[1743466953] 'read index received' (duration: 109.346029ms)","trace[1743466953] 'applied index is now lower than readState.Index' (duration: 168.514µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:36.052223Z","caller":"traceutil/trace.go:171","msg":"trace[1009138933] transaction","detail":"{read_only:false; response_revision:4714; number_of_response:1; }","duration":"109.918862ms","start":"2026-05-05T16:58:35.942295Z","end":"2026-05-05T16:58:36.052213Z","steps":["trace[1009138933] 'process raft request' (duration: 109.473782ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:36.052297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"108.312595ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-a199ddac-d82f-4b08-9dae-081a62d0f4b2\" ","response":"range_response_count:1 size:4068"} {"level":"info","ts":"2026-05-05T16:58:36.052391Z","caller":"traceutil/trace.go:171","msg":"trace[705290796] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-a199ddac-d82f-4b08-9dae-081a62d0f4b2; range_end:; response_count:1; response_revision:4714; }","duration":"108.440709ms","start":"2026-05-05T16:58:35.94392Z","end":"2026-05-05T16:58:36.052361Z","steps":["trace[705290796] 'agreement among raft nodes before linearized reading' (duration: 108.209934ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:36.052637Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.258369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12974"} {"level":"info","ts":"2026-05-05T16:58:36.052664Z","caller":"traceutil/trace.go:171","msg":"trace[1048137092] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4714; }","duration":"110.31549ms","start":"2026-05-05T16:58:35.942342Z","end":"2026-05-05T16:58:36.052658Z","steps":["trace[1048137092] 'agreement among raft nodes before linearized reading' (duration: 110.233098ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:44.224969Z","caller":"traceutil/trace.go:171","msg":"trace[672246723] transaction","detail":"{read_only:false; response_revision:4869; number_of_response:1; }","duration":"209.178273ms","start":"2026-05-05T16:58:44.015773Z","end":"2026-05-05T16:58:44.224952Z","steps":["trace[672246723] 'process raft request' (duration: 209.08146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:44.576622Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.610828ms","expected-duration":"100ms","prefix":"read-only range ","request":"limit:1 serializable:true keys_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:44.576745Z","caller":"traceutil/trace.go:171","msg":"trace[525397789] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:4869; }","duration":"198.751921ms","start":"2026-05-05T16:58:44.377976Z","end":"2026-05-05T16:58:44.576728Z","steps":["trace[525397789] 'range keys from in-memory index tree' (duration: 198.596728ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:44.577371Z","caller":"traceutil/trace.go:171","msg":"trace[1854043722] linearizableReadLoop","detail":"{readStateIndex:5054; appliedIndex:5052; }","duration":"493.030735ms","start":"2026-05-05T16:58:44.084316Z","end":"2026-05-05T16:58:44.577347Z","steps":["trace[1854043722] 'read index received' (duration: 140.545863ms)","trace[1854043722] 'applied index is now lower than readState.Index' (duration: 352.483802ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:44.577548Z","caller":"traceutil/trace.go:171","msg":"trace[2145130536] transaction","detail":"{read_only:false; response_revision:4871; number_of_response:1; }","duration":"492.948853ms","start":"2026-05-05T16:58:44.084587Z","end":"2026-05-05T16:58:44.577536Z","steps":["trace[2145130536] 'process raft request' (duration: 492.653937ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:44.577592Z","caller":"traceutil/trace.go:171","msg":"trace[1925677827] transaction","detail":"{read_only:false; response_revision:4870; number_of_response:1; }","duration":"553.603004ms","start":"2026-05-05T16:58:44.023941Z","end":"2026-05-05T16:58:44.577544Z","steps":["trace[1925677827] 'process raft request' (duration: 508.747129ms)","trace[1925677827] 'compare' (duration: 43.819053ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:44.577726Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:44.0845Z","time spent":"493.079036ms","remote":"127.0.0.1:58382","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:44.57777Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:44.023924Z","time spent":"553.753218ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":11830,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-05-05T16:58:44.577826Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"493.506365ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11095a92-e2d5-487d-a39c-a269fd18f876\" ","response":"range_response_count:1 size:3646"} {"level":"info","ts":"2026-05-05T16:58:44.57793Z","caller":"traceutil/trace.go:171","msg":"trace[808393239] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-11095a92-e2d5-487d-a39c-a269fd18f876; range_end:; response_count:1; response_revision:4871; }","duration":"493.650169ms","start":"2026-05-05T16:58:44.084263Z","end":"2026-05-05T16:58:44.577913Z","steps":["trace[808393239] 'agreement among raft nodes before linearized reading' (duration: 493.311051ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:44.578001Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:44.084244Z","time spent":"493.72236ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":94,"response count":1,"response size":3668,"request content":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-11095a92-e2d5-487d-a39c-a269fd18f876\" "} {"level":"warn","ts":"2026-05-05T16:58:44.578295Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"221.847452ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-05T16:58:44.578331Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"193.141171ms","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-05-05T16:58:44.578388Z","caller":"traceutil/trace.go:171","msg":"trace[1748950584] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4871; }","duration":"221.917544ms","start":"2026-05-05T16:58:44.356421Z","end":"2026-05-05T16:58:44.578338Z","steps":["trace[1748950584] 'agreement among raft nodes before linearized reading' (duration: 221.773641ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:44.578418Z","caller":"traceutil/trace.go:171","msg":"trace[743186488] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4871; }","duration":"193.222253ms","start":"2026-05-05T16:58:44.385151Z","end":"2026-05-05T16:58:44.578373Z","steps":["trace[743186488] 'agreement among raft nodes before linearized reading' (duration: 193.05797ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:44.578484Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"139.832586ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secretgen.k14s.io/sshkeys/\" range_end:\"/registry/secretgen.k14s.io/sshkeys0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:44.57854Z","caller":"traceutil/trace.go:171","msg":"trace[874078276] range","detail":"{range_begin:/registry/secretgen.k14s.io/sshkeys/; range_end:/registry/secretgen.k14s.io/sshkeys0; response_count:0; response_revision:4871; }","duration":"139.99616ms","start":"2026-05-05T16:58:44.438528Z","end":"2026-05-05T16:58:44.578524Z","steps":["trace[874078276] 'agreement among raft nodes before linearized reading' (duration: 139.909369ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:44.578334Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.816603ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-05-05T16:58:44.578616Z","caller":"traceutil/trace.go:171","msg":"trace[3545891] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:4871; }","duration":"223.12507ms","start":"2026-05-05T16:58:44.355476Z","end":"2026-05-05T16:58:44.578601Z","steps":["trace[3545891] 'agreement among raft nodes before linearized reading' (duration: 222.722052ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:44.578759Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.298835ms","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-05-05T16:58:44.578899Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.192369ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T16:58:44.578968Z","caller":"traceutil/trace.go:171","msg":"trace[49388890] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4871; }","duration":"222.296222ms","start":"2026-05-05T16:58:44.356664Z","end":"2026-05-05T16:58:44.578961Z","steps":["trace[49388890] 'agreement among raft nodes before linearized reading' (duration: 221.987615ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:44.579198Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.015203ms","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-05-05T16:58:44.579223Z","caller":"traceutil/trace.go:171","msg":"trace[425804090] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4871; }","duration":"228.072845ms","start":"2026-05-05T16:58:44.351144Z","end":"2026-05-05T16:58:44.579217Z","steps":["trace[425804090] 'agreement among raft nodes before linearized reading' (duration: 227.968743ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:44.579553Z","caller":"traceutil/trace.go:171","msg":"trace[2108812451] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:4871; }","duration":"263.405407ms","start":"2026-05-05T16:58:44.315411Z","end":"2026-05-05T16:58:44.578817Z","steps":["trace[2108812451] 'agreement among raft nodes before linearized reading' (duration: 262.747453ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:44.581723Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"225.305845ms","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-05-05T16:58:44.581795Z","caller":"traceutil/trace.go:171","msg":"trace[1086029928] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4871; }","duration":"225.425078ms","start":"2026-05-05T16:58:44.356353Z","end":"2026-05-05T16:58:44.581778Z","steps":["trace[1086029928] 'agreement among raft nodes before linearized reading' (duration: 222.016406ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.545339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.404995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:10392"} {"level":"info","ts":"2026-05-05T16:58:58.545419Z","caller":"traceutil/trace.go:171","msg":"trace[1959578012] range","detail":"{range_begin:/registry/pods/openstack/rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5042; }","duration":"414.532057ms","start":"2026-05-05T16:58:58.13087Z","end":"2026-05-05T16:58:58.545402Z","steps":["trace[1959578012] 'range keys from in-memory index tree' (duration: 414.25388ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.545427Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"162.859354ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/certificatesigningrequests/\" range_end:\"/registry/certificatesigningrequests0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-05-05T16:58:58.545456Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:58.130851Z","time spent":"414.596718ms","remote":"127.0.0.1:58268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":53,"response count":1,"response size":10414,"request content":"key:\"/registry/pods/openstack/rabbitmq-keystone-server-0\" "} {"level":"info","ts":"2026-05-05T16:58:58.545497Z","caller":"traceutil/trace.go:171","msg":"trace[369614597] range","detail":"{range_begin:/registry/certificatesigningrequests/; range_end:/registry/certificatesigningrequests0; response_count:0; response_revision:5042; }","duration":"162.975727ms","start":"2026-05-05T16:58:58.382502Z","end":"2026-05-05T16:58:58.545477Z","steps":["trace[369614597] 'count revisions from in-memory index tree' (duration: 162.782263ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.545743Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"773.104002ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:58.545782Z","caller":"traceutil/trace.go:171","msg":"trace[1569765758] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5042; }","duration":"773.167354ms","start":"2026-05-05T16:58:57.772602Z","end":"2026-05-05T16:58:58.54577Z","steps":["trace[1569765758] 'range keys from in-memory index tree' (duration: 773.043921ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.545785Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"386.777521ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"warn","ts":"2026-05-05T16:58:58.54585Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"732.425544ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:58.545861Z","caller":"traceutil/trace.go:171","msg":"trace[91007475] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5042; }","duration":"386.891413ms","start":"2026-05-05T16:58:58.158948Z","end":"2026-05-05T16:58:58.545839Z","steps":["trace[91007475] 'range keys from in-memory index tree' (duration: 386.607477ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:58.545881Z","caller":"traceutil/trace.go:171","msg":"trace[1696180515] range","detail":"{range_begin:/registry/cilium.io/ciliumclusterwidenetworkpolicies/; range_end:/registry/cilium.io/ciliumclusterwidenetworkpolicies0; response_count:0; response_revision:5042; }","duration":"732.484106ms","start":"2026-05-05T16:58:57.813386Z","end":"2026-05-05T16:58:58.545871Z","steps":["trace[1696180515] 'count revisions from in-memory index tree' (duration: 732.278851ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.545911Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:57.813372Z","time spent":"732.529936ms","remote":"127.0.0.1:57998","response type":"/etcdserverpb.KV/Range","request count":0,"request size":112,"response count":0,"response size":27,"request content":"key:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies/\" range_end:\"/registry/cilium.io/ciliumclusterwidenetworkpolicies0\" count_only:true "} {"level":"warn","ts":"2026-05-05T16:58:58.545913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:58.158931Z","time spent":"386.968384ms","remote":"127.0.0.1:58222","response type":"/etcdserverpb.KV/Range","request count":0,"request size":83,"response count":1,"response size":1887,"request content":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" "} {"level":"warn","ts":"2026-05-05T16:58:58.546055Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"479.263304ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-05-05T16:58:58.54609Z","caller":"traceutil/trace.go:171","msg":"trace[2048652817] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5042; }","duration":"479.333465ms","start":"2026-05-05T16:58:58.066748Z","end":"2026-05-05T16:58:58.546081Z","steps":["trace[2048652817] 'range keys from in-memory index tree' (duration: 479.135282ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.546117Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:58.06673Z","time spent":"479.380036ms","remote":"127.0.0.1:44410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-05-05T16:58:58.54581Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:57.772586Z","time spent":"773.215484ms","remote":"127.0.0.1:58048","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-05-05T16:58:58.546207Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"726.450394ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3244"} {"level":"info","ts":"2026-05-05T16:58:58.546352Z","caller":"traceutil/trace.go:171","msg":"trace[1137867181] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:5042; }","duration":"726.644768ms","start":"2026-05-05T16:58:57.819685Z","end":"2026-05-05T16:58:58.54633Z","steps":["trace[1137867181] 'range keys from in-memory index tree' (duration: 726.31354ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.546409Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:57.819671Z","time spent":"726.720159ms","remote":"127.0.0.1:44410","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3266,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"warn","ts":"2026-05-05T16:58:58.547302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"248.851937ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T16:58:58.547376Z","caller":"traceutil/trace.go:171","msg":"trace[1493705922] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:5042; }","duration":"248.954139ms","start":"2026-05-05T16:58:58.298408Z","end":"2026-05-05T16:58:58.547362Z","steps":["trace[1493705922] 'count revisions from in-memory index tree' (duration: 248.798935ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.870039Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"217.683551ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T16:58:58.870353Z","caller":"traceutil/trace.go:171","msg":"trace[1171356648] transaction","detail":"{read_only:false; response_revision:5048; number_of_response:1; }","duration":"227.827706ms","start":"2026-05-05T16:58:58.642508Z","end":"2026-05-05T16:58:58.870336Z","steps":["trace[1171356648] 'process raft request' (duration: 227.741444ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:58.870463Z","caller":"traceutil/trace.go:171","msg":"trace[709662145] transaction","detail":"{read_only:false; response_revision:5045; number_of_response:1; }","duration":"309.437582ms","start":"2026-05-05T16:58:58.560982Z","end":"2026-05-05T16:58:58.87042Z","steps":["trace[709662145] 'process raft request' (duration: 91.27594ms)","trace[709662145] 'compare' (duration: 217.564189ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:58.870448Z","caller":"traceutil/trace.go:171","msg":"trace[276500602] transaction","detail":"{read_only:false; response_revision:5047; number_of_response:1; }","duration":"229.407887ms","start":"2026-05-05T16:58:58.641002Z","end":"2026-05-05T16:58:58.87041Z","steps":["trace[276500602] 'process raft request' (duration: 229.201352ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:58.870502Z","caller":"traceutil/trace.go:171","msg":"trace[780901200] linearizableReadLoop","detail":"{readStateIndex:5237; appliedIndex:5235; }","duration":"231.239135ms","start":"2026-05-05T16:58:58.639231Z","end":"2026-05-05T16:58:58.870469Z","steps":["trace[780901200] 'read index received' (duration: 12.998251ms)","trace[780901200] 'applied index is now lower than readState.Index' (duration: 218.239104ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T16:58:58.870587Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-05-05T16:58:58.560964Z","time spent":"309.564214ms","remote":"127.0.0.1:58122","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":731,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-05-05T16:58:58.870607Z","caller":"traceutil/trace.go:171","msg":"trace[1302380506] transaction","detail":"{read_only:false; response_revision:5046; number_of_response:1; }","duration":"249.51986ms","start":"2026-05-05T16:58:58.621076Z","end":"2026-05-05T16:58:58.870596Z","steps":["trace[1302380506] 'process raft request' (duration: 249.05893ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:58.87076Z","caller":"traceutil/trace.go:171","msg":"trace[979939547] transaction","detail":"{read_only:false; response_revision:5049; number_of_response:1; }","duration":"228.011569ms","start":"2026-05-05T16:58:58.642649Z","end":"2026-05-05T16:58:58.870661Z","steps":["trace[979939547] 'process raft request' (duration: 227.645792ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.870849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"231.597122ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0\" ","response":"range_response_count:1 size:1865"} {"level":"info","ts":"2026-05-05T16:58:58.870898Z","caller":"traceutil/trace.go:171","msg":"trace[1896614118] range","detail":"{range_begin:/registry/persistentvolumeclaims/openstack/persistence-rabbitmq-keystone-server-0; range_end:; response_count:1; response_revision:5049; }","duration":"231.684753ms","start":"2026-05-05T16:58:58.6392Z","end":"2026-05-05T16:58:58.870884Z","steps":["trace[1896614118] 'agreement among raft nodes before linearized reading' (duration: 231.428578ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:58.871033Z","caller":"traceutil/trace.go:171","msg":"trace[1938962857] transaction","detail":"{read_only:false; response_revision:5050; number_of_response:1; }","duration":"228.325435ms","start":"2026-05-05T16:58:58.642694Z","end":"2026-05-05T16:58:58.87102Z","steps":["trace[1938962857] 'process raft request' (duration: 227.855146ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.871154Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"206.925467ms","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-05-05T16:58:58.871194Z","caller":"traceutil/trace.go:171","msg":"trace[1322078572] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:5050; }","duration":"206.988599ms","start":"2026-05-05T16:58:58.664195Z","end":"2026-05-05T16:58:58.871184Z","steps":["trace[1322078572] 'agreement among raft nodes before linearized reading' (duration: 206.875476ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.871325Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.067786ms","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-05-05T16:58:58.871393Z","caller":"traceutil/trace.go:171","msg":"trace[1912724934] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:5050; }","duration":"120.167138ms","start":"2026-05-05T16:58:58.751209Z","end":"2026-05-05T16:58:58.871376Z","steps":["trace[1912724934] 'agreement among raft nodes before linearized reading' (duration: 120.010825ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.871432Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.782401ms","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-05-05T16:58:58.871486Z","caller":"traceutil/trace.go:171","msg":"trace[402735060] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:5050; }","duration":"121.857432ms","start":"2026-05-05T16:58:58.749615Z","end":"2026-05-05T16:58:58.871473Z","steps":["trace[402735060] 'agreement among raft nodes before linearized reading' (duration: 121.72768ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.871619Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.617317ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:480"} {"level":"warn","ts":"2026-05-05T16:58:58.871607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.305311ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"info","ts":"2026-05-05T16:58:58.87165Z","caller":"traceutil/trace.go:171","msg":"trace[1640703339] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:5050; }","duration":"125.676719ms","start":"2026-05-05T16:58:58.745965Z","end":"2026-05-05T16:58:58.871642Z","steps":["trace[1640703339] 'agreement among raft nodes before linearized reading' (duration: 125.598287ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:58.871667Z","caller":"traceutil/trace.go:171","msg":"trace[1053942519] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:5050; }","duration":"120.366472ms","start":"2026-05-05T16:58:58.751286Z","end":"2026-05-05T16:58:58.871653Z","steps":["trace[1053942519] 'agreement among raft nodes before linearized reading' (duration: 120.224289ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:58.871815Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.906954ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-05-05T16:58:58.871849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.573256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/openstack/messaging-topology-operator-leader-election\" ","response":"range_response_count:1 size:572"} {"level":"info","ts":"2026-05-05T16:58:58.871874Z","caller":"traceutil/trace.go:171","msg":"trace[1785221416] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5050; }","duration":"148.008976ms","start":"2026-05-05T16:58:58.72385Z","end":"2026-05-05T16:58:58.871859Z","steps":["trace[1785221416] 'agreement among raft nodes before linearized reading' (duration: 147.825692ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:58.87189Z","caller":"traceutil/trace.go:171","msg":"trace[437534861] range","detail":"{range_begin:/registry/leases/openstack/messaging-topology-operator-leader-election; range_end:; response_count:1; response_revision:5050; }","duration":"135.677178ms","start":"2026-05-05T16:58:58.7362Z","end":"2026-05-05T16:58:58.871877Z","steps":["trace[437534861] 'agreement among raft nodes before linearized reading' (duration: 135.537706ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:59.141543Z","caller":"traceutil/trace.go:171","msg":"trace[1444326201] transaction","detail":"{read_only:false; response_revision:5052; number_of_response:1; }","duration":"263.536579ms","start":"2026-05-05T16:58:58.877964Z","end":"2026-05-05T16:58:59.1415Z","steps":["trace[1444326201] 'process raft request' (duration: 263.377596ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:59.1418Z","caller":"traceutil/trace.go:171","msg":"trace[1895271056] transaction","detail":"{read_only:false; response_revision:5053; number_of_response:1; }","duration":"263.54292ms","start":"2026-05-05T16:58:58.878234Z","end":"2026-05-05T16:58:59.141777Z","steps":["trace[1895271056] 'process raft request' (duration: 263.230474ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:59.141946Z","caller":"traceutil/trace.go:171","msg":"trace[1384190506] linearizableReadLoop","detail":"{readStateIndex:5247; appliedIndex:5241; }","duration":"263.10809ms","start":"2026-05-05T16:58:58.878827Z","end":"2026-05-05T16:58:59.141935Z","steps":["trace[1384190506] 'read index received' (duration: 76.341509ms)","trace[1384190506] 'applied index is now lower than readState.Index' (duration: 186.765861ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T16:58:59.141951Z","caller":"traceutil/trace.go:171","msg":"trace[960321570] transaction","detail":"{read_only:false; response_revision:5055; number_of_response:1; }","duration":"263.56459ms","start":"2026-05-05T16:58:58.878359Z","end":"2026-05-05T16:58:59.141923Z","steps":["trace[960321570] 'process raft request' (duration: 263.463288ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:59.141992Z","caller":"traceutil/trace.go:171","msg":"trace[1284188189] transaction","detail":"{read_only:false; response_revision:5057; number_of_response:1; }","duration":"262.944906ms","start":"2026-05-05T16:58:58.879008Z","end":"2026-05-05T16:58:59.141953Z","steps":["trace[1284188189] 'process raft request' (duration: 262.876805ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:59.142016Z","caller":"traceutil/trace.go:171","msg":"trace[706141516] transaction","detail":"{read_only:false; response_revision:5058; number_of_response:1; }","duration":"234.533928ms","start":"2026-05-05T16:58:58.907475Z","end":"2026-05-05T16:58:59.142008Z","steps":["trace[706141516] 'process raft request' (duration: 234.435005ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:59.142057Z","caller":"traceutil/trace.go:171","msg":"trace[1579939569] transaction","detail":"{read_only:false; response_revision:5054; number_of_response:1; }","duration":"263.708794ms","start":"2026-05-05T16:58:58.878338Z","end":"2026-05-05T16:58:59.142047Z","steps":["trace[1579939569] 'process raft request' (duration: 263.378887ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:58:59.142067Z","caller":"traceutil/trace.go:171","msg":"trace[666327238] transaction","detail":"{read_only:false; response_revision:5056; number_of_response:1; }","duration":"263.631752ms","start":"2026-05-05T16:58:58.878427Z","end":"2026-05-05T16:58:59.142059Z","steps":["trace[666327238] 'process raft request' (duration: 263.432257ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:59.14218Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"263.367786ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/rabbitmq-keystone.18acb9dfa5bd9727\" ","response":"range_response_count:1 size:740"} {"level":"info","ts":"2026-05-05T16:58:59.142208Z","caller":"traceutil/trace.go:171","msg":"trace[288113296] range","detail":"{range_begin:/registry/events/openstack/rabbitmq-keystone.18acb9dfa5bd9727; range_end:; response_count:1; response_revision:5058; }","duration":"263.545309ms","start":"2026-05-05T16:58:58.878655Z","end":"2026-05-05T16:58:59.1422Z","steps":["trace[288113296] 'agreement among raft nodes before linearized reading' (duration: 263.420147ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T16:58:59.142243Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.395846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9635"} {"level":"info","ts":"2026-05-05T16:58:59.142285Z","caller":"traceutil/trace.go:171","msg":"trace[384058961] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:5058; }","duration":"260.460188ms","start":"2026-05-05T16:58:58.881814Z","end":"2026-05-05T16:58:59.142274Z","steps":["trace[384058961] 'agreement among raft nodes before linearized reading' (duration: 260.325735ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:59:27.405672Z","caller":"traceutil/trace.go:171","msg":"trace[807035147] transaction","detail":"{read_only:false; response_revision:5343; number_of_response:1; }","duration":"100.085747ms","start":"2026-05-05T16:59:27.305561Z","end":"2026-05-05T16:59:27.405647Z","steps":["trace[807035147] 'process raft request' (duration: 99.17541ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:59:47.517077Z","caller":"traceutil/trace.go:171","msg":"trace[1824523329] transaction","detail":"{read_only:false; response_revision:5480; number_of_response:1; }","duration":"120.839247ms","start":"2026-05-05T16:59:47.396207Z","end":"2026-05-05T16:59:47.517047Z","steps":["trace[1824523329] 'process raft request' (duration: 120.608483ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:59:51.691499Z","caller":"traceutil/trace.go:171","msg":"trace[990156740] transaction","detail":"{read_only:false; response_revision:5508; number_of_response:1; }","duration":"109.339421ms","start":"2026-05-05T16:59:51.582134Z","end":"2026-05-05T16:59:51.691474Z","steps":["trace[990156740] 'process raft request' (duration: 109.165779ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:59:51.691906Z","caller":"traceutil/trace.go:171","msg":"trace[2066049218] transaction","detail":"{read_only:false; response_revision:5509; number_of_response:1; }","duration":"108.961686ms","start":"2026-05-05T16:59:51.582908Z","end":"2026-05-05T16:59:51.691869Z","steps":["trace[2066049218] 'process raft request' (duration: 108.527319ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:59:51.692105Z","caller":"traceutil/trace.go:171","msg":"trace[914303070] transaction","detail":"{read_only:false; response_revision:5510; number_of_response:1; }","duration":"108.940245ms","start":"2026-05-05T16:59:51.583084Z","end":"2026-05-05T16:59:51.692025Z","steps":["trace[914303070] 'process raft request' (duration: 108.654241ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:59:51.692307Z","caller":"traceutil/trace.go:171","msg":"trace[1760992002] transaction","detail":"{read_only:false; response_revision:5511; number_of_response:1; }","duration":"108.400595ms","start":"2026-05-05T16:59:51.583892Z","end":"2026-05-05T16:59:51.692292Z","steps":["trace[1760992002] 'process raft request' (duration: 108.07235ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T16:59:52.444366Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2893} {"level":"info","ts":"2026-05-05T16:59:52.525019Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2893,"took":"79.367817ms","hash":3662479780,"current-db-size-bytes":53121024,"current-db-size":"53 MB","current-db-size-in-use-bytes":28897280,"current-db-size-in-use":"29 MB"} {"level":"info","ts":"2026-05-05T16:59:52.525119Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3662479780,"revision":2893,"compact-revision":-1} {"level":"info","ts":"2026-05-05T17:00:19.805389Z","caller":"traceutil/trace.go:171","msg":"trace[1352310278] transaction","detail":"{read_only:false; response_revision:5707; number_of_response:1; }","duration":"119.473151ms","start":"2026-05-05T17:00:19.685894Z","end":"2026-05-05T17:00:19.805367Z","steps":["trace[1352310278] 'process raft request' (duration: 119.297638ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:00:19.893285Z","caller":"traceutil/trace.go:171","msg":"trace[290810174] linearizableReadLoop","detail":"{readStateIndex:5919; appliedIndex:5916; }","duration":"120.338553ms","start":"2026-05-05T17:00:19.772921Z","end":"2026-05-05T17:00:19.89326Z","steps":["trace[290810174] 'read index received' (duration: 32.277584ms)","trace[290810174] 'applied index is now lower than readState.Index' (duration: 88.059779ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:00:19.89343Z","caller":"traceutil/trace.go:171","msg":"trace[2088081530] transaction","detail":"{read_only:false; response_revision:5708; number_of_response:1; }","duration":"201.575491ms","start":"2026-05-05T17:00:19.691827Z","end":"2026-05-05T17:00:19.893403Z","steps":["trace[2088081530] 'process raft request' (duration: 189.049734ms)","trace[2088081530] 'compare' (duration: 12.172062ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:00:19.893466Z","caller":"traceutil/trace.go:171","msg":"trace[77250195] transaction","detail":"{read_only:false; response_revision:5709; number_of_response:1; }","duration":"165.059743ms","start":"2026-05-05T17:00:19.728385Z","end":"2026-05-05T17:00:19.893445Z","steps":["trace[77250195] 'process raft request' (duration: 164.80515ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:00:19.893662Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.727598ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T17:00:19.893706Z","caller":"traceutil/trace.go:171","msg":"trace[1514323437] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5709; }","duration":"120.81172ms","start":"2026-05-05T17:00:19.772882Z","end":"2026-05-05T17:00:19.893694Z","steps":["trace[1514323437] 'agreement among raft nodes before linearized reading' (duration: 120.719659ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:19.519839Z","caller":"traceutil/trace.go:171","msg":"trace[425373136] linearizableReadLoop","detail":"{readStateIndex:6776; appliedIndex:6775; }","duration":"198.613387ms","start":"2026-05-05T17:02:19.3212Z","end":"2026-05-05T17:02:19.519813Z","steps":["trace[425373136] 'read index received' (duration: 198.385263ms)","trace[425373136] 'applied index is now lower than readState.Index' (duration: 226.764µs)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:02:19.519961Z","caller":"traceutil/trace.go:171","msg":"trace[723820990] transaction","detail":"{read_only:false; response_revision:6538; number_of_response:1; }","duration":"202.777475ms","start":"2026-05-05T17:02:19.317174Z","end":"2026-05-05T17:02:19.519951Z","steps":["trace[723820990] 'process raft request' (duration: 202.476541ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:19.520294Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.078302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T17:02:19.52036Z","caller":"traceutil/trace.go:171","msg":"trace[184015781] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:0; response_revision:6538; }","duration":"199.184523ms","start":"2026-05-05T17:02:19.321161Z","end":"2026-05-05T17:02:19.520345Z","steps":["trace[184015781] 'agreement among raft nodes before linearized reading' (duration: 199.078892ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:19.520626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"192.392846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/horizon-db-sync\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T17:02:19.520664Z","caller":"traceutil/trace.go:171","msg":"trace[2008804549] range","detail":"{range_begin:/registry/jobs/openstack/horizon-db-sync; range_end:; response_count:0; response_revision:6538; }","duration":"192.465026ms","start":"2026-05-05T17:02:19.328191Z","end":"2026-05-05T17:02:19.520656Z","steps":["trace[2008804549] 'agreement among raft nodes before linearized reading' (duration: 191.85693ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:38.61551Z","caller":"traceutil/trace.go:171","msg":"trace[129879665] transaction","detail":"{read_only:false; response_revision:6661; number_of_response:1; }","duration":"225.672648ms","start":"2026-05-05T17:02:38.389815Z","end":"2026-05-05T17:02:38.615487Z","steps":["trace[129879665] 'process raft request' (duration: 225.518707ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:02:38.789992Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"137.783554ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T17:02:38.790161Z","caller":"traceutil/trace.go:171","msg":"trace[1718942914] transaction","detail":"{read_only:false; response_revision:6662; number_of_response:1; }","duration":"161.533169ms","start":"2026-05-05T17:02:38.628609Z","end":"2026-05-05T17:02:38.790142Z","steps":["trace[1718942914] 'process raft request' (duration: 23.508353ms)","trace[1718942914] 'compare' (duration: 137.598672ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:02:39.029168Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"119.907733ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-05-05T17:02:39.029385Z","caller":"traceutil/trace.go:171","msg":"trace[1218626971] transaction","detail":"{read_only:false; response_revision:6665; number_of_response:1; }","duration":"233.85461ms","start":"2026-05-05T17:02:38.795511Z","end":"2026-05-05T17:02:39.029365Z","steps":["trace[1218626971] 'process raft request' (duration: 233.747269ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:39.029369Z","caller":"traceutil/trace.go:171","msg":"trace[1085178286] transaction","detail":"{read_only:false; response_revision:6664; number_of_response:1; }","duration":"233.921171ms","start":"2026-05-05T17:02:38.795422Z","end":"2026-05-05T17:02:39.029343Z","steps":["trace[1085178286] 'process raft request' (duration: 113.768905ms)","trace[1085178286] 'compare' (duration: 119.561339ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:02:40.069499Z","caller":"traceutil/trace.go:171","msg":"trace[1912338739] linearizableReadLoop","detail":"{readStateIndex:6911; appliedIndex:6909; }","duration":"138.640951ms","start":"2026-05-05T17:02:39.930836Z","end":"2026-05-05T17:02:40.069476Z","steps":["trace[1912338739] 'read index received' (duration: 10.870222ms)","trace[1912338739] 'applied index is now lower than readState.Index' (duration: 127.769389ms)"],"step_count":2} {"level":"warn","ts":"2026-05-05T17:02:40.06972Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"138.816042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/openstack/percona-xtradb-pitr\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-05-05T17:02:40.06982Z","caller":"traceutil/trace.go:171","msg":"trace[496976523] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:6669; }","duration":"139.006834ms","start":"2026-05-05T17:02:39.930795Z","end":"2026-05-05T17:02:40.069802Z","steps":["trace[496976523] 'agreement among raft nodes before linearized reading' (duration: 138.791862ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:02:40.070216Z","caller":"traceutil/trace.go:171","msg":"trace[1463476159] transaction","detail":"{read_only:false; response_revision:6669; number_of_response:1; }","duration":"182.309009ms","start":"2026-05-05T17:02:39.887885Z","end":"2026-05-05T17:02:40.070194Z","steps":["trace[1463476159] 'process raft request' (duration: 128.800461ms)","trace[1463476159] 'compare' (duration: 52.561958ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:03:37.494811Z","caller":"traceutil/trace.go:171","msg":"trace[1316310080] transaction","detail":"{read_only:false; response_revision:7027; number_of_response:1; }","duration":"130.60622ms","start":"2026-05-05T17:03:37.364178Z","end":"2026-05-05T17:03:37.494785Z","steps":["trace[1316310080] 'process raft request' (duration: 43.948371ms)","trace[1316310080] 'compare' (duration: 86.420877ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:03:37.532479Z","caller":"traceutil/trace.go:171","msg":"trace[2087147491] linearizableReadLoop","detail":"{readStateIndex:7288; appliedIndex:7284; }","duration":"167.792172ms","start":"2026-05-05T17:03:37.364668Z","end":"2026-05-05T17:03:37.53246Z","steps":["trace[2087147491] 'read index received' (duration: 43.507716ms)","trace[2087147491] 'applied index is now lower than readState.Index' (duration: 124.283346ms)"],"step_count":2} {"level":"info","ts":"2026-05-05T17:03:37.5328Z","caller":"traceutil/trace.go:171","msg":"trace[974860029] transaction","detail":"{read_only:false; response_revision:7031; number_of_response:1; }","duration":"166.884023ms","start":"2026-05-05T17:03:37.365881Z","end":"2026-05-05T17:03:37.532765Z","steps":["trace[974860029] 'process raft request' (duration: 166.513989ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:03:37.532982Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"121.752798ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/\" range_end:\"/registry/leases0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-05-05T17:03:37.533025Z","caller":"traceutil/trace.go:171","msg":"trace[55254766] transaction","detail":"{read_only:false; response_revision:7029; number_of_response:1; }","duration":"168.6403ms","start":"2026-05-05T17:03:37.364378Z","end":"2026-05-05T17:03:37.533018Z","steps":["trace[55254766] 'process raft request' (duration: 167.950843ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:03:37.533044Z","caller":"traceutil/trace.go:171","msg":"trace[946427753] range","detail":"{range_begin:/registry/leases/; range_end:/registry/leases0; response_count:0; response_revision:7032; }","duration":"121.883639ms","start":"2026-05-05T17:03:37.411151Z","end":"2026-05-05T17:03:37.533035Z","steps":["trace[946427753] 'agreement among raft nodes before linearized reading' (duration: 121.774528ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:03:37.533137Z","caller":"traceutil/trace.go:171","msg":"trace[482855139] transaction","detail":"{read_only:false; response_revision:7030; number_of_response:1; }","duration":"168.6549ms","start":"2026-05-05T17:03:37.36447Z","end":"2026-05-05T17:03:37.533125Z","steps":["trace[482855139] 'process raft request' (duration: 167.883482ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:03:37.53321Z","caller":"traceutil/trace.go:171","msg":"trace[1198621978] transaction","detail":"{read_only:false; response_revision:7032; number_of_response:1; }","duration":"163.275574ms","start":"2026-05-05T17:03:37.369926Z","end":"2026-05-05T17:03:37.533202Z","steps":["trace[1198621978] 'process raft request' (duration: 162.497046ms)"],"step_count":1} {"level":"warn","ts":"2026-05-05T17:03:37.53338Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"168.715391ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/horizon-5c9b98f8f6-92lc7\" ","response":"range_response_count:1 size:9762"} {"level":"info","ts":"2026-05-05T17:03:37.533467Z","caller":"traceutil/trace.go:171","msg":"trace[43423265] range","detail":"{range_begin:/registry/pods/openstack/horizon-5c9b98f8f6-92lc7; range_end:; response_count:1; response_revision:7032; }","duration":"168.832143ms","start":"2026-05-05T17:03:37.364625Z","end":"2026-05-05T17:03:37.533457Z","steps":["trace[43423265] 'agreement among raft nodes before linearized reading' (duration: 168.656381ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:03:37.532971Z","caller":"traceutil/trace.go:171","msg":"trace[2139858122] transaction","detail":"{read_only:false; response_revision:7028; number_of_response:1; }","duration":"168.62595ms","start":"2026-05-05T17:03:37.364317Z","end":"2026-05-05T17:03:37.532943Z","steps":["trace[2139858122] 'process raft request' (duration: 167.904813ms)"],"step_count":1} {"level":"info","ts":"2026-05-05T17:04:52.450372Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5513} {"level":"info","ts":"2026-05-05T17:04:52.527811Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5513,"took":"76.262876ms","hash":2110085942,"current-db-size-bytes":53121024,"current-db-size":"53 MB","current-db-size-in-use-bytes":18485248,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-05-05T17:04:52.527877Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2110085942,"revision":5513,"compact-revision":2893}