{"level":"warn","ts":"2026-08-06T00:54:19.355941Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-06T00:54:19.356026Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.67:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.19.213.67:2380","--initial-cluster=instance=https://199.19.213.67:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.67:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.67: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-08-06T00:54:19.356197Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-08-06T00:54:19.356246Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.67:2380"]} {"level":"info","ts":"2026-08-06T00:54:19.356268Z","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-08-06T00:54:19.356869Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"]} {"level":"info","ts":"2026-08-06T00:54:19.356935Z","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.19.213.67:2380"],"listen-peer-urls":["https://199.19.213.67:2380"],"advertise-client-urls":["https://199.19.213.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.67: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-08-06T00:54:19.367593Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"10.43864ms"} {"level":"info","ts":"2026-08-06T00:54:19.379624Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"fba6d6d8cd5f215c","cluster-id":"f3c660457a1c2c72"} {"level":"info","ts":"2026-08-06T00:54:19.37972Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=()"} {"level":"info","ts":"2026-08-06T00:54:19.379743Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became follower at term 0"} {"level":"info","ts":"2026-08-06T00:54:19.379757Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft fba6d6d8cd5f215c [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-06T00:54:19.379767Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became follower at term 1"} {"level":"info","ts":"2026-08-06T00:54:19.379804Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=(18133417176300724572)"} {"level":"warn","ts":"2026-08-06T00:54:19.386684Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-06T00:54:19.38991Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-06T00:54:19.391393Z","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-08-06T00:54:19.396064Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"fba6d6d8cd5f215c","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-06T00:54:19.399829Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"fba6d6d8cd5f215c","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-06T00:54:19.399901Z","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-08-06T00:54:19.399927Z","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-08-06T00:54:19.399936Z","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-08-06T00:54:19.400837Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c switched to configuration voters=(18133417176300724572)"} {"level":"info","ts":"2026-08-06T00:54:19.404521Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"f3c660457a1c2c72","local-member-id":"fba6d6d8cd5f215c","added-peer-id":"fba6d6d8cd5f215c","added-peer-peer-urls":["https://199.19.213.67:2380"]} {"level":"info","ts":"2026-08-06T00:54:19.410377Z","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-08-06T00:54:19.410363Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.67:2380"} {"level":"info","ts":"2026-08-06T00:54:19.410528Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.67:2380"} {"level":"info","ts":"2026-08-06T00:54:19.410935Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"fba6d6d8cd5f215c","initial-advertise-peer-urls":["https://199.19.213.67:2380"],"listen-peer-urls":["https://199.19.213.67:2380"],"advertise-client-urls":["https://199.19.213.67:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.67:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-06T00:54:19.410991Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-06T00:54:19.780425Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c is starting a new election at term 1"} {"level":"info","ts":"2026-08-06T00:54:19.780547Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became pre-candidate at term 1"} {"level":"info","ts":"2026-08-06T00:54:19.780599Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c received MsgPreVoteResp from fba6d6d8cd5f215c at term 1"} {"level":"info","ts":"2026-08-06T00:54:19.780615Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became candidate at term 2"} {"level":"info","ts":"2026-08-06T00:54:19.780626Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c received MsgVoteResp from fba6d6d8cd5f215c at term 2"} {"level":"info","ts":"2026-08-06T00:54:19.780639Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"fba6d6d8cd5f215c became leader at term 2"} {"level":"info","ts":"2026-08-06T00:54:19.780658Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: fba6d6d8cd5f215c elected leader fba6d6d8cd5f215c at term 2"} {"level":"info","ts":"2026-08-06T00:54:19.829738Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T00:54:19.962384Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T00:54:19.962622Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-06T00:54:19.962659Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-06T00:54:19.962392Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"fba6d6d8cd5f215c","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.67:2379]}","request-path":"/0/members/fba6d6d8cd5f215c/attributes","cluster-id":"f3c660457a1c2c72","publish-timeout":"7s"} {"level":"info","ts":"2026-08-06T00:54:19.962491Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-06T00:54:19.966015Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-06T00:54:19.966169Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.67:2379"} {"level":"info","ts":"2026-08-06T00:54:20.067963Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"f3c660457a1c2c72","local-member-id":"fba6d6d8cd5f215c","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T00:54:20.068062Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-06T00:54:20.068116Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"warn","ts":"2026-08-06T00:54:30.072676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"122.717797ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T00:54:30.072847Z","caller":"traceutil/trace.go:171","msg":"trace[1399843666] transaction","detail":"{read_only:false; response_revision:398; number_of_response:1; }","duration":"126.323284ms","start":"2026-08-06T00:54:29.94651Z","end":"2026-08-06T00:54:30.072833Z","steps":["trace[1399843666] 'compare' (duration: 122.607006ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:54:30.073592Z","caller":"traceutil/trace.go:171","msg":"trace[631352299] transaction","detail":"{read_only:false; response_revision:399; number_of_response:1; }","duration":"125.099642ms","start":"2026-08-06T00:54:29.948469Z","end":"2026-08-06T00:54:30.073568Z","steps":["trace[631352299] 'process raft request' (duration: 124.98401ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:54:42.258624Z","caller":"traceutil/trace.go:171","msg":"trace[563239519] transaction","detail":"{read_only:false; response_revision:430; number_of_response:1; }","duration":"615.607485ms","start":"2026-08-06T00:54:41.642991Z","end":"2026-08-06T00:54:42.258598Z","steps":["trace[563239519] 'process raft request' (duration: 615.475503ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:54:42.259007Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:54:41.642963Z","time spent":"615.734578ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T00:54:42.259729Z","caller":"traceutil/trace.go:171","msg":"trace[1868053512] linearizableReadLoop","detail":"{readStateIndex:445; appliedIndex:443; }","duration":"317.348165ms","start":"2026-08-06T00:54:41.942361Z","end":"2026-08-06T00:54:42.259709Z","steps":["trace[1868053512] 'read index received' (duration: 316.182257ms)","trace[1868053512] 'applied index is now lower than readState.Index' (duration: 1.164878ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:54:42.259844Z","caller":"traceutil/trace.go:171","msg":"trace[963161753] transaction","detail":"{read_only:false; response_revision:431; number_of_response:1; }","duration":"615.427314ms","start":"2026-08-06T00:54:41.644404Z","end":"2026-08-06T00:54:42.259831Z","steps":["trace[963161753] 'process raft request' (duration: 615.167881ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:54:42.259884Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.514517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T00:54:42.259935Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:54:41.644376Z","time spent":"615.507615ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":423,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T00:54:42.259956Z","caller":"traceutil/trace.go:171","msg":"trace[1930678957] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:432; }","duration":"317.629218ms","start":"2026-08-06T00:54:41.942309Z","end":"2026-08-06T00:54:42.259938Z","steps":["trace[1930678957] 'agreement among raft nodes before linearized reading' (duration: 317.527487ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:54:42.260179Z","caller":"traceutil/trace.go:171","msg":"trace[1298163496] transaction","detail":"{read_only:false; response_revision:432; number_of_response:1; }","duration":"230.001898ms","start":"2026-08-06T00:54:42.03015Z","end":"2026-08-06T00:54:42.260152Z","steps":["trace[1298163496] 'process raft request' (duration: 229.504371ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:54:42.260274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"132.618761ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"info","ts":"2026-08-06T00:54:42.260329Z","caller":"traceutil/trace.go:171","msg":"trace[1980083873] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:432; }","duration":"132.688672ms","start":"2026-08-06T00:54:42.127625Z","end":"2026-08-06T00:54:42.260314Z","steps":["trace[1980083873] 'agreement among raft nodes before linearized reading' (duration: 132.600341ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:54:42.260287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"312.78209ms","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-08-06T00:54:42.260454Z","caller":"traceutil/trace.go:171","msg":"trace[207015049] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:432; }","duration":"312.949783ms","start":"2026-08-06T00:54:41.947466Z","end":"2026-08-06T00:54:42.260415Z","steps":["trace[207015049] 'agreement among raft nodes before linearized reading' (duration: 312.76013ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:54:42.260471Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:54:41.942289Z","time spent":"317.7001ms","remote":"127.0.0.1:54214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T00:54:42.260554Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:54:41.947451Z","time spent":"313.069524ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T00:54:56.817747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"260.524734ms","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-08-06T00:54:56.817852Z","caller":"traceutil/trace.go:171","msg":"trace[1733187610] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:530; }","duration":"260.639227ms","start":"2026-08-06T00:54:56.557196Z","end":"2026-08-06T00:54:56.817836Z","steps":["trace[1733187610] 'range keys from in-memory index tree' (duration: 260.512234ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:54:57.516954Z","caller":"traceutil/trace.go:171","msg":"trace[695948581] transaction","detail":"{read_only:false; response_revision:531; number_of_response:1; }","duration":"171.274118ms","start":"2026-08-06T00:54:57.345521Z","end":"2026-08-06T00:54:57.516796Z","steps":["trace[695948581] 'process raft request' (duration: 171.168526ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:54:57.526212Z","caller":"traceutil/trace.go:171","msg":"trace[1591723046] transaction","detail":"{read_only:false; response_revision:533; number_of_response:1; }","duration":"164.688279ms","start":"2026-08-06T00:54:57.36148Z","end":"2026-08-06T00:54:57.526169Z","steps":["trace[1591723046] 'process raft request' (duration: 164.509495ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:54:57.526324Z","caller":"traceutil/trace.go:171","msg":"trace[1251490036] transaction","detail":"{read_only:false; response_revision:534; number_of_response:1; }","duration":"145.907079ms","start":"2026-08-06T00:54:57.380409Z","end":"2026-08-06T00:54:57.526317Z","steps":["trace[1251490036] 'process raft request' (duration: 145.624324ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:54:57.526219Z","caller":"traceutil/trace.go:171","msg":"trace[938118146] transaction","detail":"{read_only:false; response_revision:532; number_of_response:1; }","duration":"177.727694ms","start":"2026-08-06T00:54:57.34847Z","end":"2026-08-06T00:54:57.526197Z","steps":["trace[938118146] 'process raft request' (duration: 173.509058ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:54:57.526296Z","caller":"traceutil/trace.go:171","msg":"trace[365490866] transaction","detail":"{read_only:false; response_revision:535; number_of_response:1; }","duration":"145.823388ms","start":"2026-08-06T00:54:57.380463Z","end":"2026-08-06T00:54:57.526286Z","steps":["trace[365490866] 'process raft request' (duration: 145.612854ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:54:57.526513Z","caller":"traceutil/trace.go:171","msg":"trace[2072773320] transaction","detail":"{read_only:false; response_revision:537; number_of_response:1; }","duration":"126.034579ms","start":"2026-08-06T00:54:57.400469Z","end":"2026-08-06T00:54:57.526504Z","steps":["trace[2072773320] 'process raft request' (duration: 125.693413ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:54:57.526637Z","caller":"traceutil/trace.go:171","msg":"trace[1675383047] transaction","detail":"{read_only:false; response_revision:536; number_of_response:1; }","duration":"138.568037ms","start":"2026-08-06T00:54:57.388054Z","end":"2026-08-06T00:54:57.526622Z","steps":["trace[1675383047] 'process raft request' (duration: 138.070387ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:54:57.526655Z","caller":"traceutil/trace.go:171","msg":"trace[113342078] transaction","detail":"{read_only:false; response_revision:538; number_of_response:1; }","duration":"124.476092ms","start":"2026-08-06T00:54:57.402162Z","end":"2026-08-06T00:54:57.526638Z","steps":["trace[113342078] 'process raft request' (duration: 124.028213ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:54:57.526734Z","caller":"traceutil/trace.go:171","msg":"trace[966928968] transaction","detail":"{read_only:false; response_revision:539; number_of_response:1; }","duration":"116.819034ms","start":"2026-08-06T00:54:57.409906Z","end":"2026-08-06T00:54:57.526725Z","steps":["trace[966928968] 'process raft request' (duration: 116.471926ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:54:57.526813Z","caller":"traceutil/trace.go:171","msg":"trace[838538783] transaction","detail":"{read_only:false; response_revision:540; number_of_response:1; }","duration":"114.365399ms","start":"2026-08-06T00:54:57.412437Z","end":"2026-08-06T00:54:57.526803Z","steps":["trace[838538783] 'process raft request' (duration: 113.989471ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:54:57.853181Z","caller":"traceutil/trace.go:171","msg":"trace[983508671] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"146.258146ms","start":"2026-08-06T00:54:57.7069Z","end":"2026-08-06T00:54:57.853158Z","steps":["trace[983508671] 'process raft request' (duration: 140.41227ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:54:57.853595Z","caller":"traceutil/trace.go:171","msg":"trace[2027663556] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"139.227448ms","start":"2026-08-06T00:54:57.714356Z","end":"2026-08-06T00:54:57.853584Z","steps":["trace[2027663556] 'process raft request' (duration: 138.969844ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:54:57.853731Z","caller":"traceutil/trace.go:171","msg":"trace[257254699] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"137.567738ms","start":"2026-08-06T00:54:57.716147Z","end":"2026-08-06T00:54:57.853715Z","steps":["trace[257254699] 'process raft request' (duration: 137.293604ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:55:03.791732Z","caller":"traceutil/trace.go:171","msg":"trace[929593944] transaction","detail":"{read_only:false; response_revision:627; number_of_response:1; }","duration":"213.39076ms","start":"2026-08-06T00:55:03.578325Z","end":"2026-08-06T00:55:03.791716Z","steps":["trace[929593944] 'process raft request' (duration: 212.273949ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:55:04.170352Z","caller":"traceutil/trace.go:171","msg":"trace[140790175] transaction","detail":"{read_only:false; response_revision:629; number_of_response:1; }","duration":"199.170469ms","start":"2026-08-06T00:55:03.971152Z","end":"2026-08-06T00:55:04.170322Z","steps":["trace[140790175] 'process raft request' (duration: 198.965145ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:04.911623Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403972036413093996,"retry-timeout":"500ms"} {"level":"info","ts":"2026-08-06T00:55:05.058004Z","caller":"traceutil/trace.go:171","msg":"trace[1951320394] transaction","detail":"{read_only:false; response_revision:630; number_of_response:1; }","duration":"768.392917ms","start":"2026-08-06T00:55:04.289581Z","end":"2026-08-06T00:55:05.057974Z","steps":["trace[1951320394] 'process raft request' (duration: 759.351308ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:05.058155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:04.289541Z","time spent":"768.53572ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036153,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T00:55:05.057985Z","caller":"traceutil/trace.go:171","msg":"trace[995426188] linearizableReadLoop","detail":"{readStateIndex:653; appliedIndex:652; }","duration":"647.189807ms","start":"2026-08-06T00:55:04.410753Z","end":"2026-08-06T00:55:05.057943Z","steps":["trace[995426188] 'read index received' (duration: 637.916444ms)","trace[995426188] 'applied index is now lower than readState.Index' (duration: 9.271043ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:55:05.058265Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"647.465242ms","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-08-06T00:55:05.058326Z","caller":"traceutil/trace.go:171","msg":"trace[1240521998] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:630; }","duration":"647.600455ms","start":"2026-08-06T00:55:04.41071Z","end":"2026-08-06T00:55:05.058311Z","steps":["trace[1240521998] 'agreement among raft nodes before linearized reading' (duration: 647.34863ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:05.05837Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:04.410691Z","time spent":"647.669606ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":530,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T00:55:05.05838Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.785685ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:55:05.058434Z","caller":"traceutil/trace.go:171","msg":"trace[1149329446] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:630; }","duration":"113.872726ms","start":"2026-08-06T00:55:04.944545Z","end":"2026-08-06T00:55:05.058418Z","steps":["trace[1149329446] 'agreement among raft nodes before linearized reading' (duration: 113.757084ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:05.058443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"634.267354ms","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-08-06T00:55:05.058514Z","caller":"traceutil/trace.go:171","msg":"trace[1319521961] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:630; }","duration":"634.372246ms","start":"2026-08-06T00:55:04.424123Z","end":"2026-08-06T00:55:05.058495Z","steps":["trace[1319521961] 'agreement among raft nodes before linearized reading' (duration: 634.144551ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:05.058557Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:04.424108Z","time spent":"634.440688ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":461,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T00:55:05.058679Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"561.358128ms","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-08-06T00:55:05.058711Z","caller":"traceutil/trace.go:171","msg":"trace[1478067010] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:630; }","duration":"561.42506ms","start":"2026-08-06T00:55:04.497276Z","end":"2026-08-06T00:55:05.058702Z","steps":["trace[1478067010] 'agreement among raft nodes before linearized reading' (duration: 561.309887ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:05.058728Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"633.335976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:433"} {"level":"warn","ts":"2026-08-06T00:55:05.058736Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:04.497252Z","time spent":"561.47659ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":503,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T00:55:05.058758Z","caller":"traceutil/trace.go:171","msg":"trace[860747537] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:630; }","duration":"633.445889ms","start":"2026-08-06T00:55:04.425303Z","end":"2026-08-06T00:55:05.058749Z","steps":["trace[860747537] 'agreement among raft nodes before linearized reading' (duration: 633.361637ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:05.058786Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:04.425284Z","time spent":"633.496139ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":457,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-06T00:55:05.679381Z","caller":"traceutil/trace.go:171","msg":"trace[296314286] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"137.476775ms","start":"2026-08-06T00:55:05.541873Z","end":"2026-08-06T00:55:05.67935Z","steps":["trace[296314286] 'process raft request' (duration: 136.21967ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:55:05.735289Z","caller":"traceutil/trace.go:171","msg":"trace[1988768160] transaction","detail":"{read_only:false; response_revision:637; number_of_response:1; }","duration":"117.246306ms","start":"2026-08-06T00:55:05.618016Z","end":"2026-08-06T00:55:05.735262Z","steps":["trace[1988768160] 'process raft request' (duration: 112.180825ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:55:29.22093Z","caller":"traceutil/trace.go:171","msg":"trace[227800036] transaction","detail":"{read_only:false; response_revision:892; number_of_response:1; }","duration":"101.274746ms","start":"2026-08-06T00:55:29.119625Z","end":"2026-08-06T00:55:29.220899Z","steps":["trace[227800036] 'process raft request' (duration: 82.962223ms)","trace[227800036] 'compare' (duration: 18.035037ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:55:31.707283Z","caller":"traceutil/trace.go:171","msg":"trace[1702813903] transaction","detail":"{read_only:false; response_revision:930; number_of_response:1; }","duration":"244.856117ms","start":"2026-08-06T00:55:31.462398Z","end":"2026-08-06T00:55:31.707254Z","steps":["trace[1702813903] 'process raft request' (duration: 243.587146ms)"],"step_count":1} 2026/08/06 00:55:43 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-06T00:55:43.9757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"194.670379ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-06T00:55:43.975792Z","caller":"traceutil/trace.go:171","msg":"trace[201643293] range","detail":"{range_begin:/registry/leases/envoy-gateway-system/5b9825d2.gateway.envoyproxy.io; range_end:; }","duration":"194.788642ms","start":"2026-08-06T00:55:43.780986Z","end":"2026-08-06T00:55:43.975774Z","steps":["trace[201643293] 'agreement among raft nodes before linearized reading' (duration: 194.676039ms)"],"step_count":1} 2026/08/06 00:55:43 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-06T00:55:43.982448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"201.640676ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-06T00:55:43.982468Z","caller":"traceutil/trace.go:171","msg":"trace[466839664] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; }","duration":"201.691757ms","start":"2026-08-06T00:55:43.78077Z","end":"2026-08-06T00:55:43.982462Z","steps":["trace[466839664] 'agreement among raft nodes before linearized reading' (duration: 201.669827ms)"],"step_count":1} 2026/08/06 00:55:43 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-06T00:55:44.125276Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403972036413094577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T00:55:44.625693Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403972036413094577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T00:55:45.126731Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403972036413094577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T00:55:45.62722Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403972036413094577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T00:55:45.782227Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.999124139s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-06T00:55:45.782293Z","caller":"traceutil/trace.go:171","msg":"trace[1425427542] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"1.999223391s","start":"2026-08-06T00:55:43.783042Z","end":"2026-08-06T00:55:45.782265Z","steps":["trace[1425427542] 'agreement among raft nodes before linearized reading' (duration: 1.999127649s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:45.782354Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.783039Z","time spent":"1.999305863s","remote":"127.0.0.1:54214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} 2026/08/06 00:55:45 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-06T00:55:45.801208Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.000774286s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-06T00:55:45.801325Z","caller":"traceutil/trace.go:171","msg":"trace[842510218] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.000963041s","start":"2026-08-06T00:55:43.800336Z","end":"2026-08-06T00:55:45.801299Z","steps":["trace[842510218] 'agreement among raft nodes before linearized reading' (duration: 2.000807897s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:45.801397Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.800316Z","time spent":"2.001069234s","remote":"127.0.0.1:54230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T00:55:46.127939Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403972036413094577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T00:55:46.6287Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403972036413094577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T00:55:47.678476Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403972036413094577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T00:55:48.13895Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.332926321s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-06T00:55:48.139028Z","caller":"traceutil/trace.go:171","msg":"trace[365335520] range","detail":"{range_begin:/registry/health; range_end:; }","duration":"2.333068756s","start":"2026-08-06T00:55:45.805942Z","end":"2026-08-06T00:55:48.139011Z","steps":["trace[365335520] 'agreement among raft nodes before linearized reading' (duration: 2.332967132s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:48.139064Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:45.805923Z","time spent":"2.333135948s","remote":"127.0.0.1:54214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":0,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T00:55:48.178999Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403972036413094577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T00:55:48.679131Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403972036413094577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T00:55:48.78301Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.001891191s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"","error":"context deadline exceeded"} {"level":"info","ts":"2026-08-06T00:55:48.783051Z","caller":"traceutil/trace.go:171","msg":"trace[747312493] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; }","duration":"5.001973693s","start":"2026-08-06T00:55:43.781062Z","end":"2026-08-06T00:55:48.783036Z","steps":["trace[747312493] 'agreement among raft nodes before linearized reading' (duration: 5.001918811s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:48.783085Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.78104Z","time spent":"5.002038625s","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T00:55:48.783201Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.999650335s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"","error":"context canceled"} {"level":"info","ts":"2026-08-06T00:55:48.783216Z","caller":"traceutil/trace.go:171","msg":"trace[1148119080] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; }","duration":"4.999675355s","start":"2026-08-06T00:55:43.783536Z","end":"2026-08-06T00:55:48.783211Z","steps":["trace[1148119080] 'agreement among raft nodes before linearized reading' (duration: 4.999660405s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:48.783231Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.783532Z","time spent":"4.999695715s","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":0,"response size":0,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} 2026/08/06 00:55:48 WARNING: [core] [Server #8] grpc: Server.processUnaryRPC failed to write status: connection error: desc = "transport is closing" {"level":"warn","ts":"2026-08-06T00:55:48.792052Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.793538Z","time spent":"4.998511672s","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":0,"request size":0,"response count":0,"response size":0,"request content":""} {"level":"warn","ts":"2026-08-06T00:55:49.179512Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403972036413094577,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T00:55:49.778799Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.154039364s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.19.213.67\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-08-06T00:55:49.778884Z","caller":"traceutil/trace.go:171","msg":"trace[1192888458] range","detail":"{range_begin:/registry/masterleases/199.19.213.67; range_end:; response_count:1; response_revision:932; }","duration":"6.154152067s","start":"2026-08-06T00:55:43.624708Z","end":"2026-08-06T00:55:49.77886Z","steps":["trace[1192888458] 'range keys from in-memory index tree' (duration: 6.15390855s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.778922Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.624698Z","time spent":"6.154215948s","remote":"127.0.0.1:54268","response type":"/etcdserverpb.KV/Range","request count":0,"request size":38,"response count":1,"response size":157,"request content":"key:\"/registry/masterleases/199.19.213.67\" "} {"level":"warn","ts":"2026-08-06T00:55:49.779274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"5.985757492s","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-08-06T00:55:49.779418Z","caller":"traceutil/trace.go:171","msg":"trace[767150709] range","detail":"{range_begin:; range_end:; response_count:0; response_revision:932; }","duration":"5.985902246s","start":"2026-08-06T00:55:43.793498Z","end":"2026-08-06T00:55:49.779401Z","steps":["trace[767150709] 'range keys from in-memory index tree' (duration: 5.985746302s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.820626Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.205198ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:29"} {"level":"info","ts":"2026-08-06T00:55:49.823176Z","caller":"traceutil/trace.go:171","msg":"trace[58816338] transaction","detail":"{read_only:false; response_revision:934; number_of_response:1; }","duration":"6.039560081s","start":"2026-08-06T00:55:43.783598Z","end":"2026-08-06T00:55:49.823158Z","steps":["trace[58816338] 'process raft request' (duration: 6.037133558s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.823316Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.783587Z","time spent":"6.039653134s","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":672,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T00:55:49.823458Z","caller":"traceutil/trace.go:171","msg":"trace[1455065733] transaction","detail":"{read_only:false; response_revision:936; number_of_response:1; }","duration":"6.029841636s","start":"2026-08-06T00:55:43.793595Z","end":"2026-08-06T00:55:49.823437Z","steps":["trace[1455065733] 'process raft request' (duration: 6.029524978s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.823583Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.793591Z","time spent":"6.029919738s","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":415,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T00:55:49.823756Z","caller":"traceutil/trace.go:171","msg":"trace[1024998760] transaction","detail":"{read_only:false; response_revision:937; number_of_response:1; }","duration":"6.029765934s","start":"2026-08-06T00:55:43.793978Z","end":"2026-08-06T00:55:49.823744Z","steps":["trace[1024998760] 'process raft request' (duration: 6.029383534s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.823819Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.793955Z","time spent":"6.029831886s","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":521,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T00:55:49.824013Z","caller":"traceutil/trace.go:171","msg":"trace[134458312] transaction","detail":"{read_only:false; response_revision:938; number_of_response:1; }","duration":"5.846380579s","start":"2026-08-06T00:55:43.97762Z","end":"2026-08-06T00:55:49.824001Z","steps":["trace[134458312] 'process raft request' (duration: 5.846083952s)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:55:49.82438Z","caller":"traceutil/trace.go:171","msg":"trace[1028637546] linearizableReadLoop","detail":"{readStateIndex:969; appliedIndex:962; }","duration":"6.199422062s","start":"2026-08-06T00:55:43.62494Z","end":"2026-08-06T00:55:49.824362Z","steps":["trace[1028637546] 'read index received' (duration: 6.020492598s)","trace[1028637546] 'applied index is now lower than readState.Index' (duration: 178.928313ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:55:49.824583Z","caller":"traceutil/trace.go:171","msg":"trace[786702189] transaction","detail":"{read_only:false; response_revision:939; number_of_response:1; }","duration":"5.839993848s","start":"2026-08-06T00:55:43.984576Z","end":"2026-08-06T00:55:49.82457Z","steps":["trace[786702189] 'process raft request' (duration: 5.839366462s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.824648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.984561Z","time spent":"5.84005118s","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":411,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T00:55:49.824798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.199848532s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T00:55:49.824829Z","caller":"traceutil/trace.go:171","msg":"trace[453547938] range","detail":"{range_begin:/registry/deployments/; range_end:/registry/deployments0; response_count:0; response_revision:939; }","duration":"6.199888523s","start":"2026-08-06T00:55:43.62493Z","end":"2026-08-06T00:55:49.824819Z","steps":["trace[453547938] 'agreement among raft nodes before linearized reading' (duration: 6.199831142s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.824859Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.624928Z","time spent":"6.199923784s","remote":"127.0.0.1:54728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":3,"response size":31,"request content":"key:\"/registry/deployments/\" range_end:\"/registry/deployments0\" count_only:true "} {"level":"warn","ts":"2026-08-06T00:55:49.825918Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.977606Z","time spent":"5.846438331s","remote":"127.0.0.1:54316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":904,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T00:55:49.85553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.23054185s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T00:55:49.85559Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.053047763s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-hj8r9\" ","response":"range_response_count:1 size:5676"} {"level":"info","ts":"2026-08-06T00:55:49.855626Z","caller":"traceutil/trace.go:171","msg":"trace[1283012787] range","detail":"{range_begin:/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-hj8r9; range_end:; response_count:1; response_revision:939; }","duration":"6.053144996s","start":"2026-08-06T00:55:43.802471Z","end":"2026-08-06T00:55:49.855616Z","steps":["trace[1283012787] 'agreement among raft nodes before linearized reading' (duration: 6.052974241s)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:55:49.855625Z","caller":"traceutil/trace.go:171","msg":"trace[1959294332] range","detail":"{range_begin:/registry/deployments/local-path-storage/local-path-provisioner; range_end:; response_count:0; response_revision:939; }","duration":"6.230719534s","start":"2026-08-06T00:55:43.624885Z","end":"2026-08-06T00:55:49.855604Z","steps":["trace[1959294332] 'agreement among raft nodes before linearized reading' (duration: 6.230529329s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.855668Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.624881Z","time spent":"6.230776476s","remote":"127.0.0.1:54728","response type":"/etcdserverpb.KV/Range","request count":0,"request size":65,"response count":0,"response size":29,"request content":"key:\"/registry/deployments/local-path-storage/local-path-provisioner\" "} {"level":"warn","ts":"2026-08-06T00:55:49.855683Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.802462Z","time spent":"6.053213037s","remote":"127.0.0.1:54434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":1,"response size":5700,"request content":"key:\"/registry/pods/envoy-gateway-system/envoy-gateway-78446f96c9-hj8r9\" "} {"level":"warn","ts":"2026-08-06T00:55:49.855737Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"568.226665ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:55:49.855836Z","caller":"traceutil/trace.go:171","msg":"trace[1329184224] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"568.35383ms","start":"2026-08-06T00:55:49.287455Z","end":"2026-08-06T00:55:49.855809Z","steps":["trace[1329184224] 'agreement among raft nodes before linearized reading' (duration: 568.237367ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.855897Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:49.28743Z","time spent":"568.455372ms","remote":"127.0.0.1:54214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T00:55:49.855915Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.715576137s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T00:55:49.855913Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.072867744s","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-08-06T00:55:49.85594Z","caller":"traceutil/trace.go:171","msg":"trace[11036431] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:939; }","duration":"1.715641379s","start":"2026-08-06T00:55:48.14029Z","end":"2026-08-06T00:55:49.855931Z","steps":["trace[11036431] 'agreement among raft nodes before linearized reading' (duration: 1.715604138s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.855986Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.072969817s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T00:55:49.856013Z","caller":"traceutil/trace.go:171","msg":"trace[598117065] range","detail":"{range_begin:/registry/namespaces/; range_end:/registry/namespaces0; response_count:0; response_revision:939; }","duration":"6.073008498s","start":"2026-08-06T00:55:43.782997Z","end":"2026-08-06T00:55:49.856005Z","steps":["trace[598117065] 'agreement among raft nodes before linearized reading' (duration: 6.072967056s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.856013Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:48.140279Z","time spent":"1.715728542s","remote":"127.0.0.1:54230","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T00:55:49.85604Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.782991Z","time spent":"6.073042128s","remote":"127.0.0.1:54352","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":5,"response size":31,"request content":"key:\"/registry/namespaces/\" range_end:\"/registry/namespaces0\" count_only:true "} {"level":"warn","ts":"2026-08-06T00:55:49.856109Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.062277038s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/kube-system/coredns-7c96b6546b-qbpzg.18c91148e89d6d14\" ","response":"range_response_count:1 size:826"} {"level":"info","ts":"2026-08-06T00:55:49.855945Z","caller":"traceutil/trace.go:171","msg":"trace[703445134] range","detail":"{range_begin:/registry/pods/; range_end:/registry/pods0; response_count:0; response_revision:939; }","duration":"6.072909896s","start":"2026-08-06T00:55:43.783026Z","end":"2026-08-06T00:55:49.855935Z","steps":["trace[703445134] 'agreement among raft nodes before linearized reading' (duration: 6.072849304s)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:55:49.856163Z","caller":"traceutil/trace.go:171","msg":"trace[340021874] range","detail":"{range_begin:/registry/events/kube-system/coredns-7c96b6546b-qbpzg.18c91148e89d6d14; range_end:; response_count:1; response_revision:939; }","duration":"6.062337379s","start":"2026-08-06T00:55:43.793807Z","end":"2026-08-06T00:55:49.856145Z","steps":["trace[340021874] 'agreement among raft nodes before linearized reading' (duration: 6.062167425s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.855647Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.712526418s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-06T00:55:49.856206Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.7938Z","time spent":"6.062397331s","remote":"127.0.0.1:54316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":72,"response count":1,"response size":850,"request content":"key:\"/registry/events/kube-system/coredns-7c96b6546b-qbpzg.18c91148e89d6d14\" "} {"level":"warn","ts":"2026-08-06T00:55:49.856254Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"864.193256ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T00:55:49.856249Z","caller":"traceutil/trace.go:171","msg":"trace[1971422456] range","detail":"{range_begin:/registry/prioritylevelconfigurations/; range_end:/registry/prioritylevelconfigurations0; response_count:0; response_revision:939; }","duration":"1.713175106s","start":"2026-08-06T00:55:48.143043Z","end":"2026-08-06T00:55:49.856218Z","steps":["trace[1971422456] 'agreement among raft nodes before linearized reading' (duration: 1.712434256s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.856297Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.075540773s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-06T00:55:49.856341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:48.143027Z","time spent":"1.71328946s","remote":"127.0.0.1:54716","response type":"/etcdserverpb.KV/Range","request count":0,"request size":82,"response count":8,"response size":31,"request content":"key:\"/registry/prioritylevelconfigurations/\" range_end:\"/registry/prioritylevelconfigurations0\" count_only:true "} {"level":"info","ts":"2026-08-06T00:55:49.856321Z","caller":"traceutil/trace.go:171","msg":"trace[589884947] range","detail":"{range_begin:/registry/configmaps/; range_end:/registry/configmaps0; response_count:0; response_revision:939; }","duration":"864.308469ms","start":"2026-08-06T00:55:48.991995Z","end":"2026-08-06T00:55:49.856304Z","steps":["trace[589884947] 'agreement among raft nodes before linearized reading' (duration: 864.191516ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.856366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"2.099672313s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-06T00:55:49.856421Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:48.99197Z","time spent":"864.431713ms","remote":"127.0.0.1:54350","response type":"/etcdserverpb.KV/Range","request count":0,"request size":48,"response count":14,"response size":31,"request content":"key:\"/registry/configmaps/\" range_end:\"/registry/configmaps0\" count_only:true "} {"level":"info","ts":"2026-08-06T00:55:49.856443Z","caller":"traceutil/trace.go:171","msg":"trace[1917697178] range","detail":"{range_begin:/registry/controllerrevisions/; range_end:/registry/controllerrevisions0; response_count:0; response_revision:939; }","duration":"2.099771545s","start":"2026-08-06T00:55:47.756656Z","end":"2026-08-06T00:55:49.856428Z","steps":["trace[1917697178] 'agreement among raft nodes before linearized reading' (duration: 2.099601781s)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:55:49.856328Z","caller":"traceutil/trace.go:171","msg":"trace[2040141828] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:939; }","duration":"6.075587215s","start":"2026-08-06T00:55:43.780733Z","end":"2026-08-06T00:55:49.85632Z","steps":["trace[2040141828] 'agreement among raft nodes before linearized reading' (duration: 6.075458421s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.856481Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:47.756583Z","time spent":"2.099891059s","remote":"127.0.0.1:54758","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":2,"response size":31,"request content":"key:\"/registry/controllerrevisions/\" range_end:\"/registry/controllerrevisions0\" count_only:true "} {"level":"warn","ts":"2026-08-06T00:55:49.856479Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.073115182s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T00:55:49.856508Z","caller":"traceutil/trace.go:171","msg":"trace[1993527418] range","detail":"{range_begin:/registry/rolebindings/; range_end:/registry/rolebindings0; response_count:0; response_revision:939; }","duration":"6.073153773s","start":"2026-08-06T00:55:43.783348Z","end":"2026-08-06T00:55:49.856501Z","steps":["trace[1993527418] 'agreement among raft nodes before linearized reading' (duration: 6.073099552s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.85651Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.780718Z","time spent":"6.075781469s","remote":"127.0.0.1:54316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":116,"response size":31,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"warn","ts":"2026-08-06T00:55:49.856534Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.783344Z","time spent":"6.073185004s","remote":"127.0.0.1:54604","response type":"/etcdserverpb.KV/Range","request count":0,"request size":52,"response count":16,"response size":31,"request content":"key:\"/registry/rolebindings/\" range_end:\"/registry/rolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-06T00:55:49.855928Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.072788772s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T00:55:49.856605Z","caller":"traceutil/trace.go:171","msg":"trace[513380320] range","detail":"{range_begin:/registry/clusterrolebindings/; range_end:/registry/clusterrolebindings0; response_count:0; response_revision:939; }","duration":"6.073485932s","start":"2026-08-06T00:55:43.783104Z","end":"2026-08-06T00:55:49.85659Z","steps":["trace[513380320] 'agreement among raft nodes before linearized reading' (duration: 6.072729621s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.856652Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"4.890255823s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T00:55:49.856655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.783101Z","time spent":"6.073540183s","remote":"127.0.0.1:54620","response type":"/etcdserverpb.KV/Range","request count":0,"request size":66,"response count":53,"response size":31,"request content":"key:\"/registry/clusterrolebindings/\" range_end:\"/registry/clusterrolebindings0\" count_only:true "} {"level":"warn","ts":"2026-08-06T00:55:49.856398Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.062714129s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T00:55:49.856678Z","caller":"traceutil/trace.go:171","msg":"trace[1151813053] range","detail":"{range_begin:/registry/limitranges/; range_end:/registry/limitranges0; response_count:0; response_revision:939; }","duration":"4.890342046s","start":"2026-08-06T00:55:44.966329Z","end":"2026-08-06T00:55:49.856671Z","steps":["trace[1151813053] 'agreement among raft nodes before linearized reading' (duration: 4.890295894s)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:55:49.856692Z","caller":"traceutil/trace.go:171","msg":"trace[2047275701] range","detail":"{range_begin:/registry/replicasets/; range_end:/registry/replicasets0; response_count:0; response_revision:939; }","duration":"6.063019898s","start":"2026-08-06T00:55:43.793665Z","end":"2026-08-06T00:55:49.856685Z","steps":["trace[2047275701] 'agreement among raft nodes before linearized reading' (duration: 6.06270171s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.8567Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:44.966302Z","time spent":"4.890393296s","remote":"127.0.0.1:54384","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":0,"response size":29,"request content":"key:\"/registry/limitranges/\" range_end:\"/registry/limitranges0\" count_only:true "} {"level":"warn","ts":"2026-08-06T00:55:49.856721Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.793661Z","time spent":"6.063052859s","remote":"127.0.0.1:54756","response type":"/etcdserverpb.KV/Range","request count":0,"request size":50,"response count":4,"response size":31,"request content":"key:\"/registry/replicasets/\" range_end:\"/registry/replicasets0\" count_only:true "} {"level":"warn","ts":"2026-08-06T00:55:49.856776Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.533279096s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:55:49.856842Z","caller":"traceutil/trace.go:171","msg":"trace[1625375160] range","detail":"{range_begin:/registry/horizontalpodautoscalers/; range_end:/registry/horizontalpodautoscalers0; response_count:0; response_revision:939; }","duration":"1.533359958s","start":"2026-08-06T00:55:48.323465Z","end":"2026-08-06T00:55:49.856825Z","steps":["trace[1625375160] 'agreement among raft nodes before linearized reading' (duration: 1.533161872s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.856439Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"6.072919686s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:55:49.856883Z","caller":"traceutil/trace.go:171","msg":"trace[1086438382] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:939; }","duration":"6.073364598s","start":"2026-08-06T00:55:43.783511Z","end":"2026-08-06T00:55:49.856875Z","steps":["trace[1086438382] 'agreement among raft nodes before linearized reading' (duration: 6.072909516s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:55:49.856886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:48.323458Z","time spent":"1.533417109s","remote":"127.0.0.1:54488","response type":"/etcdserverpb.KV/Range","request count":0,"request size":76,"response count":0,"response size":29,"request content":"key:\"/registry/horizontalpodautoscalers/\" range_end:\"/registry/horizontalpodautoscalers0\" count_only:true "} {"level":"warn","ts":"2026-08-06T00:55:49.85636Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.783023Z","time spent":"6.073127261s","remote":"127.0.0.1:54434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":36,"response count":13,"response size":31,"request content":"key:\"/registry/pods/\" range_end:\"/registry/pods0\" count_only:true "} {"level":"warn","ts":"2026-08-06T00:55:49.856913Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:55:43.783501Z","time spent":"6.07340336s","remote":"127.0.0.1:54640","response type":"/etcdserverpb.KV/Range","request count":0,"request size":62,"response count":0,"response size":29,"request content":"key:\"/registry/volumeattachments/\" range_end:\"/registry/volumeattachments0\" count_only:true "} {"level":"info","ts":"2026-08-06T00:55:50.074481Z","caller":"traceutil/trace.go:171","msg":"trace[458600584] transaction","detail":"{read_only:false; response_revision:947; number_of_response:1; }","duration":"110.073064ms","start":"2026-08-06T00:55:49.964385Z","end":"2026-08-06T00:55:50.074458Z","steps":["trace[458600584] 'process raft request' (duration: 110.013822ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:55:50.074522Z","caller":"traceutil/trace.go:171","msg":"trace[1959838134] transaction","detail":"{read_only:false; response_revision:946; number_of_response:1; }","duration":"111.021517ms","start":"2026-08-06T00:55:49.963465Z","end":"2026-08-06T00:55:50.074487Z","steps":["trace[1959838134] 'process raft request' (duration: 84.99921ms)","trace[1959838134] 'compare' (duration: 25.798972ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:55:50.467413Z","caller":"traceutil/trace.go:171","msg":"trace[979959305] transaction","detail":"{read_only:false; response_revision:969; number_of_response:1; }","duration":"122.329387ms","start":"2026-08-06T00:55:50.345055Z","end":"2026-08-06T00:55:50.467384Z","steps":["trace[979959305] 'process raft request' (duration: 111.482294ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:55:50.467656Z","caller":"traceutil/trace.go:171","msg":"trace[206253239] transaction","detail":"{read_only:false; response_revision:970; number_of_response:1; }","duration":"120.972042ms","start":"2026-08-06T00:55:50.346378Z","end":"2026-08-06T00:55:50.46735Z","steps":["trace[206253239] 'process raft request' (duration: 120.88751ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:56:18.944217Z","caller":"traceutil/trace.go:171","msg":"trace[1906316211] transaction","detail":"{read_only:false; response_revision:1267; number_of_response:1; }","duration":"149.954943ms","start":"2026-08-06T00:56:18.79424Z","end":"2026-08-06T00:56:18.944195Z","steps":["trace[1906316211] 'process raft request' (duration: 149.764537ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:56:18.953273Z","caller":"traceutil/trace.go:171","msg":"trace[510993868] transaction","detail":"{read_only:false; response_revision:1268; number_of_response:1; }","duration":"158.317377ms","start":"2026-08-06T00:56:18.794918Z","end":"2026-08-06T00:56:18.953235Z","steps":["trace[510993868] 'process raft request' (duration: 158.125852ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:56:22.448544Z","caller":"traceutil/trace.go:171","msg":"trace[1636046088] transaction","detail":"{read_only:false; response_revision:1283; number_of_response:1; }","duration":"289.877723ms","start":"2026-08-06T00:56:22.158623Z","end":"2026-08-06T00:56:22.448501Z","steps":["trace[1636046088] 'process raft request' (duration: 289.385109ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:56:22.454888Z","caller":"traceutil/trace.go:171","msg":"trace[29942623] transaction","detail":"{read_only:false; response_revision:1284; number_of_response:1; }","duration":"123.121334ms","start":"2026-08-06T00:56:22.331741Z","end":"2026-08-06T00:56:22.454862Z","steps":["trace[29942623] 'process raft request' (duration: 122.907658ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:57:06.65985Z","caller":"traceutil/trace.go:171","msg":"trace[1710822117] transaction","detail":"{read_only:false; response_revision:1935; number_of_response:1; }","duration":"105.803957ms","start":"2026-08-06T00:57:06.554018Z","end":"2026-08-06T00:57:06.659822Z","steps":["trace[1710822117] 'process raft request' (duration: 105.634472ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:57:06.858648Z","caller":"traceutil/trace.go:171","msg":"trace[1245135496] transaction","detail":"{read_only:false; response_revision:1936; number_of_response:1; }","duration":"123.04667ms","start":"2026-08-06T00:57:06.735543Z","end":"2026-08-06T00:57:06.858589Z","steps":["trace[1245135496] 'process raft request' (duration: 28.6794ms)","trace[1245135496] 'compare' (duration: 94.008509ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:57:07.582239Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"623.385821ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T00:57:07.582343Z","caller":"traceutil/trace.go:171","msg":"trace[1734190837] linearizableReadLoop","detail":"{readStateIndex:2020; appliedIndex:2019; }","duration":"641.231462ms","start":"2026-08-06T00:57:06.941095Z","end":"2026-08-06T00:57:07.582326Z","steps":["trace[1734190837] 'read index received' (duration: 17.595653ms)","trace[1734190837] 'applied index is now lower than readState.Index' (duration: 623.634679ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:57:07.582376Z","caller":"traceutil/trace.go:171","msg":"trace[1947383303] transaction","detail":"{read_only:false; response_revision:1938; number_of_response:1; }","duration":"649.348097ms","start":"2026-08-06T00:57:06.933004Z","end":"2026-08-06T00:57:07.582352Z","steps":["trace[1947383303] 'process raft request' (duration: 25.76599ms)","trace[1947383303] 'compare' (duration: 622.739132ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:57:07.582487Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:57:06.932979Z","time spent":"649.456361ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T00:57:07.582641Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.167198ms","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":"info","ts":"2026-08-06T00:57:07.582678Z","caller":"traceutil/trace.go:171","msg":"trace[1590033827] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1938; }","duration":"602.24108ms","start":"2026-08-06T00:57:06.980424Z","end":"2026-08-06T00:57:07.582665Z","steps":["trace[1590033827] 'agreement among raft nodes before linearized reading' (duration: 602.037114ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:57:07.582678Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.944161ms","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-08-06T00:57:07.58271Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:57:06.980355Z","time spent":"602.348333ms","remote":"127.0.0.1:57030","response type":"/etcdserverpb.KV/Range","request count":0,"request size":74,"response count":1,"response size":5468,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" "} {"level":"warn","ts":"2026-08-06T00:57:07.582705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.566399ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:491"} {"level":"info","ts":"2026-08-06T00:57:07.582737Z","caller":"traceutil/trace.go:171","msg":"trace[498838423] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:1938; }","duration":"366.048035ms","start":"2026-08-06T00:57:07.216674Z","end":"2026-08-06T00:57:07.582722Z","steps":["trace[498838423] 'agreement among raft nodes before linearized reading' (duration: 365.90126ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:57:07.582751Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"364.163308ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"warn","ts":"2026-08-06T00:57:07.582774Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:57:07.216651Z","time spent":"366.116547ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-06T00:57:07.582808Z","caller":"traceutil/trace.go:171","msg":"trace[1336252064] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1938; }","duration":"364.260811ms","start":"2026-08-06T00:57:07.218531Z","end":"2026-08-06T00:57:07.582792Z","steps":["trace[1336252064] 'agreement among raft nodes before linearized reading' (duration: 364.113556ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:57:07.582802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"598.614409ms","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-08-06T00:57:07.582861Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:57:07.218509Z","time spent":"364.340963ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":529,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T00:57:07.582878Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"360.685402ms","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-08-06T00:57:07.582893Z","caller":"traceutil/trace.go:171","msg":"trace[1015329443] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:1938; }","duration":"598.725423ms","start":"2026-08-06T00:57:06.984141Z","end":"2026-08-06T00:57:07.582866Z","steps":["trace[1015329443] 'agreement among raft nodes before linearized reading' (duration: 598.518757ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:57:07.582921Z","caller":"traceutil/trace.go:171","msg":"trace[852796230] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:1938; }","duration":"360.779055ms","start":"2026-08-06T00:57:07.222128Z","end":"2026-08-06T00:57:07.582907Z","steps":["trace[852796230] 'agreement among raft nodes before linearized reading' (duration: 360.659782ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:57:07.582937Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:57:06.984128Z","time spent":"598.800335ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-06T00:57:07.582959Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:57:07.222102Z","time spent":"360.848607ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-06T00:57:07.582764Z","caller":"traceutil/trace.go:171","msg":"trace[1359841883] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:1938; }","duration":"360.665332ms","start":"2026-08-06T00:57:07.222083Z","end":"2026-08-06T00:57:07.582749Z","steps":["trace[1359841883] 'agreement among raft nodes before linearized reading' (duration: 360.391704ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:57:07.583021Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:57:07.222062Z","time spent":"360.9478ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":515,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-06T00:57:07.583774Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"642.150049ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:57:07.58388Z","caller":"traceutil/trace.go:171","msg":"trace[1936321019] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1938; }","duration":"642.776749ms","start":"2026-08-06T00:57:06.941083Z","end":"2026-08-06T00:57:07.583859Z","steps":["trace[1936321019] 'agreement among raft nodes before linearized reading' (duration: 641.325804ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:57:07.584145Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:57:06.941049Z","time spent":"642.919063ms","remote":"127.0.0.1:54214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-06T00:57:21.15863Z","caller":"traceutil/trace.go:171","msg":"trace[1554245001] transaction","detail":"{read_only:false; response_revision:2053; number_of_response:1; }","duration":"182.257684ms","start":"2026-08-06T00:57:20.976349Z","end":"2026-08-06T00:57:21.158607Z","steps":["trace[1554245001] 'process raft request' (duration: 182.12911ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:57:41.665614Z","caller":"traceutil/trace.go:171","msg":"trace[555887792] transaction","detail":"{read_only:false; response_revision:2180; number_of_response:1; }","duration":"114.063836ms","start":"2026-08-06T00:57:41.551517Z","end":"2026-08-06T00:57:41.665581Z","steps":["trace[555887792] 'process raft request' (duration: 113.869621ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:57:42.476142Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"279.650359ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-06T00:57:42.476479Z","caller":"traceutil/trace.go:171","msg":"trace[323890431] transaction","detail":"{read_only:false; response_revision:2187; number_of_response:1; }","duration":"608.397553ms","start":"2026-08-06T00:57:41.868011Z","end":"2026-08-06T00:57:42.476408Z","steps":["trace[323890431] 'process raft request' (duration: 328.383722ms)","trace[323890431] 'compare' (duration: 279.515195ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:57:42.476638Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:57:41.867981Z","time spent":"608.575958ms","remote":"127.0.0.1:54316","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":819,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-08-06T00:57:42.476724Z","caller":"traceutil/trace.go:171","msg":"trace[662992406] linearizableReadLoop","detail":"{readStateIndex:2277; appliedIndex:2276; }","duration":"534.321591ms","start":"2026-08-06T00:57:41.942317Z","end":"2026-08-06T00:57:42.476639Z","steps":["trace[662992406] 'read index received' (duration: 254.094864ms)","trace[662992406] 'applied index is now lower than readState.Index' (duration: 280.223306ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:57:42.476933Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"534.584788ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:57:42.47702Z","caller":"traceutil/trace.go:171","msg":"trace[502261511] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2187; }","duration":"534.740784ms","start":"2026-08-06T00:57:41.942256Z","end":"2026-08-06T00:57:42.476997Z","steps":["trace[502261511] 'agreement among raft nodes before linearized reading' (duration: 534.493176ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:57:42.477066Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:57:41.942232Z","time spent":"534.825525ms","remote":"127.0.0.1:54214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-08-06T00:57:42.477433Z","caller":"traceutil/trace.go:171","msg":"trace[1927348091] transaction","detail":"{read_only:false; response_revision:2188; number_of_response:1; }","duration":"420.410278ms","start":"2026-08-06T00:57:42.056975Z","end":"2026-08-06T00:57:42.477385Z","steps":["trace[1927348091] 'process raft request' (duration: 419.809859ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:57:42.477471Z","caller":"traceutil/trace.go:171","msg":"trace[106132788] transaction","detail":"{read_only:false; response_revision:2189; number_of_response:1; }","duration":"414.783132ms","start":"2026-08-06T00:57:42.062621Z","end":"2026-08-06T00:57:42.477404Z","steps":["trace[106132788] 'process raft request' (duration: 414.611447ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:57:42.477648Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:57:42.056952Z","time spent":"420.585252ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T00:57:42.477871Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:57:42.062611Z","time spent":"415.04922ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":555,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T00:57:42.478021Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.603539ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-08-06T00:57:42.478089Z","caller":"traceutil/trace.go:171","msg":"trace[441856917] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:2190; }","duration":"199.700303ms","start":"2026-08-06T00:57:42.27837Z","end":"2026-08-06T00:57:42.478071Z","steps":["trace[441856917] 'agreement among raft nodes before linearized reading' (duration: 199.451995ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:58:46.315158Z","caller":"traceutil/trace.go:171","msg":"trace[1565957501] linearizableReadLoop","detail":"{readStateIndex:2738; appliedIndex:2737; }","duration":"139.744002ms","start":"2026-08-06T00:58:46.175391Z","end":"2026-08-06T00:58:46.315135Z","steps":["trace[1565957501] 'read index received' (duration: 139.505861ms)","trace[1565957501] 'applied index is now lower than readState.Index' (duration: 237.271µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:58:46.315589Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"140.186336ms","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-08-06T00:58:46.315641Z","caller":"traceutil/trace.go:171","msg":"trace[173772581] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2632; }","duration":"140.252246ms","start":"2026-08-06T00:58:46.175375Z","end":"2026-08-06T00:58:46.315627Z","steps":["trace[173772581] 'agreement among raft nodes before linearized reading' (duration: 139.893763ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:58:46.31583Z","caller":"traceutil/trace.go:171","msg":"trace[574747843] transaction","detail":"{read_only:false; response_revision:2632; number_of_response:1; }","duration":"264.409365ms","start":"2026-08-06T00:58:46.050855Z","end":"2026-08-06T00:58:46.315265Z","steps":["trace[574747843] 'process raft request' (duration: 264.091773ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:59:18.671305Z","caller":"traceutil/trace.go:171","msg":"trace[566279455] linearizableReadLoop","detail":"{readStateIndex:2921; appliedIndex:2920; }","duration":"217.927434ms","start":"2026-08-06T00:59:18.453352Z","end":"2026-08-06T00:59:18.671279Z","steps":["trace[566279455] 'read index received' (duration: 217.70007ms)","trace[566279455] 'applied index is now lower than readState.Index' (duration: 226.464µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:59:18.671397Z","caller":"traceutil/trace.go:171","msg":"trace[360744147] transaction","detail":"{read_only:false; response_revision:2809; number_of_response:1; }","duration":"243.72683ms","start":"2026-08-06T00:59:18.427614Z","end":"2026-08-06T00:59:18.671341Z","steps":["trace[360744147] 'process raft request' (duration: 243.506356ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:18.671508Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"218.127356ms","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-08-06T00:59:18.671558Z","caller":"traceutil/trace.go:171","msg":"trace[2069895587] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:2809; }","duration":"218.220297ms","start":"2026-08-06T00:59:18.453317Z","end":"2026-08-06T00:59:18.671537Z","steps":["trace[2069895587] 'agreement among raft nodes before linearized reading' (duration: 218.128736ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:18.672007Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.228548ms","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-08-06T00:59:18.672088Z","caller":"traceutil/trace.go:171","msg":"trace[510187061] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:2809; }","duration":"169.34305ms","start":"2026-08-06T00:59:18.502707Z","end":"2026-08-06T00:59:18.67205Z","steps":["trace[510187061] 'agreement among raft nodes before linearized reading' (duration: 169.072017ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:18.672162Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.227599ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-06T00:59:18.672151Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.485357ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:492"} {"level":"info","ts":"2026-08-06T00:59:18.672206Z","caller":"traceutil/trace.go:171","msg":"trace[1922656191] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2809; }","duration":"150.40037ms","start":"2026-08-06T00:59:18.521795Z","end":"2026-08-06T00:59:18.672196Z","steps":["trace[1922656191] 'agreement among raft nodes before linearized reading' (duration: 150.228708ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:59:18.672251Z","caller":"traceutil/trace.go:171","msg":"trace[669622569] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2809; }","duration":"152.619099ms","start":"2026-08-06T00:59:18.519606Z","end":"2026-08-06T00:59:18.672225Z","steps":["trace[669622569] 'agreement among raft nodes before linearized reading' (duration: 152.337155ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:18.672528Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"154.185728ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:438"} {"level":"warn","ts":"2026-08-06T00:59:18.672568Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.544504ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-06T00:59:18.672574Z","caller":"traceutil/trace.go:171","msg":"trace[465505081] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:2809; }","duration":"154.27734ms","start":"2026-08-06T00:59:18.518284Z","end":"2026-08-06T00:59:18.672562Z","steps":["trace[465505081] 'agreement among raft nodes before linearized reading' (duration: 154.156948ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:59:18.672601Z","caller":"traceutil/trace.go:171","msg":"trace[1703538425] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:2809; }","duration":"166.617225ms","start":"2026-08-06T00:59:18.505974Z","end":"2026-08-06T00:59:18.672591Z","steps":["trace[1703538425] 'agreement among raft nodes before linearized reading' (duration: 166.522794ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:59:18.793882Z","caller":"traceutil/trace.go:171","msg":"trace[387005173] linearizableReadLoop","detail":"{readStateIndex:2926; appliedIndex:2921; }","duration":"111.356276ms","start":"2026-08-06T00:59:18.682496Z","end":"2026-08-06T00:59:18.793852Z","steps":["trace[387005173] 'read index received' (duration: 9.085894ms)","trace[387005173] 'applied index is now lower than readState.Index' (duration: 102.269132ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:59:18.79452Z","caller":"traceutil/trace.go:171","msg":"trace[1846041151] transaction","detail":"{read_only:false; response_revision:2814; number_of_response:1; }","duration":"112.27913ms","start":"2026-08-06T00:59:18.682202Z","end":"2026-08-06T00:59:18.794481Z","steps":["trace[1846041151] 'process raft request' (duration: 111.615571ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:59:18.794853Z","caller":"traceutil/trace.go:171","msg":"trace[1028805813] transaction","detail":"{read_only:false; response_revision:2811; number_of_response:1; }","duration":"114.878501ms","start":"2026-08-06T00:59:18.679964Z","end":"2026-08-06T00:59:18.794842Z","steps":["trace[1028805813] 'process raft request' (duration: 113.547344ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:18.794816Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.275998ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-08-06T00:59:18.794934Z","caller":"traceutil/trace.go:171","msg":"trace[280949950] transaction","detail":"{read_only:false; response_revision:2812; number_of_response:1; }","duration":"114.517837ms","start":"2026-08-06T00:59:18.680405Z","end":"2026-08-06T00:59:18.794923Z","steps":["trace[280949950] 'process raft request' (duration: 113.299761ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:59:18.794876Z","caller":"traceutil/trace.go:171","msg":"trace[489208883] transaction","detail":"{read_only:false; response_revision:2813; number_of_response:1; }","duration":"113.548645ms","start":"2026-08-06T00:59:18.681304Z","end":"2026-08-06T00:59:18.794852Z","steps":["trace[489208883] 'process raft request' (duration: 112.456891ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T00:59:18.795202Z","caller":"traceutil/trace.go:171","msg":"trace[1597841695] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2814; }","duration":"112.487962ms","start":"2026-08-06T00:59:18.682479Z","end":"2026-08-06T00:59:18.794967Z","steps":["trace[1597841695] 'agreement among raft nodes before linearized reading' (duration: 111.943945ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:24.251296Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"253.04757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-06T00:59:24.251452Z","caller":"traceutil/trace.go:171","msg":"trace[806967390] linearizableReadLoop","detail":"{readStateIndex:2953; appliedIndex:2952; }","duration":"347.495544ms","start":"2026-08-06T00:59:23.903918Z","end":"2026-08-06T00:59:24.251413Z","steps":["trace[806967390] 'read index received' (duration: 94.009989ms)","trace[806967390] 'applied index is now lower than readState.Index' (duration: 253.483375ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T00:59:24.251629Z","caller":"traceutil/trace.go:171","msg":"trace[1314213514] transaction","detail":"{read_only:false; response_revision:2840; number_of_response:1; }","duration":"349.394109ms","start":"2026-08-06T00:59:23.902175Z","end":"2026-08-06T00:59:24.251569Z","steps":["trace[1314213514] 'process raft request' (duration: 95.978994ms)","trace[1314213514] 'compare' (duration: 252.589893ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T00:59:24.251849Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"347.92749ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12211"} {"level":"info","ts":"2026-08-06T00:59:24.251912Z","caller":"traceutil/trace.go:171","msg":"trace[1153547234] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2840; }","duration":"348.010152ms","start":"2026-08-06T00:59:23.90388Z","end":"2026-08-06T00:59:24.25189Z","steps":["trace[1153547234] 'agreement among raft nodes before linearized reading' (duration: 347.84758ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:24.251982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:23.903866Z","time spent":"348.099193ms","remote":"127.0.0.1:54434","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12235,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-08-06T00:59:24.251894Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:23.902155Z","time spent":"349.554452ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T00:59:24.286184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"343.010469ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T00:59:24.286282Z","caller":"traceutil/trace.go:171","msg":"trace[483727975] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2841; }","duration":"343.239143ms","start":"2026-08-06T00:59:23.943027Z","end":"2026-08-06T00:59:24.286266Z","steps":["trace[483727975] 'agreement among raft nodes before linearized reading' (duration: 343.04118ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T00:59:24.28632Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T00:59:23.943004Z","time spent":"343.308293ms","remote":"127.0.0.1:54214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T00:59:24.286448Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.992838ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/auth-system/keycloak-headless\" ","response":"range_response_count:1 size:1369"} {"level":"info","ts":"2026-08-06T00:59:24.286502Z","caller":"traceutil/trace.go:171","msg":"trace[688035845] range","detail":"{range_begin:/registry/services/specs/auth-system/keycloak-headless; range_end:; response_count:1; response_revision:2841; }","duration":"267.095329ms","start":"2026-08-06T00:59:24.019393Z","end":"2026-08-06T00:59:24.286489Z","steps":["trace[688035845] 'agreement among raft nodes before linearized reading' (duration: 266.823885ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:01:09.526213Z","caller":"traceutil/trace.go:171","msg":"trace[913889798] linearizableReadLoop","detail":"{readStateIndex:3518; appliedIndex:3517; }","duration":"266.504577ms","start":"2026-08-06T01:01:09.259684Z","end":"2026-08-06T01:01:09.526188Z","steps":["trace[913889798] 'read index received' (duration: 266.357513ms)","trace[913889798] 'applied index is now lower than readState.Index' (duration: 146.174µs)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:01:09.526434Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.744171ms","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-08-06T01:01:09.526406Z","caller":"traceutil/trace.go:171","msg":"trace[1250980773] transaction","detail":"{read_only:false; response_revision:3383; number_of_response:1; }","duration":"464.649286ms","start":"2026-08-06T01:01:09.061617Z","end":"2026-08-06T01:01:09.526267Z","steps":["trace[1250980773] 'process raft request' (duration: 464.403381ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:01:09.526477Z","caller":"traceutil/trace.go:171","msg":"trace[640068745] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:3383; }","duration":"266.867965ms","start":"2026-08-06T01:01:09.259599Z","end":"2026-08-06T01:01:09.526466Z","steps":["trace[640068745] 'agreement among raft nodes before linearized reading' (duration: 266.719201ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:01:09.526709Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:01:09.061592Z","time spent":"464.963964ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":534,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:01:09.98529Z","caller":"traceutil/trace.go:171","msg":"trace[1660568711] linearizableReadLoop","detail":"{readStateIndex:3521; appliedIndex:3518; }","duration":"458.977971ms","start":"2026-08-06T01:01:09.526292Z","end":"2026-08-06T01:01:09.985271Z","steps":["trace[1660568711] 'read index received' (duration: 458.878819ms)","trace[1660568711] 'applied index is now lower than readState.Index' (duration: 97.922µs)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:01:09.985346Z","caller":"traceutil/trace.go:171","msg":"trace[1954012558] transaction","detail":"{read_only:false; response_revision:3386; number_of_response:1; }","duration":"623.409729ms","start":"2026-08-06T01:01:09.361921Z","end":"2026-08-06T01:01:09.985331Z","steps":["trace[1954012558] 'process raft request' (duration: 623.324248ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:01:09.985317Z","caller":"traceutil/trace.go:171","msg":"trace[642802921] transaction","detail":"{read_only:false; response_revision:3384; number_of_response:1; }","duration":"640.175633ms","start":"2026-08-06T01:01:09.345104Z","end":"2026-08-06T01:01:09.985279Z","steps":["trace[642802921] 'process raft request' (duration: 639.977559ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:01:09.985415Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"605.94655ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-06T01:01:09.985453Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:01:09.361914Z","time spent":"623.467611ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":474,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:01:09.985467Z","caller":"traceutil/trace.go:171","msg":"trace[1733852319] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gateways/; range_end:/registry/gateway.networking.k8s.io/gateways0; response_count:0; response_revision:3386; }","duration":"606.009791ms","start":"2026-08-06T01:01:09.379446Z","end":"2026-08-06T01:01:09.985456Z","steps":["trace[1733852319] 'agreement among raft nodes before linearized reading' (duration: 605.915959ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:01:09.985491Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:01:09.379442Z","time spent":"606.041473ms","remote":"127.0.0.1:48746","response type":"/etcdserverpb.KV/Range","request count":0,"request size":96,"response count":0,"response size":29,"request content":"key:\"/registry/gateway.networking.k8s.io/gateways/\" range_end:\"/registry/gateway.networking.k8s.io/gateways0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:01:09.985502Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:01:09.345077Z","time spent":"640.322317ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:01:09.985604Z","caller":"traceutil/trace.go:171","msg":"trace[1134016151] transaction","detail":"{read_only:false; response_revision:3385; number_of_response:1; }","duration":"633.123787ms","start":"2026-08-06T01:01:09.352473Z","end":"2026-08-06T01:01:09.985596Z","steps":["trace[1134016151] 'process raft request' (duration: 632.749259ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:01:09.985653Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:01:09.352455Z","time spent":"633.176178ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:01:09.985655Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"608.269242ms","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-08-06T01:01:09.985677Z","caller":"traceutil/trace.go:171","msg":"trace[167047352] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:3386; }","duration":"608.303773ms","start":"2026-08-06T01:01:09.377367Z","end":"2026-08-06T01:01:09.985671Z","steps":["trace[167047352] 'agreement among raft nodes before linearized reading' (duration: 608.230921ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:01:09.9857Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:01:09.377363Z","time spent":"608.332193ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-06T01:01:09.985789Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"609.001138ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-06T01:01:09.985814Z","caller":"traceutil/trace.go:171","msg":"trace[1395151869] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:3386; }","duration":"609.051249ms","start":"2026-08-06T01:01:09.376757Z","end":"2026-08-06T01:01:09.985808Z","steps":["trace[1395151869] 'agreement among raft nodes before linearized reading' (duration: 608.982357ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:01:09.985832Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:01:09.376748Z","time spent":"609.07974ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T01:01:09.985852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"601.505801ms","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-08-06T01:01:09.985896Z","caller":"traceutil/trace.go:171","msg":"trace[462764027] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:3386; }","duration":"601.567152ms","start":"2026-08-06T01:01:09.384315Z","end":"2026-08-06T01:01:09.985882Z","steps":["trace[462764027] 'agreement among raft nodes before linearized reading' (duration: 601.446119ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:01:09.985929Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:01:09.384311Z","time spent":"601.611152ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":580,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-06T01:01:09.985935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"695.880627ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:01:09.985956Z","caller":"traceutil/trace.go:171","msg":"trace[150859358] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:3386; }","duration":"695.944078ms","start":"2026-08-06T01:01:09.290005Z","end":"2026-08-06T01:01:09.985949Z","steps":["trace[150859358] 'agreement among raft nodes before linearized reading' (duration: 695.910847ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:01:09.985978Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:01:09.289985Z","time spent":"695.987939ms","remote":"127.0.0.1:54214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T01:01:09.986157Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"602.458623ms","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-08-06T01:01:09.987297Z","caller":"traceutil/trace.go:171","msg":"trace[1427718736] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:3386; }","duration":"603.637489ms","start":"2026-08-06T01:01:09.383623Z","end":"2026-08-06T01:01:09.987261Z","steps":["trace[1427718736] 'agreement among raft nodes before linearized reading' (duration: 601.677285ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:01:09.987337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.017218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:01:09.987369Z","caller":"traceutil/trace.go:171","msg":"trace[1512895583] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:3387; }","duration":"414.09104ms","start":"2026-08-06T01:01:09.573268Z","end":"2026-08-06T01:01:09.987359Z","steps":["trace[1512895583] 'agreement among raft nodes before linearized reading' (duration: 414.024698ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:01:09.987395Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:01:09.573247Z","time spent":"414.141471ms","remote":"127.0.0.1:57008","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"info","ts":"2026-08-06T01:01:09.987399Z","caller":"traceutil/trace.go:171","msg":"trace[1917820368] transaction","detail":"{read_only:false; response_revision:3387; number_of_response:1; }","duration":"456.002745ms","start":"2026-08-06T01:01:09.531385Z","end":"2026-08-06T01:01:09.987388Z","steps":["trace[1917820368] 'process raft request' (duration: 455.831931ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:01:09.987444Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:01:09.531365Z","time spent":"456.055966ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":418,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:01:09.987661Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:01:09.383618Z","time spent":"603.735731ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-06T01:03:16.181838Z","caller":"traceutil/trace.go:171","msg":"trace[905966697] transaction","detail":"{read_only:false; number_of_response:1; response_revision:4555; }","duration":"196.95197ms","start":"2026-08-06T01:03:15.984858Z","end":"2026-08-06T01:03:16.18181Z","steps":["trace[905966697] 'process raft request' (duration: 127.790984ms)","trace[905966697] 'compare' (duration: 68.872799ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:03:16.18221Z","caller":"traceutil/trace.go:171","msg":"trace[1647488793] transaction","detail":"{read_only:false; response_revision:4557; number_of_response:1; }","duration":"178.852607ms","start":"2026-08-06T01:03:16.00333Z","end":"2026-08-06T01:03:16.182183Z","steps":["trace[1647488793] 'process raft request' (duration: 178.644852ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:16.182348Z","caller":"traceutil/trace.go:171","msg":"trace[676821189] linearizableReadLoop","detail":"{readStateIndex:4744; appliedIndex:4741; }","duration":"147.451608ms","start":"2026-08-06T01:03:16.034873Z","end":"2026-08-06T01:03:16.182325Z","steps":["trace[676821189] 'read index received' (duration: 77.778848ms)","trace[676821189] 'applied index is now lower than readState.Index' (duration: 69.67167ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:03:16.182623Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.738336ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-tls\" ","response":"range_response_count:1 size:4330"} {"level":"info","ts":"2026-08-06T01:03:16.182661Z","caller":"traceutil/trace.go:171","msg":"trace[51509320] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-tls; range_end:; response_count:1; response_revision:4559; }","duration":"147.817208ms","start":"2026-08-06T01:03:16.034834Z","end":"2026-08-06T01:03:16.182651Z","steps":["trace[51509320] 'agreement among raft nodes before linearized reading' (duration: 147.651163ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:16.182671Z","caller":"traceutil/trace.go:171","msg":"trace[759208783] transaction","detail":"{read_only:false; response_revision:4556; number_of_response:1; }","duration":"185.67584ms","start":"2026-08-06T01:03:15.996971Z","end":"2026-08-06T01:03:16.182647Z","steps":["trace[759208783] 'process raft request' (duration: 184.781746ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:16.376664Z","caller":"traceutil/trace.go:171","msg":"trace[968063404] transaction","detail":"{read_only:false; response_revision:4561; number_of_response:1; }","duration":"188.651739ms","start":"2026-08-06T01:03:16.187985Z","end":"2026-08-06T01:03:16.376637Z","steps":["trace[968063404] 'process raft request' (duration: 187.812706ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:03:16.376802Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.999377ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/monitoring/kube-prometheus-stack-admission-patch\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:03:16.376893Z","caller":"traceutil/trace.go:171","msg":"trace[1964538325] range","detail":"{range_begin:/registry/jobs/monitoring/kube-prometheus-stack-admission-patch; range_end:; response_count:0; response_revision:4561; }","duration":"183.129311ms","start":"2026-08-06T01:03:16.19375Z","end":"2026-08-06T01:03:16.376879Z","steps":["trace[1964538325] 'agreement among raft nodes before linearized reading' (duration: 182.938976ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:16.377431Z","caller":"traceutil/trace.go:171","msg":"trace[322705201] linearizableReadLoop","detail":"{readStateIndex:4748; appliedIndex:4747; }","duration":"182.834073ms","start":"2026-08-06T01:03:16.193782Z","end":"2026-08-06T01:03:16.376616Z","steps":["trace[322705201] 'read index received' (duration: 45.224868ms)","trace[322705201] 'applied index is now lower than readState.Index' (duration: 137.607675ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:03:16.377719Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.278973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/grafana-tls-5w87f\" ","response":"range_response_count:1 size:2444"} {"level":"info","ts":"2026-08-06T01:03:16.377791Z","caller":"traceutil/trace.go:171","msg":"trace[182776104] range","detail":"{range_begin:/registry/secrets/monitoring/grafana-tls-5w87f; range_end:; response_count:1; response_revision:4561; }","duration":"144.395366ms","start":"2026-08-06T01:03:16.233385Z","end":"2026-08-06T01:03:16.37778Z","steps":["trace[182776104] 'agreement among raft nodes before linearized reading' (duration: 144.15445ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:21.679785Z","caller":"traceutil/trace.go:171","msg":"trace[2133527268] transaction","detail":"{read_only:false; response_revision:4703; number_of_response:1; }","duration":"164.796199ms","start":"2026-08-06T01:03:21.514953Z","end":"2026-08-06T01:03:21.679749Z","steps":["trace[2133527268] 'process raft request' (duration: 119.608977ms)","trace[2133527268] 'compare' (duration: 45.039218ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:03:31.789638Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"391.236561ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-06T01:03:31.789912Z","caller":"traceutil/trace.go:171","msg":"trace[860392413] linearizableReadLoop","detail":"{readStateIndex:5070; appliedIndex:5069; }","duration":"450.288536ms","start":"2026-08-06T01:03:31.339602Z","end":"2026-08-06T01:03:31.789891Z","steps":["trace[860392413] 'read index received' (duration: 58.727915ms)","trace[860392413] 'applied index is now lower than readState.Index' (duration: 391.555201ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:03:31.78998Z","caller":"traceutil/trace.go:171","msg":"trace[233880578] transaction","detail":"{read_only:false; response_revision:4875; number_of_response:1; }","duration":"451.407565ms","start":"2026-08-06T01:03:31.338537Z","end":"2026-08-06T01:03:31.789945Z","steps":["trace[233880578] 'process raft request' (duration: 59.763743ms)","trace[233880578] 'compare' (duration: 390.887432ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:03:31.790355Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:03:31.338525Z","time spent":"451.779365ms","remote":"127.0.0.1:54604","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":892,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-08-06T01:03:31.790424Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"176.538846ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/\" range_end:\"/registry/serviceaccounts0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"warn","ts":"2026-08-06T01:03:31.790407Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"450.782509ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" ","response":"range_response_count:1 size:1285"} {"level":"info","ts":"2026-08-06T01:03:31.790513Z","caller":"traceutil/trace.go:171","msg":"trace[30981616] range","detail":"{range_begin:/registry/serviceaccounts/; range_end:/registry/serviceaccounts0; response_count:0; response_revision:4875; }","duration":"176.678609ms","start":"2026-08-06T01:03:31.613816Z","end":"2026-08-06T01:03:31.790495Z","steps":["trace[30981616] 'agreement among raft nodes before linearized reading' (duration: 176.425172ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:03:31.790608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"233.255776ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:12973"} {"level":"warn","ts":"2026-08-06T01:03:31.790646Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"224.451689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0\" ","response":"range_response_count:1 size:1935"} {"level":"info","ts":"2026-08-06T01:03:31.790672Z","caller":"traceutil/trace.go:171","msg":"trace[996654723] range","detail":"{range_begin:/registry/pods/monitoring/alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4875; }","duration":"233.349829ms","start":"2026-08-06T01:03:31.557306Z","end":"2026-08-06T01:03:31.790655Z","steps":["trace[996654723] 'agreement among raft nodes before linearized reading' (duration: 233.090552ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:31.790524Z","caller":"traceutil/trace.go:171","msg":"trace[546580915] range","detail":"{range_begin:/registry/services/specs/monitoring/alertmanager-operated; range_end:; response_count:1; response_revision:4875; }","duration":"450.927842ms","start":"2026-08-06T01:03:31.339578Z","end":"2026-08-06T01:03:31.790506Z","steps":["trace[546580915] 'agreement among raft nodes before linearized reading' (duration: 450.449999ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:31.790815Z","caller":"traceutil/trace.go:171","msg":"trace[1182576838] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/alertmanager-kube-prometheus-stack-alertmanager-db-alertmanager-kube-prometheus-stack-alertmanager-0; range_end:; response_count:1; response_revision:4875; }","duration":"224.536931ms","start":"2026-08-06T01:03:31.56614Z","end":"2026-08-06T01:03:31.790677Z","steps":["trace[1182576838] 'agreement among raft nodes before linearized reading' (duration: 224.060048ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:03:31.79078Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:03:31.339568Z","time spent":"451.2011ms","remote":"127.0.0.1:54444","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":1309,"request content":"key:\"/registry/services/specs/monitoring/alertmanager-operated\" "} {"level":"warn","ts":"2026-08-06T01:03:31.79106Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"232.514297ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-6fbdfdd86-xn68p.18c911b85024f3cd\" ","response":"range_response_count:1 size:895"} {"level":"info","ts":"2026-08-06T01:03:31.791171Z","caller":"traceutil/trace.go:171","msg":"trace[85314229] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-6fbdfdd86-xn68p.18c911b85024f3cd; range_end:; response_count:1; response_revision:4875; }","duration":"232.679912ms","start":"2026-08-06T01:03:31.558477Z","end":"2026-08-06T01:03:31.791157Z","steps":["trace[85314229] 'agreement among raft nodes before linearized reading' (duration: 231.832429ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:03:31.791793Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"254.1548ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2207411"} {"level":"info","ts":"2026-08-06T01:03:31.791854Z","caller":"traceutil/trace.go:171","msg":"trace[1452176689] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4875; }","duration":"254.220103ms","start":"2026-08-06T01:03:31.53762Z","end":"2026-08-06T01:03:31.79184Z","steps":["trace[1452176689] 'agreement among raft nodes before linearized reading' (duration: 252.562948ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:03:31.916564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"105.301693ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager\" ","response":"range_response_count:1 size:4848"} {"level":"info","ts":"2026-08-06T01:03:31.916642Z","caller":"traceutil/trace.go:171","msg":"trace[409317558] range","detail":"{range_begin:/registry/monitoring.coreos.com/alertmanagers/monitoring/kube-prometheus-stack-alertmanager; range_end:; response_count:1; response_revision:4880; }","duration":"105.395336ms","start":"2026-08-06T01:03:31.81123Z","end":"2026-08-06T01:03:31.916625Z","steps":["trace[409317558] 'agreement among raft nodes before linearized reading' (duration: 105.17393ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:32.329006Z","caller":"traceutil/trace.go:171","msg":"trace[1801464553] linearizableReadLoop","detail":"{readStateIndex:5090; appliedIndex:5088; }","duration":"269.14291ms","start":"2026-08-06T01:03:32.059831Z","end":"2026-08-06T01:03:32.328974Z","steps":["trace[1801464553] 'read index received' (duration: 35.489649ms)","trace[1801464553] 'applied index is now lower than readState.Index' (duration: 233.651751ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:03:32.329014Z","caller":"traceutil/trace.go:171","msg":"trace[630517798] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"269.367336ms","start":"2026-08-06T01:03:32.059605Z","end":"2026-08-06T01:03:32.328973Z","steps":["trace[630517798] 'process raft request' (duration: 269.043858ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:32.329099Z","caller":"traceutil/trace.go:171","msg":"trace[1563058613] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"249.745516ms","start":"2026-08-06T01:03:32.079326Z","end":"2026-08-06T01:03:32.329071Z","steps":["trace[1563058613] 'process raft request' (duration: 249.496769ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:03:32.329599Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"269.742246ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0\" ","response":"range_response_count:1 size:1342"} {"level":"info","ts":"2026-08-06T01:03:32.329685Z","caller":"traceutil/trace.go:171","msg":"trace[358670953] range","detail":"{range_begin:/registry/secrets/monitoring/prometheus-kube-prometheus-stack-prometheus-tls-assets-0; range_end:; response_count:1; response_revision:4896; }","duration":"269.851849ms","start":"2026-08-06T01:03:32.059812Z","end":"2026-08-06T01:03:32.329663Z","steps":["trace[358670953] 'agreement among raft nodes before linearized reading' (duration: 269.312154ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:32.375963Z","caller":"traceutil/trace.go:171","msg":"trace[1137925971] transaction","detail":"{read_only:false; response_revision:4897; number_of_response:1; }","duration":"274.776252ms","start":"2026-08-06T01:03:32.10114Z","end":"2026-08-06T01:03:32.375917Z","steps":["trace[1137925971] 'process raft request' (duration: 274.499505ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:32.376261Z","caller":"traceutil/trace.go:171","msg":"trace[419487015] transaction","detail":"{read_only:false; response_revision:4899; number_of_response:1; }","duration":"111.06684ms","start":"2026-08-06T01:03:32.26514Z","end":"2026-08-06T01:03:32.376207Z","steps":["trace[419487015] 'process raft request' (duration: 110.755752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:03:32.376394Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.163533ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" ","response":"range_response_count:1 size:650"} {"level":"info","ts":"2026-08-06T01:03:32.37647Z","caller":"traceutil/trace.go:171","msg":"trace[1335225323] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0; range_end:; response_count:1; response_revision:4899; }","duration":"315.268246ms","start":"2026-08-06T01:03:32.061184Z","end":"2026-08-06T01:03:32.376453Z","steps":["trace[1335225323] 'agreement among raft nodes before linearized reading' (duration: 315.081711ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:03:32.376516Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:03:32.061162Z","time spent":"315.344337ms","remote":"127.0.0.1:54348","response type":"/etcdserverpb.KV/Range","request count":0,"request size":91,"response count":1,"response size":674,"request content":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-tls-assets-0\" "} {"level":"info","ts":"2026-08-06T01:03:32.376264Z","caller":"traceutil/trace.go:171","msg":"trace[1765775856] transaction","detail":"{read_only:false; response_revision:4898; number_of_response:1; }","duration":"137.494594ms","start":"2026-08-06T01:03:32.238514Z","end":"2026-08-06T01:03:32.376009Z","steps":["trace[1765775856] 'process raft request' (duration: 137.318869ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:03:32.378796Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"318.545976ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" ","response":"range_response_count:1 size:3224"} {"level":"info","ts":"2026-08-06T01:03:32.379206Z","caller":"traceutil/trace.go:171","msg":"trace[1378289295] range","detail":"{range_begin:/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone; range_end:; response_count:1; response_revision:4899; }","duration":"318.982507ms","start":"2026-08-06T01:03:32.060207Z","end":"2026-08-06T01:03:32.379189Z","steps":["trace[1378289295] 'agreement among raft nodes before linearized reading' (duration: 316.13595ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:03:32.379288Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:03:32.060199Z","time spent":"319.077519ms","remote":"127.0.0.1:56818","response type":"/etcdserverpb.KV/Range","request count":0,"request size":69,"response count":1,"response size":3248,"request content":"key:\"/registry/rabbitmq.com/rabbitmqclusters/openstack/rabbitmq-keystone\" "} {"level":"info","ts":"2026-08-06T01:03:32.584545Z","caller":"traceutil/trace.go:171","msg":"trace[707464866] transaction","detail":"{read_only:false; response_revision:4904; number_of_response:1; }","duration":"133.64792ms","start":"2026-08-06T01:03:32.45087Z","end":"2026-08-06T01:03:32.584518Z","steps":["trace[707464866] 'process raft request' (duration: 133.470755ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:32.584708Z","caller":"traceutil/trace.go:171","msg":"trace[199906770] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"133.943428ms","start":"2026-08-06T01:03:32.450739Z","end":"2026-08-06T01:03:32.584682Z","steps":["trace[199906770] 'process raft request' (duration: 90.702ms)","trace[199906770] 'compare' (duration: 42.60256ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:03:32.584973Z","caller":"traceutil/trace.go:171","msg":"trace[1524974547] linearizableReadLoop","detail":"{readStateIndex:5099; appliedIndex:5097; }","duration":"128.836031ms","start":"2026-08-06T01:03:32.456117Z","end":"2026-08-06T01:03:32.584953Z","steps":["trace[1524974547] 'read index received' (duration: 85.254663ms)","trace[1524974547] 'applied index is now lower than readState.Index' (duration: 43.579658ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:03:32.5861Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"126.637951ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/specs/monitoring/prometheus-operated\" ","response":"range_response_count:1 size:993"} {"level":"info","ts":"2026-08-06T01:03:32.586166Z","caller":"traceutil/trace.go:171","msg":"trace[30982182] range","detail":"{range_begin:/registry/services/specs/monitoring/prometheus-operated; range_end:; response_count:1; response_revision:4906; }","duration":"126.739414ms","start":"2026-08-06T01:03:32.459413Z","end":"2026-08-06T01:03:32.586153Z","steps":["trace[30982182] 'agreement among raft nodes before linearized reading' (duration: 126.531168ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:03:32.586935Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"135.656754ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config\" ","response":"range_response_count:1 size:708"} {"level":"info","ts":"2026-08-06T01:03:32.586994Z","caller":"traceutil/trace.go:171","msg":"trace[493297209] range","detail":"{range_begin:/registry/secrets/monitoring/alertmanager-kube-prometheus-stack-alertmanager-web-config; range_end:; response_count:1; response_revision:4905; }","duration":"135.745037ms","start":"2026-08-06T01:03:32.451235Z","end":"2026-08-06T01:03:32.58698Z","steps":["trace[493297209] 'agreement among raft nodes before linearized reading' (duration: 133.901968ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:03:44.551834Z","caller":"traceutil/trace.go:171","msg":"trace[354213301] transaction","detail":"{read_only:false; response_revision:5061; number_of_response:1; }","duration":"124.241241ms","start":"2026-08-06T01:03:44.427573Z","end":"2026-08-06T01:03:44.551814Z","steps":["trace[354213301] 'process raft request' (duration: 124.100488ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:04:13.059768Z","caller":"traceutil/trace.go:171","msg":"trace[393325943] linearizableReadLoop","detail":"{readStateIndex:5572; appliedIndex:5571; }","duration":"113.129116ms","start":"2026-08-06T01:04:12.946617Z","end":"2026-08-06T01:04:13.059746Z","steps":["trace[393325943] 'read index received' (duration: 109.184467ms)","trace[393325943] 'applied index is now lower than readState.Index' (duration: 3.943569ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:04:13.059867Z","caller":"traceutil/trace.go:171","msg":"trace[330546724] transaction","detail":"{read_only:false; response_revision:5364; number_of_response:1; }","duration":"144.202674ms","start":"2026-08-06T01:04:12.915656Z","end":"2026-08-06T01:04:13.059858Z","steps":["trace[330546724] 'process raft request' (duration: 140.249334ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:04:13.060002Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.382834ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:04:13.060032Z","caller":"traceutil/trace.go:171","msg":"trace[884439286] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:5364; }","duration":"113.464646ms","start":"2026-08-06T01:04:12.94656Z","end":"2026-08-06T01:04:13.060025Z","steps":["trace[884439286] 'agreement among raft nodes before linearized reading' (duration: 113.391204ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:04:19.989127Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2819} {"level":"info","ts":"2026-08-06T01:04:20.1423Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2819,"took":"151.597973ms","hash":1510942531,"current-db-size-bytes":52617216,"current-db-size":"53 MB","current-db-size-in-use-bytes":28168192,"current-db-size-in-use":"28 MB"} {"level":"info","ts":"2026-08-06T01:04:20.142366Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1510942531,"revision":2819,"compact-revision":-1} {"level":"info","ts":"2026-08-06T01:04:39.061177Z","caller":"traceutil/trace.go:171","msg":"trace[1974193276] transaction","detail":"{read_only:false; response_revision:5529; number_of_response:1; }","duration":"104.270522ms","start":"2026-08-06T01:04:38.95687Z","end":"2026-08-06T01:04:39.061141Z","steps":["trace[1974193276] 'process raft request' (duration: 60.49394ms)","trace[1974193276] 'compare' (duration: 43.570616ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:08:57.392434Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403972036413110027,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:08:57.892657Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403972036413110027,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:08:58.393544Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403972036413110027,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:08:58.435052Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.54556462s","expected-duration":"1s"} {"level":"info","ts":"2026-08-06T01:08:58.435575Z","caller":"traceutil/trace.go:171","msg":"trace[51205815] transaction","detail":"{read_only:false; response_revision:7231; number_of_response:1; }","duration":"1.546130303s","start":"2026-08-06T01:08:56.88942Z","end":"2026-08-06T01:08:58.43555Z","steps":["trace[51205815] 'process raft request' (duration: 1.545895358s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:58.435804Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:56.889407Z","time spent":"1.546290506s","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":491,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:08:58.440405Z","caller":"traceutil/trace.go:171","msg":"trace[1447490877] transaction","detail":"{read_only:false; response_revision:7232; number_of_response:1; }","duration":"1.550704326s","start":"2026-08-06T01:08:56.889685Z","end":"2026-08-06T01:08:58.440389Z","steps":["trace[1447490877] 'process raft request' (duration: 1.550512761s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:58.440521Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:56.889678Z","time spent":"1.550796557s","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:08:58.440669Z","caller":"traceutil/trace.go:171","msg":"trace[839600708] transaction","detail":"{read_only:false; response_revision:7233; number_of_response:1; }","duration":"1.550758746s","start":"2026-08-06T01:08:56.889895Z","end":"2026-08-06T01:08:58.440654Z","steps":["trace[839600708] 'process raft request' (duration: 1.550384318s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:58.440802Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:56.88989Z","time spent":"1.550805807s","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:08:58.440977Z","caller":"traceutil/trace.go:171","msg":"trace[1255649626] transaction","detail":"{read_only:false; response_revision:7237; number_of_response:1; }","duration":"532.730557ms","start":"2026-08-06T01:08:57.908236Z","end":"2026-08-06T01:08:58.440966Z","steps":["trace[1255649626] 'process raft request' (duration: 532.667406ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:58.440988Z","caller":"traceutil/trace.go:171","msg":"trace[1522983351] transaction","detail":"{read_only:false; response_revision:7235; number_of_response:1; }","duration":"1.549625821s","start":"2026-08-06T01:08:56.891337Z","end":"2026-08-06T01:08:58.440963Z","steps":["trace[1522983351] 'process raft request' (duration: 1.549465348s)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:58.440989Z","caller":"traceutil/trace.go:171","msg":"trace[195184306] transaction","detail":"{read_only:false; response_revision:7236; number_of_response:1; }","duration":"699.592916ms","start":"2026-08-06T01:08:57.741385Z","end":"2026-08-06T01:08:58.440978Z","steps":["trace[195184306] 'process raft request' (duration: 699.491313ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:58.441063Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:57.908219Z","time spent":"532.800509ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:08:58.441062Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:57.741364Z","time spent":"699.671158ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:08:58.441109Z","caller":"traceutil/trace.go:171","msg":"trace[611140541] transaction","detail":"{read_only:false; response_revision:7234; number_of_response:1; }","duration":"1.550127692s","start":"2026-08-06T01:08:56.890972Z","end":"2026-08-06T01:08:58.4411Z","steps":["trace[611140541] 'process raft request' (duration: 1.549340355s)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:08:58.440988Z","caller":"traceutil/trace.go:171","msg":"trace[60184100] transaction","detail":"{read_only:false; response_revision:7238; number_of_response:1; }","duration":"499.082324ms","start":"2026-08-06T01:08:57.941898Z","end":"2026-08-06T01:08:58.440981Z","steps":["trace[60184100] 'process raft request' (duration: 499.028602ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:58.441163Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:56.890967Z","time spent":"1.550168084s","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":541,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:08:58.441076Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:56.891325Z","time spent":"1.549709254s","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:08:58.441169Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:57.941885Z","time spent":"499.264757ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:08:58.441408Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.549680494s","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-08-06T01:08:58.441434Z","caller":"traceutil/trace.go:171","msg":"trace[388667596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:7238; }","duration":"1.549720194s","start":"2026-08-06T01:08:56.891708Z","end":"2026-08-06T01:08:58.441428Z","steps":["trace[388667596] 'agreement among raft nodes before linearized reading' (duration: 1.549595801s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:58.441449Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:56.891701Z","time spent":"1.549744435s","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"info","ts":"2026-08-06T01:08:58.441818Z","caller":"traceutil/trace.go:171","msg":"trace[741700155] linearizableReadLoop","detail":"{readStateIndex:7517; appliedIndex:7509; }","duration":"1.550081443s","start":"2026-08-06T01:08:56.891725Z","end":"2026-08-06T01:08:58.441807Z","steps":["trace[741700155] 'read index received' (duration: 1.543689509s)","trace[741700155] 'applied index is now lower than readState.Index' (duration: 5.534444ms)"],"step_count":2} {"level":"warn","ts":"2026-08-06T01:08:58.44197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.493313221s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-06T01:08:58.442045Z","caller":"traceutil/trace.go:171","msg":"trace[1535560236] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:7238; }","duration":"1.493429154s","start":"2026-08-06T01:08:56.948607Z","end":"2026-08-06T01:08:58.442036Z","steps":["trace[1535560236] 'agreement among raft nodes before linearized reading' (duration: 1.493314901s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:58.442102Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:56.948591Z","time spent":"1.493492055s","remote":"127.0.0.1:54214","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":29,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-06T01:08:58.442163Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"414.008366ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/cert-manager/cert-manager-controller\" ","response":"range_response_count:1 size:528"} {"level":"info","ts":"2026-08-06T01:08:58.442229Z","caller":"traceutil/trace.go:171","msg":"trace[1690297349] range","detail":"{range_begin:/registry/leases/cert-manager/cert-manager-controller; range_end:; response_count:1; response_revision:7238; }","duration":"414.15327ms","start":"2026-08-06T01:08:58.02806Z","end":"2026-08-06T01:08:58.442213Z","steps":["trace[1690297349] 'agreement among raft nodes before linearized reading' (duration: 413.942565ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:58.44226Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:58.028046Z","time spent":"414.208411ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":55,"response count":1,"response size":552,"request content":"key:\"/registry/leases/cert-manager/cert-manager-controller\" "} {"level":"warn","ts":"2026-08-06T01:08:58.445366Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"951.474551ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:8"} {"level":"info","ts":"2026-08-06T01:08:58.445419Z","caller":"traceutil/trace.go:171","msg":"trace[1332990598] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:7238; }","duration":"951.533622ms","start":"2026-08-06T01:08:57.493876Z","end":"2026-08-06T01:08:58.44541Z","steps":["trace[1332990598] 'agreement among raft nodes before linearized reading' (duration: 948.31289ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:08:58.445441Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:08:57.493817Z","time spent":"951.616425ms","remote":"127.0.0.1:54316","response type":"/etcdserverpb.KV/Range","request count":0,"request size":40,"response count":698,"response size":32,"request content":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true "} {"level":"info","ts":"2026-08-06T01:09:19.996179Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5402} {"level":"info","ts":"2026-08-06T01:09:20.079395Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5402,"took":"81.754843ms","hash":2618267583,"current-db-size-bytes":52617216,"current-db-size":"53 MB","current-db-size-in-use-bytes":18620416,"current-db-size-in-use":"19 MB"} {"level":"info","ts":"2026-08-06T01:09:20.079505Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2618267583,"revision":5402,"compact-revision":2819} {"level":"warn","ts":"2026-08-06T01:13:38.220516Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2403972036413113772,"retry-timeout":"500ms"} {"level":"warn","ts":"2026-08-06T01:13:38.680859Z","caller":"wal/wal.go:805","msg":"slow fdatasync","took":"1.282216329s","expected-duration":"1s"} {"level":"info","ts":"2026-08-06T01:13:38.681247Z","caller":"traceutil/trace.go:171","msg":"trace[351176805] transaction","detail":"{read_only:false; response_revision:8640; number_of_response:1; }","duration":"1.28267095s","start":"2026-08-06T01:13:37.398557Z","end":"2026-08-06T01:13:38.681228Z","steps":["trace[351176805] 'process raft request' (duration: 1.282518747s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:38.68139Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:37.398536Z","time spent":"1.282784373s","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":510,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-06T01:13:38.683575Z","caller":"traceutil/trace.go:171","msg":"trace[148336852] linearizableReadLoop","detail":"{readStateIndex:8978; appliedIndex:8975; }","duration":"963.602559ms","start":"2026-08-06T01:13:37.71995Z","end":"2026-08-06T01:13:38.683552Z","steps":["trace[148336852] 'read index received' (duration: 961.098303ms)","trace[148336852] 'applied index is now lower than readState.Index' (duration: 2.503716ms)"],"step_count":2} {"level":"info","ts":"2026-08-06T01:13:38.683614Z","caller":"traceutil/trace.go:171","msg":"trace[1969374681] transaction","detail":"{read_only:false; response_revision:8641; number_of_response:1; }","duration":"1.278542343s","start":"2026-08-06T01:13:37.405056Z","end":"2026-08-06T01:13:38.683598Z","steps":["trace[1969374681] 'process raft request' (duration: 1.278364258s)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:13:38.68364Z","caller":"traceutil/trace.go:171","msg":"trace[1108792534] transaction","detail":"{read_only:false; response_revision:8642; number_of_response:1; }","duration":"1.277422633s","start":"2026-08-06T01:13:37.406211Z","end":"2026-08-06T01:13:38.683634Z","steps":["trace[1108792534] 'process raft request' (duration: 1.27731939s)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:38.683677Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:37.405044Z","time spent":"1.278600584s","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":556,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:13:38.683722Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:37.4062Z","time spent":"1.277458544s","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-08-06T01:13:38.683953Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"960.754183ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:507"} {"level":"info","ts":"2026-08-06T01:13:38.683983Z","caller":"traceutil/trace.go:171","msg":"trace[1864122119] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8642; }","duration":"960.804865ms","start":"2026-08-06T01:13:37.723171Z","end":"2026-08-06T01:13:38.683976Z","steps":["trace[1864122119] 'agreement among raft nodes before linearized reading' (duration: 960.696532ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:38.684002Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:37.723161Z","time spent":"960.836945ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":54,"response count":1,"response size":531,"request content":"key:\"/registry/leases/kube-system/kube-controller-manager\" "} {"level":"warn","ts":"2026-08-06T01:13:38.684089Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.829654ms","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-08-06T01:13:38.684113Z","caller":"traceutil/trace.go:171","msg":"trace[602140242] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:8642; }","duration":"831.895515ms","start":"2026-08-06T01:13:37.852212Z","end":"2026-08-06T01:13:38.684107Z","steps":["trace[602140242] 'agreement among raft nodes before linearized reading' (duration: 831.794063ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:38.68413Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:37.852202Z","time spent":"831.924527ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":462,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-08-06T01:13:38.684188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"833.920829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"warn","ts":"2026-08-06T01:13:38.684213Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"964.261946ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true ","response":"range_response_count:0 size:7"} {"level":"info","ts":"2026-08-06T01:13:38.684214Z","caller":"traceutil/trace.go:171","msg":"trace[488749663] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8642; }","duration":"833.96561ms","start":"2026-08-06T01:13:37.850242Z","end":"2026-08-06T01:13:38.684208Z","steps":["trace[488749663] 'agreement among raft nodes before linearized reading' (duration: 833.902338ms)"],"step_count":1} {"level":"info","ts":"2026-08-06T01:13:38.684231Z","caller":"traceutil/trace.go:171","msg":"trace[859764938] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:8642; }","duration":"964.280826ms","start":"2026-08-06T01:13:37.719944Z","end":"2026-08-06T01:13:38.684225Z","steps":["trace[859764938] 'agreement among raft nodes before linearized reading' (duration: 964.249836ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:38.684251Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:37.719903Z","time spent":"964.344298ms","remote":"127.0.0.1:56940","response type":"/etcdserverpb.KV/Range","request count":0,"request size":102,"response count":1,"response size":31,"request content":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/\" range_end:\"/registry/pxc.percona.com/perconaxtradbclusters0\" count_only:true "} {"level":"warn","ts":"2026-08-06T01:13:38.684283Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"835.542321ms","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-08-06T01:13:38.684307Z","caller":"traceutil/trace.go:171","msg":"trace[947632975] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8642; }","duration":"835.600572ms","start":"2026-08-06T01:13:37.848701Z","end":"2026-08-06T01:13:38.684302Z","steps":["trace[947632975] 'agreement among raft nodes before linearized reading' (duration: 835.544151ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:38.684328Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:37.848668Z","time spent":"835.656164ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":517,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-06T01:13:38.684353Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"831.219589ms","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-08-06T01:13:38.684373Z","caller":"traceutil/trace.go:171","msg":"trace[1195088100] range","detail":"{range_begin:/registry/leases/openstack/rabbitmq-cluster-operator-leader-election; range_end:; response_count:1; response_revision:8642; }","duration":"831.258619ms","start":"2026-08-06T01:13:37.85311Z","end":"2026-08-06T01:13:38.684369Z","steps":["trace[1195088100] 'agreement among raft nodes before linearized reading' (duration: 831.207828ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:38.684387Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:37.853101Z","time spent":"831.282579ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":70,"response count":1,"response size":581,"request content":"key:\"/registry/leases/openstack/rabbitmq-cluster-operator-leader-election\" "} {"level":"warn","ts":"2026-08-06T01:13:38.684418Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"837.237056ms","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-08-06T01:13:38.684235Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:37.850233Z","time spent":"833.997741ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":458,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-06T01:13:38.684442Z","caller":"traceutil/trace.go:171","msg":"trace[1069463935] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8642; }","duration":"837.286037ms","start":"2026-08-06T01:13:37.84715Z","end":"2026-08-06T01:13:38.684436Z","steps":["trace[1069463935] 'agreement among raft nodes before linearized reading' (duration: 837.235785ms)"],"step_count":1} {"level":"warn","ts":"2026-08-06T01:13:38.684463Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-06T01:13:37.847133Z","time spent":"837.325958ms","remote":"127.0.0.1:54540","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":504,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-08-06T01:14:20.001422Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7342} {"level":"info","ts":"2026-08-06T01:14:20.058265Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7342,"took":"55.690669ms","hash":2762988141,"current-db-size-bytes":52617216,"current-db-size":"53 MB","current-db-size-in-use-bytes":14950400,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-08-06T01:14:20.058318Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2762988141,"revision":7342,"compact-revision":5402}