{"level":"warn","ts":"2026-07-07T07:24:00.361874Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T07:24:00.361959Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.19.213.114: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.114:2380","--initial-cluster=instance=https://199.19.213.114:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.19.213.114:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.19.213.114: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-07-07T07:24:00.362028Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-07-07T07:24:00.362035Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.19.213.114:2380"]} {"level":"info","ts":"2026-07-07T07:24:00.362055Z","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-07-07T07:24:00.362715Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.114:2379"]} {"level":"info","ts":"2026-07-07T07:24:00.362786Z","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.114:2380"],"listen-peer-urls":["https://199.19.213.114:2380"],"advertise-client-urls":["https://199.19.213.114:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.114:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.19.213.114: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-07-07T07:24:00.367386Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.377821ms"} {"level":"info","ts":"2026-07-07T07:24:00.381736Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"e1d03a09d7253a9d","cluster-id":"bb9e539fe2ac80b2"} {"level":"info","ts":"2026-07-07T07:24:00.381872Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d switched to configuration voters=()"} {"level":"info","ts":"2026-07-07T07:24:00.381901Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d became follower at term 0"} {"level":"info","ts":"2026-07-07T07:24:00.381931Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft e1d03a09d7253a9d [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-07T07:24:00.381951Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d became follower at term 1"} {"level":"info","ts":"2026-07-07T07:24:00.38199Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d switched to configuration voters=(16271569267628259997)"} {"level":"warn","ts":"2026-07-07T07:24:00.403225Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-07T07:24:00.404857Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-07T07:24:00.405929Z","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-07-07T07:24:00.408827Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"e1d03a09d7253a9d","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-07T07:24:00.409057Z","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-07-07T07:24:00.409106Z","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-07-07T07:24:00.409124Z","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-07-07T07:24:00.409283Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"e1d03a09d7253a9d","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-07T07:24:00.41067Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d switched to configuration voters=(16271569267628259997)"} {"level":"info","ts":"2026-07-07T07:24:00.41079Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"bb9e539fe2ac80b2","local-member-id":"e1d03a09d7253a9d","added-peer-id":"e1d03a09d7253a9d","added-peer-peer-urls":["https://199.19.213.114:2380"]} {"level":"info","ts":"2026-07-07T07:24:00.413511Z","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-07-07T07:24:00.413564Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.19.213.114:2380"} {"level":"info","ts":"2026-07-07T07:24:00.41367Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.19.213.114:2380"} {"level":"info","ts":"2026-07-07T07:24:00.413766Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"e1d03a09d7253a9d","initial-advertise-peer-urls":["https://199.19.213.114:2380"],"listen-peer-urls":["https://199.19.213.114:2380"],"advertise-client-urls":["https://199.19.213.114:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.19.213.114:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-07T07:24:00.413796Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-07T07:24:01.282648Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d is starting a new election at term 1"} {"level":"info","ts":"2026-07-07T07:24:01.282698Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d became pre-candidate at term 1"} {"level":"info","ts":"2026-07-07T07:24:01.282718Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d received MsgPreVoteResp from e1d03a09d7253a9d at term 1"} {"level":"info","ts":"2026-07-07T07:24:01.282728Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d became candidate at term 2"} {"level":"info","ts":"2026-07-07T07:24:01.282734Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d received MsgVoteResp from e1d03a09d7253a9d at term 2"} {"level":"info","ts":"2026-07-07T07:24:01.282742Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"e1d03a09d7253a9d became leader at term 2"} {"level":"info","ts":"2026-07-07T07:24:01.282753Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: e1d03a09d7253a9d elected leader e1d03a09d7253a9d at term 2"} {"level":"info","ts":"2026-07-07T07:24:01.283499Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"e1d03a09d7253a9d","local-member-attributes":"{Name:instance ClientURLs:[https://199.19.213.114:2379]}","request-path":"/0/members/e1d03a09d7253a9d/attributes","cluster-id":"bb9e539fe2ac80b2","publish-timeout":"7s"} {"level":"info","ts":"2026-07-07T07:24:01.28355Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T07:24:01.283631Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-07T07:24:01.283945Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T07:24:01.285524Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.19.213.114:2379"} {"level":"info","ts":"2026-07-07T07:24:01.285778Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"bb9e539fe2ac80b2","local-member-id":"e1d03a09d7253a9d","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T07:24:01.285829Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T07:24:01.285846Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-07T07:24:01.286141Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-07T07:24:01.286162Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-07T07:24:01.286727Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-07T07:24:02.995365Z","caller":"traceutil/trace.go:171","msg":"trace[1699534896] transaction","detail":"{read_only:false; response_revision:86; number_of_response:1; }","duration":"116.945352ms","start":"2026-07-07T07:24:02.878397Z","end":"2026-07-07T07:24:02.995342Z","steps":["trace[1699534896] 'process raft request' (duration: 116.80533ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:24:03.462836Z","caller":"traceutil/trace.go:171","msg":"trace[1231765973] linearizableReadLoop","detail":"{readStateIndex:92; appliedIndex:91; }","duration":"463.756055ms","start":"2026-07-07T07:24:02.99904Z","end":"2026-07-07T07:24:03.462796Z","steps":["trace[1231765973] 'read index received' (duration: 431.639591ms)","trace[1231765973] 'applied index is now lower than readState.Index' (duration: 32.115464ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:24:03.4629Z","caller":"traceutil/trace.go:171","msg":"trace[518665816] transaction","detail":"{read_only:false; response_revision:87; number_of_response:1; }","duration":"464.422032ms","start":"2026-07-07T07:24:02.998424Z","end":"2026-07-07T07:24:03.462846Z","steps":["trace[518665816] 'process raft request' (duration: 432.297267ms)","trace[518665816] 'compare' (duration: 31.95571ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:24:03.463084Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"463.986385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:3491"} {"level":"info","ts":"2026-07-07T07:24:03.463187Z","caller":"traceutil/trace.go:171","msg":"trace[283302754] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:87; }","duration":"464.213324ms","start":"2026-07-07T07:24:02.998953Z","end":"2026-07-07T07:24:03.463166Z","steps":["trace[283302754] 'agreement among raft nodes before linearized reading' (duration: 463.951642ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:24:03.463238Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:24:02.998934Z","time spent":"464.291441ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":3515,"request content":"key:\"/registry/minions/instance\" "} {"level":"warn","ts":"2026-07-07T07:24:03.463528Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:24:02.998386Z","time spent":"464.590497ms","remote":"127.0.0.1:42130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":642,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:24:03.858939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"220.577893ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-07-07T07:24:03.85922Z","caller":"traceutil/trace.go:171","msg":"trace[2030498587] linearizableReadLoop","detail":"{readStateIndex:94; appliedIndex:92; }","duration":"266.04716ms","start":"2026-07-07T07:24:03.593156Z","end":"2026-07-07T07:24:03.859203Z","steps":["trace[2030498587] 'read index received' (duration: 44.943262ms)","trace[2030498587] 'applied index is now lower than readState.Index' (duration: 221.102878ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:24:03.859246Z","caller":"traceutil/trace.go:171","msg":"trace[1683821801] transaction","detail":"{read_only:false; response_revision:88; number_of_response:1; }","duration":"391.667311ms","start":"2026-07-07T07:24:03.46754Z","end":"2026-07-07T07:24:03.859207Z","steps":["trace[1683821801] 'process raft request' (duration: 170.597876ms)","trace[1683821801] 'compare' (duration: 220.439191ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:24:03.859318Z","caller":"traceutil/trace.go:171","msg":"trace[89834497] transaction","detail":"{read_only:false; response_revision:89; number_of_response:1; }","duration":"390.253388ms","start":"2026-07-07T07:24:03.468997Z","end":"2026-07-07T07:24:03.859251Z","steps":["trace[89834497] 'process raft request' (duration: 390.080103ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:24:03.859419Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"266.258048ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/priorityclasses/system-node-critical\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T07:24:03.859463Z","caller":"traceutil/trace.go:171","msg":"trace[960306337] range","detail":"{range_begin:/registry/priorityclasses/system-node-critical; range_end:; response_count:0; response_revision:89; }","duration":"266.344506ms","start":"2026-07-07T07:24:03.593107Z","end":"2026-07-07T07:24:03.859451Z","steps":["trace[960306337] 'agreement among raft nodes before linearized reading' (duration: 266.211785ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:24:03.859551Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:24:03.468981Z","time spent":"390.455616ms","remote":"127.0.0.1:42130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":638,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:24:03.859549Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"265.196567ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/clusterroles/\" range_end:\"/registry/clusterroles0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T07:24:03.859753Z","caller":"traceutil/trace.go:171","msg":"trace[1906541485] range","detail":"{range_begin:/registry/clusterroles/; range_end:/registry/clusterroles0; response_count:0; response_revision:89; }","duration":"265.451369ms","start":"2026-07-07T07:24:03.594286Z","end":"2026-07-07T07:24:03.859737Z","steps":["trace[1906541485] 'agreement among raft nodes before linearized reading' (duration: 265.185286ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:24:03.859705Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"256.343643ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-07T07:24:03.859895Z","caller":"traceutil/trace.go:171","msg":"trace[975409885] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:89; }","duration":"256.581633ms","start":"2026-07-07T07:24:03.603282Z","end":"2026-07-07T07:24:03.859864Z","steps":["trace[975409885] 'agreement among raft nodes before linearized reading' (duration: 256.252784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:24:03.860474Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:24:03.467516Z","time spent":"391.88755ms","remote":"127.0.0.1:42422","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":651,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T07:24:37.259105Z","caller":"traceutil/trace.go:171","msg":"trace[757420825] transaction","detail":"{read_only:false; response_revision:499; number_of_response:1; }","duration":"111.479554ms","start":"2026-07-07T07:24:37.147601Z","end":"2026-07-07T07:24:37.259081Z","steps":["trace[757420825] 'process raft request' (duration: 55.497449ms)","trace[757420825] 'compare' (duration: 55.71207ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:24:37.267086Z","caller":"traceutil/trace.go:171","msg":"trace[1472993353] transaction","detail":"{read_only:false; response_revision:500; number_of_response:1; }","duration":"119.351054ms","start":"2026-07-07T07:24:37.147713Z","end":"2026-07-07T07:24:37.267064Z","steps":["trace[1472993353] 'process raft request' (duration: 119.00592ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:24:39.654525Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"305.496637ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:24:39.654714Z","caller":"traceutil/trace.go:171","msg":"trace[803161152] transaction","detail":"{read_only:false; response_revision:569; number_of_response:1; }","duration":"347.827348ms","start":"2026-07-07T07:24:39.306874Z","end":"2026-07-07T07:24:39.654702Z","steps":["trace[803161152] 'process raft request' (duration: 347.787374ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:24:39.654756Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:24:39.306849Z","time spent":"347.887823ms","remote":"127.0.0.1:42106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":23066,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:24:39.654755Z","caller":"traceutil/trace.go:171","msg":"trace[1487253484] transaction","detail":"{read_only:false; response_revision:568; number_of_response:1; }","duration":"353.379866ms","start":"2026-07-07T07:24:39.301338Z","end":"2026-07-07T07:24:39.654718Z","steps":["trace[1487253484] 'process raft request' (duration: 353.272036ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:24:39.654905Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:24:39.301306Z","time spent":"353.516629ms","remote":"127.0.0.1:42310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:24:39.654938Z","caller":"traceutil/trace.go:171","msg":"trace[841718629] transaction","detail":"{read_only:false; response_revision:567; number_of_response:1; }","duration":"358.968278ms","start":"2026-07-07T07:24:39.29593Z","end":"2026-07-07T07:24:39.654898Z","steps":["trace[841718629] 'process raft request' (duration: 53.011406ms)","trace[841718629] 'compare' (duration: 305.367495ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:24:39.655655Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:24:39.295896Z","time spent":"359.207631ms","remote":"127.0.0.1:42106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":29452,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:24:39.656029Z","caller":"traceutil/trace.go:171","msg":"trace[652665066] transaction","detail":"{read_only:false; response_revision:570; number_of_response:1; }","duration":"300.006825ms","start":"2026-07-07T07:24:39.355992Z","end":"2026-07-07T07:24:39.655999Z","steps":["trace[652665066] 'process raft request' (duration: 299.670913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:24:39.656155Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:24:39.355965Z","time spent":"300.134038ms","remote":"127.0.0.1:42106","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17062,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-07T07:24:39.656351Z","caller":"traceutil/trace.go:171","msg":"trace[1614873755] linearizableReadLoop","detail":"{readStateIndex:590; appliedIndex:585; }","duration":"221.601419ms","start":"2026-07-07T07:24:39.434718Z","end":"2026-07-07T07:24:39.656319Z","steps":["trace[1614873755] 'read index received' (duration: 180.9403ms)","trace[1614873755] 'applied index is now lower than readState.Index' (duration: 40.660169ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:24:39.656633Z","caller":"traceutil/trace.go:171","msg":"trace[127608596] transaction","detail":"{read_only:false; response_revision:571; number_of_response:1; }","duration":"293.119837ms","start":"2026-07-07T07:24:39.363456Z","end":"2026-07-07T07:24:39.656576Z","steps":["trace[127608596] 'process raft request' (duration: 292.5287ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:24:39.656926Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"222.179845ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:24:39.657032Z","caller":"traceutil/trace.go:171","msg":"trace[324853116] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:571; }","duration":"222.333661ms","start":"2026-07-07T07:24:39.434687Z","end":"2026-07-07T07:24:39.65702Z","steps":["trace[324853116] 'agreement among raft nodes before linearized reading' (duration: 222.181606ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:24:42.770322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"296.41517ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:24:42.770433Z","caller":"traceutil/trace.go:171","msg":"trace[145462706] linearizableReadLoop","detail":"{readStateIndex:636; appliedIndex:635; }","duration":"337.881785ms","start":"2026-07-07T07:24:42.432532Z","end":"2026-07-07T07:24:42.770414Z","steps":["trace[145462706] 'read index received' (duration: 41.183418ms)","trace[145462706] 'applied index is now lower than readState.Index' (duration: 296.697317ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:24:42.770564Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"338.03121ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:24:42.770623Z","caller":"traceutil/trace.go:171","msg":"trace[1815080580] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:616; }","duration":"338.121349ms","start":"2026-07-07T07:24:42.43247Z","end":"2026-07-07T07:24:42.770591Z","steps":["trace[1815080580] 'agreement among raft nodes before linearized reading' (duration: 338.024829ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:24:42.770624Z","caller":"traceutil/trace.go:171","msg":"trace[213094872] transaction","detail":"{read_only:false; response_revision:616; number_of_response:1; }","duration":"352.320961ms","start":"2026-07-07T07:24:42.418269Z","end":"2026-07-07T07:24:42.77059Z","steps":["trace[213094872] 'process raft request' (duration: 55.57873ms)","trace[213094872] 'compare' (duration: 296.228542ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:24:42.770675Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:24:42.432449Z","time spent":"338.208527ms","remote":"127.0.0.1:42040","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-07-07T07:24:42.770771Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:24:42.41824Z","time spent":"352.472855ms","remote":"127.0.0.1:42256","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":182,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T07:24:46.627074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.512171ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:24:46.627187Z","caller":"traceutil/trace.go:171","msg":"trace[2065233730] transaction","detail":"{read_only:false; response_revision:645; number_of_response:1; }","duration":"183.097149ms","start":"2026-07-07T07:24:46.444075Z","end":"2026-07-07T07:24:46.627172Z","steps":["trace[2065233730] 'process raft request' (duration: 70.436043ms)","trace[2065233730] 'compare' (duration: 112.391538ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:24:47.148317Z","caller":"traceutil/trace.go:171","msg":"trace[1523833033] linearizableReadLoop","detail":"{readStateIndex:670; appliedIndex:666; }","duration":"507.336037ms","start":"2026-07-07T07:24:46.640949Z","end":"2026-07-07T07:24:47.148285Z","steps":["trace[1523833033] 'read index received' (duration: 25.512638ms)","trace[1523833033] 'applied index is now lower than readState.Index' (duration: 481.822219ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:24:47.148353Z","caller":"traceutil/trace.go:171","msg":"trace[832170180] transaction","detail":"{read_only:false; response_revision:648; number_of_response:1; }","duration":"515.043751ms","start":"2026-07-07T07:24:46.633274Z","end":"2026-07-07T07:24:47.148318Z","steps":["trace[832170180] 'process raft request' (duration: 514.916207ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:24:47.14848Z","caller":"traceutil/trace.go:171","msg":"trace[862039588] transaction","detail":"{read_only:false; response_revision:647; number_of_response:1; }","duration":"517.125845ms","start":"2026-07-07T07:24:46.631336Z","end":"2026-07-07T07:24:47.148462Z","steps":["trace[862039588] 'process raft request' (duration: 514.019219ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:24:47.148519Z","caller":"traceutil/trace.go:171","msg":"trace[799839420] transaction","detail":"{read_only:false; response_revision:649; number_of_response:1; }","duration":"514.971464ms","start":"2026-07-07T07:24:46.633533Z","end":"2026-07-07T07:24:47.148505Z","steps":["trace[799839420] 'process raft request' (duration: 514.711899ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:24:47.148569Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:24:46.633253Z","time spent":"515.181904ms","remote":"127.0.0.1:42292","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":2785,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:24:47.148586Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:24:46.631305Z","time spent":"517.221284ms","remote":"127.0.0.1:42130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":775,"response count":0,"response size":40,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-07-07T07:24:47.148601Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:24:46.633525Z","time spent":"515.021099ms","remote":"127.0.0.1:42250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":3209,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:24:47.148627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"507.671471ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/kube-system/cilium-8kbbb\" ","response":"range_response_count:1 size:16965"} {"level":"info","ts":"2026-07-07T07:24:47.148664Z","caller":"traceutil/trace.go:171","msg":"trace[513771761] range","detail":"{range_begin:/registry/pods/kube-system/cilium-8kbbb; range_end:; response_count:1; response_revision:649; }","duration":"507.740787ms","start":"2026-07-07T07:24:46.640912Z","end":"2026-07-07T07:24:47.148652Z","steps":["trace[513771761] 'agreement among raft nodes before linearized reading' (duration: 507.674831ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:24:47.148693Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:24:46.640887Z","time spent":"507.799213ms","remote":"127.0.0.1:42250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":41,"response count":1,"response size":16989,"request content":"key:\"/registry/pods/kube-system/cilium-8kbbb\" "} {"level":"info","ts":"2026-07-07T07:24:47.274732Z","caller":"traceutil/trace.go:171","msg":"trace[155572185] linearizableReadLoop","detail":"{readStateIndex:671; appliedIndex:670; }","duration":"126.332899ms","start":"2026-07-07T07:24:47.148378Z","end":"2026-07-07T07:24:47.274711Z","steps":["trace[155572185] 'read index received' (duration: 100.820469ms)","trace[155572185] 'applied index is now lower than readState.Index' (duration: 25.51164ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:24:47.27491Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"462.293284ms","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-07-07T07:24:47.275088Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"341.483372ms","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-07-07T07:24:47.275192Z","caller":"traceutil/trace.go:171","msg":"trace[706308596] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:650; }","duration":"341.615964ms","start":"2026-07-07T07:24:46.93355Z","end":"2026-07-07T07:24:47.275166Z","steps":["trace[706308596] 'agreement among raft nodes before linearized reading' (duration: 341.357919ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:24:47.275215Z","caller":"traceutil/trace.go:171","msg":"trace[1198005214] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:650; }","duration":"462.645078ms","start":"2026-07-07T07:24:46.812557Z","end":"2026-07-07T07:24:47.275202Z","steps":["trace[1198005214] 'agreement among raft nodes before linearized reading' (duration: 462.241689ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:24:47.275232Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:24:46.933534Z","time spent":"341.690071ms","remote":"127.0.0.1:42310","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-07-07T07:24:47.274949Z","caller":"traceutil/trace.go:171","msg":"trace[1029195474] transaction","detail":"{read_only:false; response_revision:650; number_of_response:1; }","duration":"171.601764ms","start":"2026-07-07T07:24:47.103297Z","end":"2026-07-07T07:24:47.274899Z","steps":["trace[1029195474] 'process raft request' (duration: 145.886065ms)","trace[1029195474] 'compare' (duration: 25.436102ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:24:47.275262Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:24:46.812538Z","time spent":"462.714125ms","remote":"127.0.0.1:42310","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-07-07T07:24:47.276494Z","caller":"traceutil/trace.go:171","msg":"trace[168229524] transaction","detail":"{read_only:false; response_revision:651; number_of_response:1; }","duration":"115.986305ms","start":"2026-07-07T07:24:47.160496Z","end":"2026-07-07T07:24:47.276482Z","steps":["trace[168229524] 'process raft request' (duration: 115.854112ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:25:01.670707Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"182.590093ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:25:01.67091Z","caller":"traceutil/trace.go:171","msg":"trace[19843070] transaction","detail":"{read_only:false; response_revision:802; number_of_response:1; }","duration":"324.876126ms","start":"2026-07-07T07:25:01.346019Z","end":"2026-07-07T07:25:01.670895Z","steps":["trace[19843070] 'process raft request' (duration: 142.029828ms)","trace[19843070] 'compare' (duration: 182.251951ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:25:01.670988Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:25:01.346004Z","time spent":"324.956032ms","remote":"127.0.0.1:42310","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-07-07T07:25:01.671213Z","caller":"traceutil/trace.go:171","msg":"trace[1803645458] linearizableReadLoop","detail":"{readStateIndex:828; appliedIndex:826; }","duration":"236.187175ms","start":"2026-07-07T07:25:01.435017Z","end":"2026-07-07T07:25:01.671205Z","steps":["trace[1803645458] 'read index received' (duration: 53.047933ms)","trace[1803645458] 'applied index is now lower than readState.Index' (duration: 183.138592ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:25:01.671275Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"236.255911ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:25:01.671298Z","caller":"traceutil/trace.go:171","msg":"trace[400525313] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:803; }","duration":"236.318088ms","start":"2026-07-07T07:25:01.434974Z","end":"2026-07-07T07:25:01.671292Z","steps":["trace[400525313] 'agreement among raft nodes before linearized reading' (duration: 236.261471ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:25:01.671283Z","caller":"traceutil/trace.go:171","msg":"trace[2144952186] transaction","detail":"{read_only:false; response_revision:803; number_of_response:1; }","duration":"321.988496ms","start":"2026-07-07T07:25:01.349267Z","end":"2026-07-07T07:25:01.671256Z","steps":["trace[2144952186] 'process raft request' (duration: 321.529902ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:25:01.671451Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:25:01.349223Z","time spent":"322.132388ms","remote":"127.0.0.1:42310","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-07-07T07:25:29.915823Z","caller":"traceutil/trace.go:171","msg":"trace[698166755] linearizableReadLoop","detail":"{readStateIndex:1230; appliedIndex:1228; }","duration":"102.980194ms","start":"2026-07-07T07:25:29.812823Z","end":"2026-07-07T07:25:29.915803Z","steps":["trace[698166755] 'read index received' (duration: 48.915239ms)","trace[698166755] 'applied index is now lower than readState.Index' (duration: 54.064155ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:25:29.9159Z","caller":"traceutil/trace.go:171","msg":"trace[1593759263] transaction","detail":"{read_only:false; response_revision:1199; number_of_response:1; }","duration":"111.50928ms","start":"2026-07-07T07:25:29.804354Z","end":"2026-07-07T07:25:29.915864Z","steps":["trace[1593759263] 'process raft request' (duration: 111.342161ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:25:29.915949Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"103.103673ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:25:29.91603Z","caller":"traceutil/trace.go:171","msg":"trace[1686222225] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:1199; }","duration":"103.210473ms","start":"2026-07-07T07:25:29.812801Z","end":"2026-07-07T07:25:29.916011Z","steps":["trace[1686222225] 'agreement among raft nodes before linearized reading' (duration: 103.085184ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:25:52.147707Z","caller":"traceutil/trace.go:171","msg":"trace[156550367] transaction","detail":"{read_only:false; response_revision:1454; number_of_response:1; }","duration":"101.481029ms","start":"2026-07-07T07:25:52.04616Z","end":"2026-07-07T07:25:52.147641Z","steps":["trace[156550367] 'process raft request' (duration: 101.161252ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:25:54.162955Z","caller":"traceutil/trace.go:171","msg":"trace[638709012] transaction","detail":"{read_only:false; response_revision:1467; number_of_response:1; }","duration":"146.958261ms","start":"2026-07-07T07:25:54.015967Z","end":"2026-07-07T07:25:54.162925Z","steps":["trace[638709012] 'process raft request' (duration: 129.184813ms)","trace[638709012] 'compare' (duration: 17.259217ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:26:19.153851Z","caller":"traceutil/trace.go:171","msg":"trace[122520603] transaction","detail":"{read_only:false; response_revision:1890; number_of_response:1; }","duration":"177.189519ms","start":"2026-07-07T07:26:18.97664Z","end":"2026-07-07T07:26:19.15383Z","steps":["trace[122520603] 'process raft request' (duration: 176.977951ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:26:19.261257Z","caller":"traceutil/trace.go:171","msg":"trace[1589260042] transaction","detail":"{read_only:false; response_revision:1891; number_of_response:1; }","duration":"145.560654ms","start":"2026-07-07T07:26:19.115671Z","end":"2026-07-07T07:26:19.261232Z","steps":["trace[1589260042] 'process raft request' (duration: 115.592702ms)","trace[1589260042] 'compare' (duration: 29.846727ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:26:19.283053Z","caller":"traceutil/trace.go:171","msg":"trace[1552885387] transaction","detail":"{read_only:false; response_revision:1892; number_of_response:1; }","duration":"123.287624ms","start":"2026-07-07T07:26:19.159743Z","end":"2026-07-07T07:26:19.28303Z","steps":["trace[1552885387] 'process raft request' (duration: 123.150458ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:26:19.706491Z","caller":"traceutil/trace.go:171","msg":"trace[1137735697] linearizableReadLoop","detail":"{readStateIndex:1961; appliedIndex:1960; }","duration":"202.700913ms","start":"2026-07-07T07:26:19.503771Z","end":"2026-07-07T07:26:19.706472Z","steps":["trace[1137735697] 'read index received' (duration: 202.575207ms)","trace[1137735697] 'applied index is now lower than readState.Index' (duration: 124.716µs)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:26:19.706676Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"202.895302ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-07T07:26:19.706713Z","caller":"traceutil/trace.go:171","msg":"trace[1284418489] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1893; }","duration":"202.978005ms","start":"2026-07-07T07:26:19.503723Z","end":"2026-07-07T07:26:19.706701Z","steps":["trace[1284418489] 'agreement among raft nodes before linearized reading' (duration: 202.832389ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:26:19.706668Z","caller":"traceutil/trace.go:171","msg":"trace[2094666473] transaction","detail":"{read_only:false; response_revision:1893; number_of_response:1; }","duration":"288.104244ms","start":"2026-07-07T07:26:19.41853Z","end":"2026-07-07T07:26:19.706634Z","steps":["trace[2094666473] 'process raft request' (duration: 287.814451ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:26:30.91045Z","caller":"traceutil/trace.go:171","msg":"trace[702493242] linearizableReadLoop","detail":"{readStateIndex:2063; appliedIndex:2062; }","duration":"130.846617ms","start":"2026-07-07T07:26:30.779575Z","end":"2026-07-07T07:26:30.910421Z","steps":["trace[702493242] 'read index received' (duration: 130.531892ms)","trace[702493242] 'applied index is now lower than readState.Index' (duration: 313.605µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:26:30.91052Z","caller":"traceutil/trace.go:171","msg":"trace[785370023] transaction","detail":"{read_only:false; response_revision:1991; number_of_response:1; }","duration":"156.44441ms","start":"2026-07-07T07:26:30.754048Z","end":"2026-07-07T07:26:30.910492Z","steps":["trace[785370023] 'process raft request' (duration: 156.159257ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:26:30.910744Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"131.168562ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-07T07:26:30.910805Z","caller":"traceutil/trace.go:171","msg":"trace[716729616] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1991; }","duration":"131.281288ms","start":"2026-07-07T07:26:30.779508Z","end":"2026-07-07T07:26:30.91079Z","steps":["trace[716729616] 'agreement among raft nodes before linearized reading' (duration: 131.103859ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:26:34.554289Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"120.287046ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:26:34.554389Z","caller":"traceutil/trace.go:171","msg":"trace[1550734177] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2011; }","duration":"120.459924ms","start":"2026-07-07T07:26:34.43391Z","end":"2026-07-07T07:26:34.554369Z","steps":["trace[1550734177] 'range keys from in-memory index tree' (duration: 120.227784ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:26:34.554673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"144.958336ms","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-07-07T07:26:34.554718Z","caller":"traceutil/trace.go:171","msg":"trace[1276006243] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:2011; }","duration":"145.046601ms","start":"2026-07-07T07:26:34.409659Z","end":"2026-07-07T07:26:34.554706Z","steps":["trace[1276006243] 'range keys from in-memory index tree' (duration: 144.81376ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:26:34.554673Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"198.96543ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-07T07:26:34.554826Z","caller":"traceutil/trace.go:171","msg":"trace[1210449496] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:2011; }","duration":"199.14482ms","start":"2026-07-07T07:26:34.355669Z","end":"2026-07-07T07:26:34.554814Z","steps":["trace[1210449496] 'range keys from in-memory index tree' (duration: 198.865856ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:26:58.317526Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"228.85769ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb\" ","response":"range_response_count:1 size:5412"} {"level":"info","ts":"2026-07-07T07:26:58.317625Z","caller":"traceutil/trace.go:171","msg":"trace[1569751284] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2153; }","duration":"228.999779ms","start":"2026-07-07T07:26:58.088599Z","end":"2026-07-07T07:26:58.317599Z","steps":["trace[1569751284] 'range keys from in-memory index tree' (duration: 228.127977ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:26:58.70891Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"207.989424ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:26:58.709426Z","caller":"traceutil/trace.go:171","msg":"trace[792881540] transaction","detail":"{read_only:false; response_revision:2155; number_of_response:1; }","duration":"321.95923ms","start":"2026-07-07T07:26:58.387437Z","end":"2026-07-07T07:26:58.709396Z","steps":["trace[792881540] 'process raft request' (duration: 113.396382ms)","trace[792881540] 'compare' (duration: 207.712567ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:26:58.709594Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:26:58.387417Z","time spent":"322.077147ms","remote":"127.0.0.1:42310","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-07-07T07:27:40.595459Z","caller":"traceutil/trace.go:171","msg":"trace[252643434] transaction","detail":"{read_only:false; response_revision:2433; number_of_response:1; }","duration":"106.09215ms","start":"2026-07-07T07:27:40.489341Z","end":"2026-07-07T07:27:40.595433Z","steps":["trace[252643434] 'process raft request' (duration: 105.923658ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:49.357962Z","caller":"traceutil/trace.go:171","msg":"trace[1015108799] transaction","detail":"{read_only:false; response_revision:2535; number_of_response:1; }","duration":"137.222557ms","start":"2026-07-07T07:27:49.220718Z","end":"2026-07-07T07:27:49.357941Z","steps":["trace[1015108799] 'process raft request' (duration: 137.081816ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:27:50.068403Z","caller":"traceutil/trace.go:171","msg":"trace[1192743964] linearizableReadLoop","detail":"{readStateIndex:2628; appliedIndex:2627; }","duration":"109.956917ms","start":"2026-07-07T07:27:49.958409Z","end":"2026-07-07T07:27:50.068366Z","steps":["trace[1192743964] 'read index received' (duration: 109.164429ms)","trace[1192743964] 'applied index is now lower than readState.Index' (duration: 791.468µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:27:50.068559Z","caller":"traceutil/trace.go:171","msg":"trace[543236011] transaction","detail":"{read_only:false; response_revision:2538; number_of_response:1; }","duration":"266.400183ms","start":"2026-07-07T07:27:49.802133Z","end":"2026-07-07T07:27:50.068534Z","steps":["trace[543236011] 'process raft request' (duration: 265.439222ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:27:50.068591Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.145161ms","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-07-07T07:27:50.06863Z","caller":"traceutil/trace.go:171","msg":"trace[330759947] range","detail":"{range_begin:/registry/volumeattachments/; range_end:/registry/volumeattachments0; response_count:0; response_revision:2538; }","duration":"110.303712ms","start":"2026-07-07T07:27:49.958316Z","end":"2026-07-07T07:27:50.06862Z","steps":["trace[330759947] 'agreement among raft nodes before linearized reading' (duration: 110.168722ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:21.970329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.611503ms","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-07-07T07:28:21.970656Z","caller":"traceutil/trace.go:171","msg":"trace[1416894256] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:2714; }","duration":"125.993301ms","start":"2026-07-07T07:28:21.844646Z","end":"2026-07-07T07:28:21.970639Z","steps":["trace[1416894256] 'range keys from in-memory index tree' (duration: 125.434609ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:28:27.589443Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"152.886ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:28:27.589545Z","caller":"traceutil/trace.go:171","msg":"trace[165786621] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2743; }","duration":"153.047433ms","start":"2026-07-07T07:28:27.436478Z","end":"2026-07-07T07:28:27.589526Z","steps":["trace[165786621] 'range keys from in-memory index tree' (duration: 152.809225ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:28:27.602015Z","caller":"traceutil/trace.go:171","msg":"trace[562461317] transaction","detail":"{read_only:false; response_revision:2744; number_of_response:1; }","duration":"112.738092ms","start":"2026-07-07T07:28:27.489253Z","end":"2026-07-07T07:28:27.601991Z","steps":["trace[562461317] 'process raft request' (duration: 112.442069ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:10.554939Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"350.604038ms","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-07-07T07:32:10.555038Z","caller":"traceutil/trace.go:171","msg":"trace[884023757] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:4608; }","duration":"350.743954ms","start":"2026-07-07T07:32:10.204271Z","end":"2026-07-07T07:32:10.555015Z","steps":["trace[884023757] 'range keys from in-memory index tree' (duration: 350.4127ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:10.555088Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:10.204254Z","time spent":"350.821497ms","remote":"127.0.0.1:42310","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-07-07T07:32:10.555087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"308.171135ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-07-07T07:32:10.555192Z","caller":"traceutil/trace.go:171","msg":"trace[133731264] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:4608; }","duration":"308.30007ms","start":"2026-07-07T07:32:10.24687Z","end":"2026-07-07T07:32:10.55517Z","steps":["trace[133731264] 'range keys from in-memory index tree' (duration: 308.05223ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:10.555247Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:10.246852Z","time spent":"308.376914ms","remote":"127.0.0.1:42310","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":"warn","ts":"2026-07-07T07:32:10.555305Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"276.220737ms","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-07-07T07:32:10.55538Z","caller":"traceutil/trace.go:171","msg":"trace[369090610] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:4608; }","duration":"276.322591ms","start":"2026-07-07T07:32:10.279038Z","end":"2026-07-07T07:32:10.555361Z","steps":["trace[369090610] 'range keys from in-memory index tree' (duration: 276.039109ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:10.555399Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"469.580153ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/minions/instance\" ","response":"range_response_count:1 size:9515"} {"level":"info","ts":"2026-07-07T07:32:10.555427Z","caller":"traceutil/trace.go:171","msg":"trace[891265151] range","detail":"{range_begin:/registry/minions/instance; range_end:; response_count:1; response_revision:4608; }","duration":"469.659776ms","start":"2026-07-07T07:32:10.085757Z","end":"2026-07-07T07:32:10.555417Z","steps":["trace[891265151] 'range keys from in-memory index tree' (duration: 469.444206ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:10.555438Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"373.579594ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:478"} {"level":"warn","ts":"2026-07-07T07:32:10.555454Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:10.085727Z","time spent":"469.720888ms","remote":"127.0.0.1:42240","response type":"/etcdserverpb.KV/Range","request count":0,"request size":28,"response count":1,"response size":9539,"request content":"key:\"/registry/minions/instance\" "} {"level":"info","ts":"2026-07-07T07:32:10.555494Z","caller":"traceutil/trace.go:171","msg":"trace[814962600] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:4608; }","duration":"373.635326ms","start":"2026-07-07T07:32:10.181842Z","end":"2026-07-07T07:32:10.555478Z","steps":["trace[814962600] 'range keys from in-memory index tree' (duration: 373.443477ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:10.555466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"365.729127ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-07T07:32:10.555536Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:10.181794Z","time spent":"373.73051ms","remote":"127.0.0.1:42310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":45,"response count":1,"response size":502,"request content":"key:\"/registry/leases/kube-system/kube-scheduler\" "} {"level":"info","ts":"2026-07-07T07:32:10.555558Z","caller":"traceutil/trace.go:171","msg":"trace[1447294648] range","detail":"{range_begin:/registry/rabbitmq.com/users/; range_end:/registry/rabbitmq.com/users0; response_count:0; response_revision:4608; }","duration":"365.897464ms","start":"2026-07-07T07:32:10.18964Z","end":"2026-07-07T07:32:10.555537Z","steps":["trace[1447294648] 'count revisions from in-memory index tree' (duration: 365.623032ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:10.555608Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:10.189616Z","time spent":"365.981037ms","remote":"127.0.0.1:51198","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":0,"response size":29,"request content":"key:\"/registry/rabbitmq.com/users/\" range_end:\"/registry/rabbitmq.com/users0\" count_only:true "} {"level":"warn","ts":"2026-07-07T07:32:10.556274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.405868ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-07-07T07:32:10.556345Z","caller":"traceutil/trace.go:171","msg":"trace[630942907] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4608; }","duration":"355.521093ms","start":"2026-07-07T07:32:10.200806Z","end":"2026-07-07T07:32:10.556327Z","steps":["trace[630942907] 'range keys from in-memory index tree' (duration: 354.549073ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:10.556391Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:10.200754Z","time spent":"355.623317ms","remote":"127.0.0.1:42154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2206997,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"info","ts":"2026-07-07T07:32:10.656192Z","caller":"traceutil/trace.go:171","msg":"trace[2090114957] linearizableReadLoop","detail":"{readStateIndex:4783; appliedIndex:4782; }","duration":"223.518036ms","start":"2026-07-07T07:32:10.432639Z","end":"2026-07-07T07:32:10.656157Z","steps":["trace[2090114957] 'read index received' (duration: 223.317458ms)","trace[2090114957] 'applied index is now lower than readState.Index' (duration: 198.958µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:32:10.656346Z","caller":"traceutil/trace.go:171","msg":"trace[1596776292] transaction","detail":"{read_only:false; response_revision:4609; number_of_response:1; }","duration":"229.10848ms","start":"2026-07-07T07:32:10.427224Z","end":"2026-07-07T07:32:10.656332Z","steps":["trace[1596776292] 'process raft request' (duration: 228.62294ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:10.656611Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"223.937995ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-07T07:32:10.656761Z","caller":"traceutil/trace.go:171","msg":"trace[784404501] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:4609; }","duration":"224.135093ms","start":"2026-07-07T07:32:10.432598Z","end":"2026-07-07T07:32:10.656733Z","steps":["trace[784404501] 'agreement among raft nodes before linearized reading' (duration: 223.834661ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:10.711316Z","caller":"traceutil/trace.go:171","msg":"trace[921033665] transaction","detail":"{read_only:false; response_revision:4611; number_of_response:1; }","duration":"150.318496ms","start":"2026-07-07T07:32:10.56096Z","end":"2026-07-07T07:32:10.711278Z","steps":["trace[921033665] 'process raft request' (duration: 150.224022ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:10.711337Z","caller":"traceutil/trace.go:171","msg":"trace[551327183] transaction","detail":"{read_only:false; response_revision:4610; number_of_response:1; }","duration":"150.470922ms","start":"2026-07-07T07:32:10.560815Z","end":"2026-07-07T07:32:10.711286Z","steps":["trace[551327183] 'process raft request' (duration: 150.16723ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:10.711602Z","caller":"traceutil/trace.go:171","msg":"trace[1045096860] transaction","detail":"{read_only:false; response_revision:4613; number_of_response:1; }","duration":"150.095306ms","start":"2026-07-07T07:32:10.561477Z","end":"2026-07-07T07:32:10.711572Z","steps":["trace[1045096860] 'process raft request' (duration: 149.94104ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:10.7116Z","caller":"traceutil/trace.go:171","msg":"trace[1381366047] transaction","detail":"{read_only:false; response_revision:4614; number_of_response:1; }","duration":"115.449977ms","start":"2026-07-07T07:32:10.596116Z","end":"2026-07-07T07:32:10.711566Z","steps":["trace[1381366047] 'process raft request' (duration: 115.362973ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:10.711767Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.475763ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-07-07T07:32:10.711832Z","caller":"traceutil/trace.go:171","msg":"trace[653171960] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4614; }","duration":"125.589097ms","start":"2026-07-07T07:32:10.586226Z","end":"2026-07-07T07:32:10.711816Z","steps":["trace[653171960] 'agreement among raft nodes before linearized reading' (duration: 125.302725ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:10.712018Z","caller":"traceutil/trace.go:171","msg":"trace[1669420847] transaction","detail":"{read_only:false; response_revision:4612; number_of_response:1; }","duration":"150.572067ms","start":"2026-07-07T07:32:10.560953Z","end":"2026-07-07T07:32:10.711525Z","steps":["trace[1669420847] 'process raft request' (duration: 150.277765ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:17.499521Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"234.416503ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:32:17.499779Z","caller":"traceutil/trace.go:171","msg":"trace[818213157] transaction","detail":"{read_only:false; response_revision:4721; number_of_response:1; }","duration":"430.010978ms","start":"2026-07-07T07:32:17.069728Z","end":"2026-07-07T07:32:17.499739Z","steps":["trace[818213157] 'process raft request' (duration: 195.288753ms)","trace[818213157] 'compare' (duration: 233.490833ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:32:17.499941Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:17.069703Z","time spent":"430.159024ms","remote":"127.0.0.1:42130","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":725,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-07T07:32:17.500041Z","caller":"traceutil/trace.go:171","msg":"trace[300441747] linearizableReadLoop","detail":"{readStateIndex:4897; appliedIndex:4896; }","duration":"419.698204ms","start":"2026-07-07T07:32:17.080325Z","end":"2026-07-07T07:32:17.500023Z","steps":["trace[300441747] 'read index received' (duration: 184.77407ms)","trace[300441747] 'applied index is now lower than readState.Index' (duration: 234.922874ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:32:17.500296Z","caller":"traceutil/trace.go:171","msg":"trace[1605268260] transaction","detail":"{read_only:false; response_revision:4722; number_of_response:1; }","duration":"382.044567ms","start":"2026-07-07T07:32:17.118241Z","end":"2026-07-07T07:32:17.500285Z","steps":["trace[1605268260] 'process raft request' (duration: 381.404749ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:17.500341Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:17.118212Z","time spent":"382.105601ms","remote":"127.0.0.1:42310","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:32:17.500409Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"417.808985ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" ","response":"range_response_count:1 size:9639"} {"level":"info","ts":"2026-07-07T07:32:17.500471Z","caller":"traceutil/trace.go:171","msg":"trace[914409628] range","detail":"{range_begin:/registry/statefulsets/openstack/rabbitmq-keystone-server; range_end:; response_count:1; response_revision:4724; }","duration":"417.894499ms","start":"2026-07-07T07:32:17.082561Z","end":"2026-07-07T07:32:17.500455Z","steps":["trace[914409628] 'agreement among raft nodes before linearized reading' (duration: 417.717421ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:17.500511Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:17.082547Z","time spent":"417.952822ms","remote":"127.0.0.1:42482","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":9663,"request content":"key:\"/registry/statefulsets/openstack/rabbitmq-keystone-server\" "} {"level":"info","ts":"2026-07-07T07:32:17.500433Z","caller":"traceutil/trace.go:171","msg":"trace[708173880] transaction","detail":"{read_only:false; response_revision:4723; number_of_response:1; }","duration":"211.448057ms","start":"2026-07-07T07:32:17.288979Z","end":"2026-07-07T07:32:17.500427Z","steps":["trace[708173880] 'process raft request' (duration: 210.767486ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:17.500828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"407.088164ms","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-07-07T07:32:17.500859Z","caller":"traceutil/trace.go:171","msg":"trace[629817498] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:4724; }","duration":"407.190819ms","start":"2026-07-07T07:32:17.09366Z","end":"2026-07-07T07:32:17.500851Z","steps":["trace[629817498] 'agreement among raft nodes before linearized reading' (duration: 407.054693ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:17.500886Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:17.093634Z","time spent":"407.242171ms","remote":"127.0.0.1:42310","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":516,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-07-07T07:32:17.501013Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"134.424826ms","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-07-07T07:32:17.501055Z","caller":"traceutil/trace.go:171","msg":"trace[81136487] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/; range_end:/registry/pxc.percona.com/perconaxtradbclusters0; response_count:0; response_revision:4724; }","duration":"134.49897ms","start":"2026-07-07T07:32:17.366543Z","end":"2026-07-07T07:32:17.501042Z","steps":["trace[81136487] 'agreement among raft nodes before linearized reading' (duration: 134.439397ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:17.502327Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"421.145888ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-07-07T07:32:17.502391Z","caller":"traceutil/trace.go:171","msg":"trace[342835557] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4724; }","duration":"422.093568ms","start":"2026-07-07T07:32:17.080281Z","end":"2026-07-07T07:32:17.502374Z","steps":["trace[342835557] 'agreement among raft nodes before linearized reading' (duration: 419.916465ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:17.502434Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:17.080261Z","time spent":"422.159111ms","remote":"127.0.0.1:42154","response type":"/etcdserverpb.KV/Range","request count":0,"request size":68,"response count":47,"response size":2206997,"request content":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" "} {"level":"warn","ts":"2026-07-07T07:32:17.950307Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.924019ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-07T07:32:17.951011Z","caller":"traceutil/trace.go:171","msg":"trace[1393550829] linearizableReadLoop","detail":"{readStateIndex:4904; appliedIndex:4903; }","duration":"160.263977ms","start":"2026-07-07T07:32:17.790725Z","end":"2026-07-07T07:32:17.950989Z","steps":["trace[1393550829] 'read index received' (duration: 471.07µs)","trace[1393550829] 'applied index is now lower than readState.Index' (duration: 159.790096ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:32:17.951158Z","caller":"traceutil/trace.go:171","msg":"trace[1275080243] transaction","detail":"{read_only:false; response_revision:4728; number_of_response:1; }","duration":"303.969442ms","start":"2026-07-07T07:32:17.647033Z","end":"2026-07-07T07:32:17.951002Z","steps":["trace[1275080243] 'process raft request' (duration: 144.235658ms)","trace[1275080243] 'compare' (duration: 158.626497ms)"],"step_count":2} {"level":"warn","ts":"2026-07-07T07:32:17.951224Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"160.477216ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:16086"} {"level":"info","ts":"2026-07-07T07:32:17.951249Z","caller":"traceutil/trace.go:171","msg":"trace[1257297936] range","detail":"{range_begin:/registry/pods/monitoring/prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4728; }","duration":"160.55281ms","start":"2026-07-07T07:32:17.790683Z","end":"2026-07-07T07:32:17.951236Z","steps":["trace[1257297936] 'agreement among raft nodes before linearized reading' (duration: 160.381392ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:17.951255Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:17.647012Z","time spent":"304.203463ms","remote":"127.0.0.1:40412","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":7495,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:32:17.951377Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.148217ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/monitoring/kube-prometheus-stack-grafana-64fcd9d99-z7m2t.18bff18658f678fa\" ","response":"range_response_count:1 size:893"} {"level":"warn","ts":"2026-07-07T07:32:17.951608Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.809189ms","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-07-07T07:32:17.951645Z","caller":"traceutil/trace.go:171","msg":"trace[2053122605] 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:4728; }","duration":"149.874701ms","start":"2026-07-07T07:32:17.801757Z","end":"2026-07-07T07:32:17.951632Z","steps":["trace[2053122605] 'agreement among raft nodes before linearized reading' (duration: 149.793558ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:17.951423Z","caller":"traceutil/trace.go:171","msg":"trace[269269306] range","detail":"{range_begin:/registry/events/monitoring/kube-prometheus-stack-grafana-64fcd9d99-z7m2t.18bff18658f678fa; range_end:; response_count:1; response_revision:4728; }","duration":"158.196159ms","start":"2026-07-07T07:32:17.793215Z","end":"2026-07-07T07:32:17.951411Z","steps":["trace[269269306] 'agreement among raft nodes before linearized reading' (duration: 157.931636ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:17.952203Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"110.163724ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/configmaps/monitoring/\" range_end:\"/registry/configmaps/monitoring0\" ","response":"range_response_count:47 size:2206973"} {"level":"info","ts":"2026-07-07T07:32:17.952233Z","caller":"traceutil/trace.go:171","msg":"trace[563729959] range","detail":"{range_begin:/registry/configmaps/monitoring/; range_end:/registry/configmaps/monitoring0; response_count:47; response_revision:4728; }","duration":"110.246017ms","start":"2026-07-07T07:32:17.841981Z","end":"2026-07-07T07:32:17.952227Z","steps":["trace[563729959] 'agreement among raft nodes before linearized reading' (duration: 109.551568ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:18.139784Z","caller":"traceutil/trace.go:171","msg":"trace[463017712] linearizableReadLoop","detail":"{readStateIndex:4906; appliedIndex:4905; }","duration":"104.996493ms","start":"2026-07-07T07:32:18.034767Z","end":"2026-07-07T07:32:18.139764Z","steps":["trace[463017712] 'read index received' (duration: 101.282293ms)","trace[463017712] 'applied index is now lower than readState.Index' (duration: 3.71339ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:32:18.139862Z","caller":"traceutil/trace.go:171","msg":"trace[879905116] transaction","detail":"{read_only:false; response_revision:4730; number_of_response:1; }","duration":"174.711446ms","start":"2026-07-07T07:32:17.965127Z","end":"2026-07-07T07:32:18.139838Z","steps":["trace[879905116] 'process raft request' (duration: 171.058098ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:18.140074Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"158.28973ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/secrets/monitoring/kube-prometheus-stack-admission\" ","response":"range_response_count:1 size:1817"} {"level":"info","ts":"2026-07-07T07:32:18.140107Z","caller":"traceutil/trace.go:171","msg":"trace[921902014] range","detail":"{range_begin:/registry/secrets/monitoring/kube-prometheus-stack-admission; range_end:; response_count:1; response_revision:4730; }","duration":"158.338742ms","start":"2026-07-07T07:32:17.981761Z","end":"2026-07-07T07:32:18.1401Z","steps":["trace[921902014] 'agreement among raft nodes before linearized reading' (duration: 158.144913ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:18.147706Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.23143ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0\" ","response":"range_response_count:1 size:2082"} {"level":"info","ts":"2026-07-07T07:32:18.147801Z","caller":"traceutil/trace.go:171","msg":"trace[1846440215] range","detail":"{range_begin:/registry/persistentvolumeclaims/monitoring/prometheus-kube-prometheus-stack-prometheus-db-prometheus-kube-prometheus-stack-prometheus-0; range_end:; response_count:1; response_revision:4730; }","duration":"108.832708ms","start":"2026-07-07T07:32:18.038935Z","end":"2026-07-07T07:32:18.147768Z","steps":["trace[1846440215] 'agreement among raft nodes before linearized reading' (duration: 101.177587ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:31.325756Z","caller":"traceutil/trace.go:171","msg":"trace[1973836862] linearizableReadLoop","detail":"{readStateIndex:5076; appliedIndex:5075; }","duration":"315.40522ms","start":"2026-07-07T07:32:31.010328Z","end":"2026-07-07T07:32:31.325734Z","steps":["trace[1973836862] 'read index received' (duration: 315.210691ms)","trace[1973836862] 'applied index is now lower than readState.Index' (duration: 193.579µs)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:32:31.325911Z","caller":"traceutil/trace.go:171","msg":"trace[1161734536] transaction","detail":"{read_only:false; response_revision:4894; number_of_response:1; }","duration":"347.767881ms","start":"2026-07-07T07:32:30.978127Z","end":"2026-07-07T07:32:31.325895Z","steps":["trace[1161734536] 'process raft request' (duration: 347.448417ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:31.326044Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:30.97811Z","time spent":"347.841174ms","remote":"127.0.0.1:42250","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":13863,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-07-07T07:32:31.326251Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"315.939094ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12216"} {"level":"info","ts":"2026-07-07T07:32:31.326286Z","caller":"traceutil/trace.go:171","msg":"trace[563187487] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:4894; }","duration":"316.023698ms","start":"2026-07-07T07:32:31.010254Z","end":"2026-07-07T07:32:31.326277Z","steps":["trace[563187487] 'agreement among raft nodes before linearized reading' (duration: 315.903002ms)"],"step_count":1} {"level":"warn","ts":"2026-07-07T07:32:31.326323Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-07T07:32:31.010223Z","time spent":"316.092621ms","remote":"127.0.0.1:42250","response type":"/etcdserverpb.KV/Range","request count":0,"request size":47,"response count":1,"response size":12240,"request content":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" "} {"level":"warn","ts":"2026-07-07T07:32:31.326505Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"290.932555ms","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-07-07T07:32:31.326588Z","caller":"traceutil/trace.go:171","msg":"trace[874894744] 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:4894; }","duration":"291.074241ms","start":"2026-07-07T07:32:31.035492Z","end":"2026-07-07T07:32:31.326567Z","steps":["trace[874894744] 'agreement among raft nodes before linearized reading' (duration: 290.872162ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:31.496983Z","caller":"traceutil/trace.go:171","msg":"trace[1255321216] range","detail":"{range_begin:/registry/deployments/openstack/percona-xtradb-pitr; range_end:; response_count:0; response_revision:4895; }","duration":"100.027631ms","start":"2026-07-07T07:32:31.396945Z","end":"2026-07-07T07:32:31.496972Z","steps":["trace[1255321216] 'agreement among raft nodes before linearized reading' (duration: 99.996849ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:31.496972Z","caller":"traceutil/trace.go:171","msg":"trace[1165013099] transaction","detail":"{read_only:false; response_revision:4895; number_of_response:1; }","duration":"152.4457ms","start":"2026-07-07T07:32:31.344494Z","end":"2026-07-07T07:32:31.49694Z","steps":["trace[1165013099] 'process raft request' (duration: 151.603082ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:31.728662Z","caller":"traceutil/trace.go:171","msg":"trace[710727750] transaction","detail":"{read_only:false; response_revision:4903; number_of_response:1; }","duration":"109.633629ms","start":"2026-07-07T07:32:31.619007Z","end":"2026-07-07T07:32:31.72864Z","steps":["trace[710727750] 'process raft request' (duration: 109.487522ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:31.880961Z","caller":"traceutil/trace.go:171","msg":"trace[1336702509] transaction","detail":"{read_only:false; response_revision:4906; number_of_response:1; }","duration":"113.780688ms","start":"2026-07-07T07:32:31.767158Z","end":"2026-07-07T07:32:31.880939Z","steps":["trace[1336702509] 'process raft request' (duration: 15.015156ms)","trace[1336702509] 'compare' (duration: 98.363144ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:32:31.88129Z","caller":"traceutil/trace.go:171","msg":"trace[1439349967] transaction","detail":"{read_only:false; response_revision:4908; number_of_response:1; }","duration":"114.081172ms","start":"2026-07-07T07:32:31.767199Z","end":"2026-07-07T07:32:31.88128Z","steps":["trace[1439349967] 'process raft request' (duration: 113.921134ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:32:31.881282Z","caller":"traceutil/trace.go:171","msg":"trace[265384559] transaction","detail":"{read_only:false; response_revision:4907; number_of_response:1; }","duration":"114.063671ms","start":"2026-07-07T07:32:31.767199Z","end":"2026-07-07T07:32:31.881263Z","steps":["trace[265384559] 'process raft request' (duration: 113.673853ms)"],"step_count":1} {"level":"info","ts":"2026-07-07T07:33:13.28281Z","caller":"traceutil/trace.go:171","msg":"trace[307346541] transaction","detail":"{read_only:false; response_revision:5261; number_of_response:1; }","duration":"122.444958ms","start":"2026-07-07T07:33:13.160328Z","end":"2026-07-07T07:33:13.282772Z","steps":["trace[307346541] 'process raft request' (duration: 65.154342ms)","trace[307346541] 'compare' (duration: 57.162139ms)"],"step_count":2} {"level":"info","ts":"2026-07-07T07:34:01.316933Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2921} {"level":"info","ts":"2026-07-07T07:34:01.431447Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2921,"took":"113.310831ms","hash":3926287600,"current-db-size-bytes":53792768,"current-db-size":"54 MB","current-db-size-in-use-bytes":29495296,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-07-07T07:34:01.431506Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3926287600,"revision":2921,"compact-revision":-1} {"level":"info","ts":"2026-07-07T07:39:01.322639Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":5595} {"level":"info","ts":"2026-07-07T07:39:01.462854Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":5595,"took":"138.754193ms","hash":3784410566,"current-db-size-bytes":53792768,"current-db-size":"54 MB","current-db-size-in-use-bytes":18292736,"current-db-size-in-use":"18 MB"} {"level":"info","ts":"2026-07-07T07:39:01.462911Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3784410566,"revision":5595,"compact-revision":2921}