{"level":"warn","ts":"2026-08-22T14:33:08.156069Z","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-22T14:33:08.156157Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.229:2379","--cert-file=/etc/kubernetes/pki/etcd/server.crt","--client-cert-auth=true","--data-dir=/var/lib/etcd","--experimental-initial-corrupt-check=true","--experimental-watch-progress-notify-interval=5s","--initial-advertise-peer-urls=https://199.204.45.229:2380","--initial-cluster=instance=https://199.204.45.229:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.229:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.229: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-22T14:33:08.156219Z","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-22T14:33:08.156231Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-08-22T14:33:08.156249Z","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-22T14:33:08.156854Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"]} {"level":"info","ts":"2026-08-22T14:33:08.15693Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":16,"max-cpu-available":16,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.229: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-22T14:33:08.159622Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"2.48149ms"} {"level":"info","ts":"2026-08-22T14:33:08.16617Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"3ba9ae53cc4335db","cluster-id":"4ad2674607ff1d60"} {"level":"info","ts":"2026-08-22T14:33:08.166256Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=()"} {"level":"info","ts":"2026-08-22T14:33:08.166274Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 0"} {"level":"info","ts":"2026-08-22T14:33:08.166293Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 3ba9ae53cc4335db [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-08-22T14:33:08.166305Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became follower at term 1"} {"level":"info","ts":"2026-08-22T14:33:08.166345Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"warn","ts":"2026-08-22T14:33:08.170957Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-08-22T14:33:08.17258Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-08-22T14:33:08.17398Z","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-22T14:33:08.176668Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"3ba9ae53cc4335db","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-08-22T14:33:08.177098Z","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-22T14:33:08.177359Z","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-22T14:33:08.177242Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"3ba9ae53cc4335db","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-08-22T14:33:08.177386Z","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-22T14:33:08.18058Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db switched to configuration voters=(4299158994234324443)"} {"level":"info","ts":"2026-08-22T14:33:08.18085Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","added-peer-id":"3ba9ae53cc4335db","added-peer-peer-urls":["https://199.204.45.229:2380"]} {"level":"info","ts":"2026-08-22T14:33:08.187237Z","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-22T14:33:08.187346Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-08-22T14:33:08.187387Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.229:2380"} {"level":"info","ts":"2026-08-22T14:33:08.187749Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"3ba9ae53cc4335db","initial-advertise-peer-urls":["https://199.204.45.229:2380"],"listen-peer-urls":["https://199.204.45.229:2380"],"advertise-client-urls":["https://199.204.45.229:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.229:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-08-22T14:33:08.187808Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-08-22T14:33:09.167031Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db is starting a new election at term 1"} {"level":"info","ts":"2026-08-22T14:33:09.167115Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became pre-candidate at term 1"} {"level":"info","ts":"2026-08-22T14:33:09.167145Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgPreVoteResp from 3ba9ae53cc4335db at term 1"} {"level":"info","ts":"2026-08-22T14:33:09.167167Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became candidate at term 2"} {"level":"info","ts":"2026-08-22T14:33:09.167179Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db received MsgVoteResp from 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-08-22T14:33:09.167196Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"3ba9ae53cc4335db became leader at term 2"} {"level":"info","ts":"2026-08-22T14:33:09.167216Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 3ba9ae53cc4335db elected leader 3ba9ae53cc4335db at term 2"} {"level":"info","ts":"2026-08-22T14:33:09.168217Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:33:09.169316Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"3ba9ae53cc4335db","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.229:2379]}","request-path":"/0/members/3ba9ae53cc4335db/attributes","cluster-id":"4ad2674607ff1d60","publish-timeout":"7s"} {"level":"info","ts":"2026-08-22T14:33:09.169324Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:33:09.169372Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-08-22T14:33:09.169602Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-08-22T14:33:09.169655Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-08-22T14:33:09.170062Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"4ad2674607ff1d60","local-member-id":"3ba9ae53cc4335db","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:33:09.170309Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:33:09.170375Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-08-22T14:33:09.172999Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.229:2379"} {"level":"info","ts":"2026-08-22T14:33:09.173011Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-08-22T14:33:49.138298Z","caller":"traceutil/trace.go:171","msg":"trace[1016562879] transaction","detail":"{read_only:false; response_revision:580; number_of_response:1; }","duration":"259.481908ms","start":"2026-08-22T14:33:48.878789Z","end":"2026-08-22T14:33:49.13827Z","steps":["trace[1016562879] 'process raft request' (duration: 259.270103ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:49.159274Z","caller":"traceutil/trace.go:171","msg":"trace[1134800199] transaction","detail":"{read_only:false; response_revision:581; number_of_response:1; }","duration":"241.496231ms","start":"2026-08-22T14:33:48.917755Z","end":"2026-08-22T14:33:49.159251Z","steps":["trace[1134800199] 'process raft request' (duration: 241.315888ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:49.159274Z","caller":"traceutil/trace.go:171","msg":"trace[1974311291] linearizableReadLoop","detail":"{readStateIndex:600; appliedIndex:599; }","duration":"190.104364ms","start":"2026-08-22T14:33:48.969128Z","end":"2026-08-22T14:33:49.159232Z","steps":["trace[1974311291] 'read index received' (duration: 169.221779ms)","trace[1974311291] 'applied index is now lower than readState.Index' (duration: 20.878785ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:33:49.159466Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.298969ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:33:49.159567Z","caller":"traceutil/trace.go:171","msg":"trace[968485480] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:581; }","duration":"190.462521ms","start":"2026-08-22T14:33:48.969089Z","end":"2026-08-22T14:33:49.159552Z","steps":["trace[968485480] 'agreement among raft nodes before linearized reading' (duration: 190.245897ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:49.159687Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.506572ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:33:49.159794Z","caller":"traceutil/trace.go:171","msg":"trace[16183719] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:581; }","duration":"190.660195ms","start":"2026-08-22T14:33:48.969105Z","end":"2026-08-22T14:33:49.159765Z","steps":["trace[16183719] 'agreement among raft nodes before linearized reading' (duration: 190.484822ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:49.159828Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"147.836352ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:33:49.159898Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.155738ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:33:49.159952Z","caller":"traceutil/trace.go:171","msg":"trace[1435409272] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:581; }","duration":"170.224019ms","start":"2026-08-22T14:33:48.989714Z","end":"2026-08-22T14:33:49.159938Z","steps":["trace[1435409272] 'agreement among raft nodes before linearized reading' (duration: 170.130667ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:49.159961Z","caller":"traceutil/trace.go:171","msg":"trace[1269067028] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:581; }","duration":"148.056197ms","start":"2026-08-22T14:33:49.011873Z","end":"2026-08-22T14:33:49.159929Z","steps":["trace[1269067028] 'agreement among raft nodes before linearized reading' (duration: 147.802162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:49.15996Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.328233ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:33:49.16004Z","caller":"traceutil/trace.go:171","msg":"trace[1469360484] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:581; }","duration":"148.439625ms","start":"2026-08-22T14:33:49.01158Z","end":"2026-08-22T14:33:49.160019Z","steps":["trace[1469360484] 'agreement among raft nodes before linearized reading' (duration: 148.306142ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:49.160057Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"170.348812ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:33:49.160048Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.51666ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T14:33:49.160036Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"169.747499ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.x-k8s.io/xmeshes/\" range_end:\"/registry/gateway.networking.x-k8s.io/xmeshes0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:33:49.160111Z","caller":"traceutil/trace.go:171","msg":"trace[1388231932] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:581; }","duration":"170.421613ms","start":"2026-08-22T14:33:48.98967Z","end":"2026-08-22T14:33:49.160091Z","steps":["trace[1388231932] 'agreement among raft nodes before linearized reading' (duration: 170.353752ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:49.159973Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"127.932596ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:33:49.160149Z","caller":"traceutil/trace.go:171","msg":"trace[345090456] range","detail":"{range_begin:/registry/gateway.networking.x-k8s.io/xmeshes/; range_end:/registry/gateway.networking.x-k8s.io/xmeshes0; response_count:0; response_revision:581; }","duration":"169.876982ms","start":"2026-08-22T14:33:48.990244Z","end":"2026-08-22T14:33:49.16012Z","steps":["trace[345090456] 'agreement among raft nodes before linearized reading' (duration: 169.661937ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:49.160166Z","caller":"traceutil/trace.go:171","msg":"trace[2076464319] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:581; }","duration":"128.13897ms","start":"2026-08-22T14:33:49.032016Z","end":"2026-08-22T14:33:49.160155Z","steps":["trace[2076464319] 'agreement among raft nodes before linearized reading' (duration: 127.898965ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:49.160188Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.616495ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.envoyproxy.io/envoyproxies/\" range_end:\"/registry/gateway.envoyproxy.io/envoyproxies0\" limit:500 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:33:49.160119Z","caller":"traceutil/trace.go:171","msg":"trace[899259714] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:581; }","duration":"128.629981ms","start":"2026-08-22T14:33:49.031471Z","end":"2026-08-22T14:33:49.160101Z","steps":["trace[899259714] 'agreement among raft nodes before linearized reading' (duration: 128.505639ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:33:49.160249Z","caller":"traceutil/trace.go:171","msg":"trace[1361920770] range","detail":"{range_begin:/registry/gateway.envoyproxy.io/envoyproxies/; range_end:/registry/gateway.envoyproxy.io/envoyproxies0; response_count:0; response_revision:581; }","duration":"190.709816ms","start":"2026-08-22T14:33:48.969526Z","end":"2026-08-22T14:33:49.160236Z","steps":["trace[1361920770] 'agreement among raft nodes before linearized reading' (duration: 190.620564ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:33:49.160098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.502689ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/gateway.networking.k8s.io/gatewayclasses/\" range_end:\"/registry/gateway.networking.k8s.io/gatewayclasses0\" limit:10000 ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:33:49.160437Z","caller":"traceutil/trace.go:171","msg":"trace[2009507439] range","detail":"{range_begin:/registry/gateway.networking.k8s.io/gatewayclasses/; range_end:/registry/gateway.networking.k8s.io/gatewayclasses0; response_count:0; response_revision:581; }","duration":"128.936927ms","start":"2026-08-22T14:33:49.031468Z","end":"2026-08-22T14:33:49.160405Z","steps":["trace[2009507439] 'agreement among raft nodes before linearized reading' (duration: 128.476218ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:34:03.933988Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"185.54538ms","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-22T14:34:03.934118Z","caller":"traceutil/trace.go:171","msg":"trace[278037010] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:743; }","duration":"185.676333ms","start":"2026-08-22T14:34:03.748381Z","end":"2026-08-22T14:34:03.934058Z","steps":["trace[278037010] 'range keys from in-memory index tree' (duration: 185.133242ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:22.466287Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.122327ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:506"} {"level":"warn","ts":"2026-08-22T14:35:22.466402Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"116.183924ms","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-08-22T14:35:22.466508Z","caller":"traceutil/trace.go:171","msg":"trace[1170102831] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:1825; }","duration":"116.353388ms","start":"2026-08-22T14:35:22.350124Z","end":"2026-08-22T14:35:22.466477Z","steps":["trace[1170102831] 'range keys from in-memory index tree' (duration: 115.809867ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:35:22.466443Z","caller":"traceutil/trace.go:171","msg":"trace[1541059319] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1825; }","duration":"148.3315ms","start":"2026-08-22T14:35:22.318085Z","end":"2026-08-22T14:35:22.466417Z","steps":["trace[1541059319] 'range keys from in-memory index tree' (duration: 147.860321ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:35:22.466583Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.538459ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/local-path-storage/helper-pod-create-pvc-3fa846d5-c9a9-4ad0-a567-231f557b789a\" ","response":"range_response_count:1 size:3627"} {"level":"info","ts":"2026-08-22T14:35:22.466702Z","caller":"traceutil/trace.go:171","msg":"trace[1907349797] range","detail":"{range_begin:/registry/pods/local-path-storage/helper-pod-create-pvc-3fa846d5-c9a9-4ad0-a567-231f557b789a; range_end:; response_count:1; response_revision:1825; }","duration":"181.698703ms","start":"2026-08-22T14:35:22.284976Z","end":"2026-08-22T14:35:22.466675Z","steps":["trace[1907349797] 'range keys from in-memory index tree' (duration: 181.302984ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:19.446621Z","caller":"traceutil/trace.go:171","msg":"trace[2103332314] linearizableReadLoop","detail":"{readStateIndex:2262; appliedIndex:2261; }","duration":"255.600332ms","start":"2026-08-22T14:36:19.190992Z","end":"2026-08-22T14:36:19.446592Z","steps":["trace[2103332314] 'read index received' (duration: 255.483189ms)","trace[2103332314] 'applied index is now lower than readState.Index' (duration: 115.933µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:36:19.446671Z","caller":"traceutil/trace.go:171","msg":"trace[1003213419] transaction","detail":"{read_only:false; response_revision:2182; number_of_response:1; }","duration":"274.894226ms","start":"2026-08-22T14:36:19.171753Z","end":"2026-08-22T14:36:19.446648Z","steps":["trace[1003213419] 'process raft request' (duration: 274.692162ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:36:19.446757Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"255.742534ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:36:19.446799Z","caller":"traceutil/trace.go:171","msg":"trace[1231980895] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2182; }","duration":"255.842957ms","start":"2026-08-22T14:36:19.190945Z","end":"2026-08-22T14:36:19.446788Z","steps":["trace[1231980895] 'agreement among raft nodes before linearized reading' (duration: 255.760465ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:19.479637Z","caller":"traceutil/trace.go:171","msg":"trace[79667738] transaction","detail":"{read_only:false; response_revision:2183; number_of_response:1; }","duration":"287.091136ms","start":"2026-08-22T14:36:19.192514Z","end":"2026-08-22T14:36:19.479605Z","steps":["trace[79667738] 'process raft request' (duration: 286.873142ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:19.479931Z","caller":"traceutil/trace.go:171","msg":"trace[1182298798] transaction","detail":"{read_only:false; response_revision:2184; number_of_response:1; }","duration":"286.82295ms","start":"2026-08-22T14:36:19.193088Z","end":"2026-08-22T14:36:19.479911Z","steps":["trace[1182298798] 'process raft request' (duration: 286.476763ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:36:19.479972Z","caller":"traceutil/trace.go:171","msg":"trace[473436336] transaction","detail":"{read_only:false; response_revision:2185; number_of_response:1; }","duration":"100.662056ms","start":"2026-08-22T14:36:19.37928Z","end":"2026-08-22T14:36:19.479942Z","steps":["trace[473436336] 'process raft request' (duration: 100.510233ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:36.003104Z","caller":"traceutil/trace.go:171","msg":"trace[1117258689] transaction","detail":"{read_only:false; response_revision:2790; number_of_response:1; }","duration":"136.594952ms","start":"2026-08-22T14:37:35.86648Z","end":"2026-08-22T14:37:36.003075Z","steps":["trace[1117258689] 'process raft request' (duration: 113.774689ms)","trace[1117258689] 'compare' (duration: 22.549677ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:37:36.005467Z","caller":"traceutil/trace.go:171","msg":"trace[519674677] transaction","detail":"{read_only:false; response_revision:2791; number_of_response:1; }","duration":"127.879636ms","start":"2026-08-22T14:37:35.877545Z","end":"2026-08-22T14:37:36.005425Z","steps":["trace[519674677] 'process raft request' (duration: 127.547839ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:37:36.00549Z","caller":"traceutil/trace.go:171","msg":"trace[831313640] transaction","detail":"{read_only:false; response_revision:2792; number_of_response:1; }","duration":"127.260792ms","start":"2026-08-22T14:37:35.87819Z","end":"2026-08-22T14:37:36.005451Z","steps":["trace[831313640] 'process raft request' (duration: 127.1474ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:40:00.488996Z","caller":"traceutil/trace.go:171","msg":"trace[1874906590] linearizableReadLoop","detail":"{readStateIndex:3892; appliedIndex:3891; }","duration":"150.070035ms","start":"2026-08-22T14:40:00.338905Z","end":"2026-08-22T14:40:00.488975Z","steps":["trace[1874906590] 'read index received' (duration: 51.854122ms)","trace[1874906590] 'applied index is now lower than readState.Index' (duration: 98.215083ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:40:00.489184Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"150.270848ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/auth-system/keycloak.18ce2785b3e7488c\" ","response":"range_response_count:1 size:676"} {"level":"info","ts":"2026-08-22T14:40:00.489141Z","caller":"traceutil/trace.go:171","msg":"trace[888536307] transaction","detail":"{read_only:false; response_revision:3753; number_of_response:1; }","duration":"192.534027ms","start":"2026-08-22T14:40:00.296561Z","end":"2026-08-22T14:40:00.489095Z","steps":["trace[888536307] 'process raft request' (duration: 94.175781ms)","trace[888536307] 'compare' (duration: 97.999439ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:40:00.48922Z","caller":"traceutil/trace.go:171","msg":"trace[455141052] range","detail":"{range_begin:/registry/events/auth-system/keycloak.18ce2785b3e7488c; range_end:; response_count:1; response_revision:3753; }","duration":"150.34592ms","start":"2026-08-22T14:40:00.338863Z","end":"2026-08-22T14:40:00.489209Z","steps":["trace[455141052] 'agreement among raft nodes before linearized reading' (duration: 150.204037ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:41:21.689711Z","caller":"traceutil/trace.go:171","msg":"trace[2135471734] transaction","detail":"{read_only:false; response_revision:4896; number_of_response:1; }","duration":"107.278346ms","start":"2026-08-22T14:41:21.582402Z","end":"2026-08-22T14:41:21.689681Z","steps":["trace[2135471734] 'process raft request' (duration: 90.620338ms)","trace[2135471734] 'compare' (duration: 16.521956ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:41:28.209852Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"128.850314ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:41:28.209985Z","caller":"traceutil/trace.go:171","msg":"trace[549364967] transaction","detail":"{read_only:false; response_revision:5013; number_of_response:1; }","duration":"261.942115ms","start":"2026-08-22T14:41:27.948024Z","end":"2026-08-22T14:41:28.209966Z","steps":["trace[549364967] 'process raft request' (duration: 131.993388ms)","trace[549364967] 'compare' (duration: 128.400446ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:41:28.234519Z","caller":"traceutil/trace.go:171","msg":"trace[1641487328] transaction","detail":"{read_only:false; response_revision:5014; number_of_response:1; }","duration":"274.377007ms","start":"2026-08-22T14:41:27.960114Z","end":"2026-08-22T14:41:28.234491Z","steps":["trace[1641487328] 'process raft request' (duration: 274.065ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:41:28.23502Z","caller":"traceutil/trace.go:171","msg":"trace[71285610] transaction","detail":"{read_only:false; response_revision:5015; number_of_response:1; }","duration":"273.428938ms","start":"2026-08-22T14:41:27.961571Z","end":"2026-08-22T14:41:28.234999Z","steps":["trace[71285610] 'process raft request' (duration: 272.876226ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:41:36.59274Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"209.636144ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:41:36.592877Z","caller":"traceutil/trace.go:171","msg":"trace[1581731697] linearizableReadLoop","detail":"{readStateIndex:5368; appliedIndex:5367; }","duration":"281.160425ms","start":"2026-08-22T14:41:36.311697Z","end":"2026-08-22T14:41:36.592858Z","steps":["trace[1581731697] 'read index received' (duration: 71.162734ms)","trace[1581731697] 'applied index is now lower than readState.Index' (duration: 209.996481ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:41:36.592964Z","caller":"traceutil/trace.go:171","msg":"trace[1775679297] transaction","detail":"{read_only:false; response_revision:5179; number_of_response:1; }","duration":"281.507962ms","start":"2026-08-22T14:41:36.311443Z","end":"2026-08-22T14:41:36.592951Z","steps":["trace[1775679297] 'process raft request' (duration: 71.429729ms)","trace[1775679297] 'compare' (duration: 209.42943ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T14:41:36.59328Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"281.589823ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/memcached-memcached-6479589586-zjjdb\" ","response":"range_response_count:1 size:7818"} {"level":"info","ts":"2026-08-22T14:41:36.593823Z","caller":"traceutil/trace.go:171","msg":"trace[53494414] range","detail":"{range_begin:/registry/pods/openstack/memcached-memcached-6479589586-zjjdb; range_end:; response_count:1; response_revision:5179; }","duration":"282.157866ms","start":"2026-08-22T14:41:36.311647Z","end":"2026-08-22T14:41:36.593805Z","steps":["trace[53494414] 'agreement among raft nodes before linearized reading' (duration: 281.505372ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:41:36.593329Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"175.854908ms","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-22T14:41:36.594044Z","caller":"traceutil/trace.go:171","msg":"trace[1819160057] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:5179; }","duration":"176.616684ms","start":"2026-08-22T14:41:36.417417Z","end":"2026-08-22T14:41:36.594033Z","steps":["trace[1819160057] 'agreement among raft nodes before linearized reading' (duration: 175.804167ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:41:36.59337Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"166.790944ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/cilium.io/ciliumnodeconfigs/\" range_end:\"/registry/cilium.io/ciliumnodeconfigs0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:41:36.594189Z","caller":"traceutil/trace.go:171","msg":"trace[975902503] range","detail":"{range_begin:/registry/cilium.io/ciliumnodeconfigs/; range_end:/registry/cilium.io/ciliumnodeconfigs0; response_count:0; response_revision:5179; }","duration":"167.647792ms","start":"2026-08-22T14:41:36.426502Z","end":"2026-08-22T14:41:36.59415Z","steps":["trace[975902503] 'agreement among raft nodes before linearized reading' (duration: 166.763383ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:43:09.195684Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":2977} {"level":"info","ts":"2026-08-22T14:43:09.268453Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":2977,"took":"71.211524ms","hash":3338317970,"current-db-size-bytes":59133952,"current-db-size":"59 MB","current-db-size-in-use-bytes":34406400,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-22T14:43:09.268521Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3338317970,"revision":2977,"compact-revision":-1} {"level":"info","ts":"2026-08-22T14:47:53.470728Z","caller":"traceutil/trace.go:171","msg":"trace[1023950955] linearizableReadLoop","detail":"{readStateIndex:8466; appliedIndex:8465; }","duration":"316.033398ms","start":"2026-08-22T14:47:53.154672Z","end":"2026-08-22T14:47:53.470705Z","steps":["trace[1023950955] 'read index received' (duration: 315.782993ms)","trace[1023950955] 'applied index is now lower than readState.Index' (duration: 249.535µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:47:53.470846Z","caller":"traceutil/trace.go:171","msg":"trace[551585604] transaction","detail":"{read_only:false; response_revision:8186; number_of_response:1; }","duration":"332.062823ms","start":"2026-08-22T14:47:53.138754Z","end":"2026-08-22T14:47:53.470817Z","steps":["trace[551585604] 'process raft request' (duration: 331.796248ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:47:53.470909Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"316.212281ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:47:53.470973Z","caller":"traceutil/trace.go:171","msg":"trace[1530554356] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8186; }","duration":"316.319424ms","start":"2026-08-22T14:47:53.154639Z","end":"2026-08-22T14:47:53.470959Z","steps":["trace[1530554356] 'agreement among raft nodes before linearized reading' (duration: 316.193452ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:47:53.471045Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"249.270847ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"warn","ts":"2026-08-22T14:47:53.471014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:47:53.138728Z","time spent":"332.209036ms","remote":"127.0.0.1:38946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":421,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:47:53.47111Z","caller":"traceutil/trace.go:171","msg":"trace[495854914] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:8186; }","duration":"249.395109ms","start":"2026-08-22T14:47:53.221698Z","end":"2026-08-22T14:47:53.471093Z","steps":["trace[495854914] 'agreement among raft nodes before linearized reading' (duration: 249.195985ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:47:53.471014Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:47:53.154626Z","time spent":"316.380275ms","remote":"127.0.0.1:38644","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":27,"request content":"key:\"/registry/health\" "} {"level":"warn","ts":"2026-08-22T14:47:53.471197Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"302.423113ms","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-22T14:47:53.471256Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"307.709ms","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-22T14:47:53.471417Z","caller":"traceutil/trace.go:171","msg":"trace[478845315] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:8186; }","duration":"307.903414ms","start":"2026-08-22T14:47:53.1635Z","end":"2026-08-22T14:47:53.471404Z","steps":["trace[478845315] 'agreement among raft nodes before linearized reading' (duration: 307.339673ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:47:53.471294Z","caller":"traceutil/trace.go:171","msg":"trace[632352270] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:8186; }","duration":"302.564255ms","start":"2026-08-22T14:47:53.168707Z","end":"2026-08-22T14:47:53.471272Z","steps":["trace[632352270] 'agreement among raft nodes before linearized reading' (duration: 302.31787ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:47:53.4715Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:47:53.163481Z","time spent":"308.008466ms","remote":"127.0.0.1:38946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":60,"response count":1,"response size":514,"request content":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" "} {"level":"warn","ts":"2026-08-22T14:47:53.47153Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:47:53.168693Z","time spent":"302.809031ms","remote":"127.0.0.1:38946","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":455,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"info","ts":"2026-08-22T14:47:53.594948Z","caller":"traceutil/trace.go:171","msg":"trace[629243874] transaction","detail":"{read_only:false; response_revision:8187; number_of_response:1; }","duration":"119.476689ms","start":"2026-08-22T14:47:53.47543Z","end":"2026-08-22T14:47:53.594907Z","steps":["trace[629243874] 'process raft request' (duration: 87.341209ms)","trace[629243874] 'compare' (duration: 31.812464ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:47:53.595103Z","caller":"traceutil/trace.go:171","msg":"trace[1125361743] transaction","detail":"{read_only:false; response_revision:8188; number_of_response:1; }","duration":"119.622172ms","start":"2026-08-22T14:47:53.475449Z","end":"2026-08-22T14:47:53.595071Z","steps":["trace[1125361743] 'process raft request' (duration: 119.354887ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:47:53.595347Z","caller":"traceutil/trace.go:171","msg":"trace[1671434013] transaction","detail":"{read_only:false; response_revision:8189; number_of_response:1; }","duration":"119.465568ms","start":"2026-08-22T14:47:53.47585Z","end":"2026-08-22T14:47:53.595315Z","steps":["trace[1671434013] 'process raft request' (duration: 119.05475ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:48:05.993778Z","caller":"traceutil/trace.go:171","msg":"trace[1017333934] transaction","detail":"{read_only:false; response_revision:8252; number_of_response:1; }","duration":"320.493448ms","start":"2026-08-22T14:48:05.673258Z","end":"2026-08-22T14:48:05.993751Z","steps":["trace[1017333934] 'process raft request' (duration: 320.308004ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:48:05.99394Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:48:05.673226Z","time spent":"320.626331ms","remote":"127.0.0.1:38946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":477,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:48:05.993901Z","caller":"traceutil/trace.go:171","msg":"trace[1257169754] transaction","detail":"{read_only:false; response_revision:8253; number_of_response:1; }","duration":"320.121391ms","start":"2026-08-22T14:48:05.673756Z","end":"2026-08-22T14:48:05.993877Z","steps":["trace[1257169754] 'process raft request' (duration: 319.954107ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:48:05.994186Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T14:48:05.673729Z","time spent":"320.347256ms","remote":"127.0.0.1:38946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":464,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T14:48:06.49897Z","caller":"traceutil/trace.go:171","msg":"trace[1172651757] transaction","detail":"{read_only:false; response_revision:8256; number_of_response:1; }","duration":"101.353582ms","start":"2026-08-22T14:48:06.397589Z","end":"2026-08-22T14:48:06.498943Z","steps":["trace[1172651757] 'process raft request' (duration: 101.132268ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:48:06.499209Z","caller":"traceutil/trace.go:171","msg":"trace[798480784] transaction","detail":"{read_only:false; response_revision:8257; number_of_response:1; }","duration":"100.091997ms","start":"2026-08-22T14:48:06.399091Z","end":"2026-08-22T14:48:06.499183Z","steps":["trace[798480784] 'process raft request' (duration: 99.8113ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:48:09.200523Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":6106} {"level":"info","ts":"2026-08-22T14:48:09.287342Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":6106,"took":"84.901439ms","hash":2731482374,"current-db-size-bytes":59133952,"current-db-size":"59 MB","current-db-size-in-use-bytes":23334912,"current-db-size-in-use":"23 MB"} {"level":"info","ts":"2026-08-22T14:48:09.28742Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2731482374,"revision":6106,"compact-revision":2977} {"level":"info","ts":"2026-08-22T14:48:34.116685Z","caller":"traceutil/trace.go:171","msg":"trace[2118924722] transaction","detail":"{read_only:false; response_revision:8460; number_of_response:1; }","duration":"239.813135ms","start":"2026-08-22T14:48:33.876849Z","end":"2026-08-22T14:48:34.116662Z","steps":["trace[2118924722] 'process raft request' (duration: 239.677743ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:48:34.311151Z","caller":"traceutil/trace.go:171","msg":"trace[2011158720] transaction","detail":"{read_only:false; response_revision:8461; number_of_response:1; }","duration":"184.506785ms","start":"2026-08-22T14:48:34.126591Z","end":"2026-08-22T14:48:34.311098Z","steps":["trace[2011158720] 'process raft request' (duration: 134.547814ms)","trace[2011158720] 'compare' (duration: 49.245106ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:48:34.311158Z","caller":"traceutil/trace.go:171","msg":"trace[366840587] linearizableReadLoop","detail":"{readStateIndex:8754; appliedIndex:8752; }","duration":"157.303906ms","start":"2026-08-22T14:48:34.153825Z","end":"2026-08-22T14:48:34.311129Z","steps":["trace[366840587] 'read index received' (duration: 107.326054ms)","trace[366840587] 'applied index is now lower than readState.Index' (duration: 49.972192ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:48:34.311311Z","caller":"traceutil/trace.go:171","msg":"trace[908686187] transaction","detail":"{read_only:false; response_revision:8462; number_of_response:1; }","duration":"183.389224ms","start":"2026-08-22T14:48:34.127879Z","end":"2026-08-22T14:48:34.311268Z","steps":["trace[908686187] 'process raft request' (duration: 182.899033ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:48:34.311375Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"157.52722ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-08-22T14:48:34.311452Z","caller":"traceutil/trace.go:171","msg":"trace[1660307698] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:8462; }","duration":"157.634062ms","start":"2026-08-22T14:48:34.1538Z","end":"2026-08-22T14:48:34.311434Z","steps":["trace[1660307698] 'agreement among raft nodes before linearized reading' (duration: 157.461029ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:48:37.010118Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"287.370508ms","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-22T14:48:37.010181Z","caller":"traceutil/trace.go:171","msg":"trace[335033929] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:8476; }","duration":"287.479261ms","start":"2026-08-22T14:48:36.72269Z","end":"2026-08-22T14:48:37.010169Z","steps":["trace[335033929] 'range keys from in-memory index tree' (duration: 287.206065ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:48:44.751482Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.674259ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-08-22T14:48:44.751633Z","caller":"traceutil/trace.go:171","msg":"trace[1397056638] transaction","detail":"{read_only:false; response_revision:8518; number_of_response:1; }","duration":"182.385912ms","start":"2026-08-22T14:48:44.569209Z","end":"2026-08-22T14:48:44.751595Z","steps":["trace[1397056638] 'process raft request' (duration: 33.492488ms)","trace[1397056638] 'compare' (duration: 148.526027ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:48:44.777985Z","caller":"traceutil/trace.go:171","msg":"trace[2011206219] transaction","detail":"{read_only:false; response_revision:8519; number_of_response:1; }","duration":"143.964625ms","start":"2026-08-22T14:48:44.633994Z","end":"2026-08-22T14:48:44.777959Z","steps":["trace[2011206219] 'process raft request' (duration: 143.819052ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:48:44.819105Z","caller":"traceutil/trace.go:171","msg":"trace[490793640] transaction","detail":"{read_only:false; response_revision:8520; number_of_response:1; }","duration":"170.564283ms","start":"2026-08-22T14:48:44.648521Z","end":"2026-08-22T14:48:44.819085Z","steps":["trace[490793640] 'process raft request' (duration: 170.45799ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:49:17.334967Z","caller":"wal/wal.go:785","msg":"created a new WAL segment","path":"/var/lib/etcd/member/wal/0000000000000001-00000000000023d6.wal"} {"level":"info","ts":"2026-08-22T14:50:53.844384Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T14:50:53.851739Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-08-22T14:50:53.851811Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"info","ts":"2026-08-22T14:53:09.207066Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":8279} {"level":"info","ts":"2026-08-22T14:53:09.284639Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":8279,"took":"75.223941ms","hash":995677638,"current-db-size-bytes":59133952,"current-db-size":"59 MB","current-db-size-in-use-bytes":25468928,"current-db-size-in-use":"26 MB"} {"level":"info","ts":"2026-08-22T14:53:09.284713Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":995677638,"revision":8279,"compact-revision":6106} {"level":"info","ts":"2026-08-22T14:53:56.399374Z","caller":"traceutil/trace.go:171","msg":"trace[108009388] transaction","detail":"{read_only:false; response_revision:11208; number_of_response:1; }","duration":"251.008176ms","start":"2026-08-22T14:53:56.148338Z","end":"2026-08-22T14:53:56.399346Z","steps":["trace[108009388] 'process raft request' (duration: 250.827323ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:53:56.410865Z","caller":"traceutil/trace.go:171","msg":"trace[960636355] linearizableReadLoop","detail":"{readStateIndex:11583; appliedIndex:11581; }","duration":"144.83994ms","start":"2026-08-22T14:53:56.265999Z","end":"2026-08-22T14:53:56.410839Z","steps":["trace[960636355] 'read index received' (duration: 133.184604ms)","trace[960636355] 'applied index is now lower than readState.Index' (duration: 11.653926ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T14:53:56.410921Z","caller":"traceutil/trace.go:171","msg":"trace[1774366981] transaction","detail":"{read_only:false; response_revision:11209; number_of_response:1; }","duration":"261.468278ms","start":"2026-08-22T14:53:56.149426Z","end":"2026-08-22T14:53:56.410895Z","steps":["trace[1774366981] 'process raft request' (duration: 261.215243ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T14:53:56.411087Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"145.066384ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/openstack/cinder-volume-595bdb78fc-v5bs7.18ce284af72670a2\" ","response":"range_response_count:1 size:804"} {"level":"info","ts":"2026-08-22T14:53:56.411149Z","caller":"traceutil/trace.go:171","msg":"trace[734274168] range","detail":"{range_begin:/registry/events/openstack/cinder-volume-595bdb78fc-v5bs7.18ce284af72670a2; range_end:; response_count:1; response_revision:11209; }","duration":"145.188376ms","start":"2026-08-22T14:53:56.265941Z","end":"2026-08-22T14:53:56.411129Z","steps":["trace[734274168] 'agreement among raft nodes before linearized reading' (duration: 144.998763ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T14:58:09.213686Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":10753} {"level":"info","ts":"2026-08-22T14:58:09.296944Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":10753,"took":"80.095261ms","hash":2895561197,"current-db-size-bytes":59133952,"current-db-size":"59 MB","current-db-size-in-use-bytes":26959872,"current-db-size-in-use":"27 MB"} {"level":"info","ts":"2026-08-22T14:58:09.297025Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2895561197,"revision":10753,"compact-revision":8279} {"level":"info","ts":"2026-08-22T15:00:35.811353Z","caller":"traceutil/trace.go:171","msg":"trace[1675239840] transaction","detail":"{read_only:false; response_revision:13863; number_of_response:1; }","duration":"126.594378ms","start":"2026-08-22T15:00:35.684728Z","end":"2026-08-22T15:00:35.811322Z","steps":["trace[1675239840] 'process raft request' (duration: 126.365433ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:02:07.379787Z","caller":"traceutil/trace.go:171","msg":"trace[1458508651] linearizableReadLoop","detail":"{readStateIndex:15539; appliedIndex:15538; }","duration":"103.887366ms","start":"2026-08-22T15:02:07.275874Z","end":"2026-08-22T15:02:07.379762Z","steps":["trace[1458508651] 'read index received' (duration: 92.833002ms)","trace[1458508651] 'applied index is now lower than readState.Index' (duration: 11.052824ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:02:07.380229Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.326425ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-22T15:02:07.380318Z","caller":"traceutil/trace.go:171","msg":"trace[1609586056] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15036; }","duration":"104.475128ms","start":"2026-08-22T15:02:07.275821Z","end":"2026-08-22T15:02:07.380297Z","steps":["trace[1609586056] 'agreement among raft nodes before linearized reading' (duration: 104.07713ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:02:07.380588Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.56218ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-rabbit-init\" ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-08-22T15:02:07.380607Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.205882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/jobs/openstack/nova-db-sync\" ","response":"range_response_count:1 size:5521"} {"level":"info","ts":"2026-08-22T15:02:07.380668Z","caller":"traceutil/trace.go:171","msg":"trace[2044068839] range","detail":"{range_begin:/registry/jobs/openstack/nova-db-sync; range_end:; response_count:1; response_revision:15037; }","duration":"104.304773ms","start":"2026-08-22T15:02:07.27635Z","end":"2026-08-22T15:02:07.380655Z","steps":["trace[2044068839] 'agreement among raft nodes before linearized reading' (duration: 104.135ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:02:07.380711Z","caller":"traceutil/trace.go:171","msg":"trace[1937751207] range","detail":"{range_begin:/registry/jobs/openstack/nova-rabbit-init; range_end:; response_count:0; response_revision:15037; }","duration":"104.783304ms","start":"2026-08-22T15:02:07.275907Z","end":"2026-08-22T15:02:07.38069Z","steps":["trace[1937751207] 'agreement among raft nodes before linearized reading' (duration: 104.484918ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:02:11.666177Z","caller":"traceutil/trace.go:171","msg":"trace[211513800] transaction","detail":"{read_only:false; response_revision:15060; number_of_response:1; }","duration":"270.834055ms","start":"2026-08-22T15:02:11.395321Z","end":"2026-08-22T15:02:11.666155Z","steps":["trace[211513800] 'process raft request' (duration: 270.693902ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:02:11.838876Z","caller":"traceutil/trace.go:171","msg":"trace[502723467] transaction","detail":"{read_only:false; response_revision:15061; number_of_response:1; }","duration":"284.219596ms","start":"2026-08-22T15:02:11.554631Z","end":"2026-08-22T15:02:11.83885Z","steps":["trace[502723467] 'process raft request' (duration: 284.040753ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:02:11.838888Z","caller":"traceutil/trace.go:171","msg":"trace[324832293] transaction","detail":"{read_only:false; response_revision:15062; number_of_response:1; }","duration":"283.801248ms","start":"2026-08-22T15:02:11.555067Z","end":"2026-08-22T15:02:11.838869Z","steps":["trace[324832293] 'process raft request' (duration: 283.739856ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:02:11.839469Z","caller":"traceutil/trace.go:171","msg":"trace[1234252754] linearizableReadLoop","detail":"{readStateIndex:15566; appliedIndex:15563; }","duration":"112.333137ms","start":"2026-08-22T15:02:11.727115Z","end":"2026-08-22T15:02:11.839448Z","steps":["trace[1234252754] 'read index received' (duration: 111.603043ms)","trace[1234252754] 'applied index is now lower than readState.Index' (duration: 728.764µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:02:11.840208Z","caller":"traceutil/trace.go:171","msg":"trace[797740630] transaction","detail":"{read_only:false; response_revision:15063; number_of_response:1; }","duration":"172.461042ms","start":"2026-08-22T15:02:11.667727Z","end":"2026-08-22T15:02:11.840188Z","steps":["trace[797740630] 'process raft request' (duration: 171.600204ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:02:11.840425Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"113.289347ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/services/endpoints/openstack/nova-api\" ","response":"range_response_count:1 size:668"} {"level":"info","ts":"2026-08-22T15:02:11.840514Z","caller":"traceutil/trace.go:171","msg":"trace[2088891555] range","detail":"{range_begin:/registry/services/endpoints/openstack/nova-api; range_end:; response_count:1; response_revision:15063; }","duration":"113.44212ms","start":"2026-08-22T15:02:11.727051Z","end":"2026-08-22T15:02:11.840494Z","steps":["trace[2088891555] 'agreement among raft nodes before linearized reading' (duration: 113.179255ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:03:09.220256Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12830} {"level":"info","ts":"2026-08-22T15:03:09.292979Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12830,"took":"70.793342ms","hash":3086171308,"current-db-size-bytes":59133952,"current-db-size":"59 MB","current-db-size-in-use-bytes":30089216,"current-db-size-in-use":"30 MB"} {"level":"info","ts":"2026-08-22T15:03:09.293051Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3086171308,"revision":12830,"compact-revision":10753} {"level":"info","ts":"2026-08-22T15:08:09.224939Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":15364} {"level":"info","ts":"2026-08-22T15:08:09.325578Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":15364,"took":"97.478865ms","hash":820497119,"current-db-size-bytes":59133952,"current-db-size":"59 MB","current-db-size-in-use-bytes":31739904,"current-db-size-in-use":"32 MB"} {"level":"info","ts":"2026-08-22T15:08:09.325638Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":820497119,"revision":15364,"compact-revision":12830} {"level":"info","ts":"2026-08-22T15:08:22.19791Z","caller":"traceutil/trace.go:171","msg":"trace[457959759] transaction","detail":"{read_only:false; response_revision:17833; number_of_response:1; }","duration":"118.584714ms","start":"2026-08-22T15:08:22.079302Z","end":"2026-08-22T15:08:22.197887Z","steps":["trace[457959759] 'process raft request' (duration: 118.031533ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:08:24.008399Z","caller":"traceutil/trace.go:171","msg":"trace[1494704366] linearizableReadLoop","detail":"{readStateIndex:18443; appliedIndex:18442; }","duration":"204.318289ms","start":"2026-08-22T15:08:23.804053Z","end":"2026-08-22T15:08:24.008371Z","steps":["trace[1494704366] 'read index received' (duration: 204.193516ms)","trace[1494704366] 'applied index is now lower than readState.Index' (duration: 123.753µs)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:08:24.008499Z","caller":"traceutil/trace.go:171","msg":"trace[1785190612] transaction","detail":"{read_only:false; response_revision:17847; number_of_response:1; }","duration":"207.404992ms","start":"2026-08-22T15:08:23.801064Z","end":"2026-08-22T15:08:24.008469Z","steps":["trace[1785190612] 'process raft request' (duration: 207.128846ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:08:24.00959Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"205.501523ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/events/\" range_end:\"/registry/events0\" count_only:true ","response":"range_response_count:0 size:9"} {"level":"info","ts":"2026-08-22T15:08:24.00968Z","caller":"traceutil/trace.go:171","msg":"trace[1658844322] range","detail":"{range_begin:/registry/events/; range_end:/registry/events0; response_count:0; response_revision:17847; }","duration":"205.635727ms","start":"2026-08-22T15:08:23.804024Z","end":"2026-08-22T15:08:24.00966Z","steps":["trace[1658844322] 'agreement among raft nodes before linearized reading' (duration: 204.457882ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:08:25.788967Z","caller":"traceutil/trace.go:171","msg":"trace[417402846] transaction","detail":"{read_only:false; response_revision:17869; number_of_response:1; }","duration":"127.922644ms","start":"2026-08-22T15:08:25.661021Z","end":"2026-08-22T15:08:25.788944Z","steps":["trace[417402846] 'process raft request' (duration: 127.792881ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:11:27.718455Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"3ba9ae53cc4335db","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-08-22T15:11:27.7245Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-08-22T15:11:27.724568Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-08-22T15:13:09.231118Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":17672} {"level":"info","ts":"2026-08-22T15:13:09.333682Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":17672,"took":"97.897865ms","hash":2013478832,"current-db-size-bytes":59133952,"current-db-size":"59 MB","current-db-size-in-use-bytes":34652160,"current-db-size-in-use":"35 MB"} {"level":"info","ts":"2026-08-22T15:13:09.333763Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2013478832,"revision":17672,"compact-revision":15364} {"level":"info","ts":"2026-08-22T15:14:19.943996Z","caller":"traceutil/trace.go:171","msg":"trace[1641389845] transaction","detail":"{read_only:false; response_revision:20517; number_of_response:1; }","duration":"106.882168ms","start":"2026-08-22T15:14:19.837091Z","end":"2026-08-22T15:14:19.943974Z","steps":["trace[1641389845] 'process raft request' (duration: 106.544161ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:14:20.095586Z","caller":"traceutil/trace.go:171","msg":"trace[1209261362] transaction","detail":"{read_only:false; response_revision:20518; number_of_response:1; }","duration":"147.091837ms","start":"2026-08-22T15:14:19.948465Z","end":"2026-08-22T15:14:20.095557Z","steps":["trace[1209261362] 'process raft request' (duration: 128.484337ms)","trace[1209261362] 'compare' (duration: 18.434236ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:14:20.692359Z","caller":"traceutil/trace.go:171","msg":"trace[778320254] transaction","detail":"{read_only:false; response_revision:20521; number_of_response:1; }","duration":"343.285644ms","start":"2026-08-22T15:14:20.349042Z","end":"2026-08-22T15:14:20.692328Z","steps":["trace[778320254] 'process raft request' (duration: 343.186112ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:14:20.692538Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:14:20.349019Z","time spent":"343.423448ms","remote":"127.0.0.1:38946","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-22T15:14:20.692702Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"339.495517ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:482"} {"level":"info","ts":"2026-08-22T15:14:20.692745Z","caller":"traceutil/trace.go:171","msg":"trace[656905001] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:20522; }","duration":"339.58235ms","start":"2026-08-22T15:14:20.353156Z","end":"2026-08-22T15:14:20.692739Z","steps":["trace[656905001] 'agreement among raft nodes before linearized reading' (duration: 339.416276ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:14:20.692783Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:14:20.353145Z","time spent":"339.63219ms","remote":"127.0.0.1:38946","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-22T15:14:20.692825Z","caller":"traceutil/trace.go:171","msg":"trace[1324817848] transaction","detail":"{read_only:false; response_revision:20520; number_of_response:1; }","duration":"344.139713ms","start":"2026-08-22T15:14:20.348672Z","end":"2026-08-22T15:14:20.692811Z","steps":["trace[1324817848] 'process raft request' (duration: 343.380898ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:14:20.692903Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:14:20.34865Z","time spent":"344.214374ms","remote":"127.0.0.1:38946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":540,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:14:20.692915Z","caller":"traceutil/trace.go:171","msg":"trace[886599110] transaction","detail":"{read_only:false; response_revision:20522; number_of_response:1; }","duration":"342.944728ms","start":"2026-08-22T15:14:20.349944Z","end":"2026-08-22T15:14:20.692889Z","steps":["trace[886599110] 'process raft request' (duration: 342.331735ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:14:20.692532Z","caller":"traceutil/trace.go:171","msg":"trace[1566747398] linearizableReadLoop","detail":"{readStateIndex:21208; appliedIndex:21204; }","duration":"339.319244ms","start":"2026-08-22T15:14:20.353191Z","end":"2026-08-22T15:14:20.69251Z","steps":["trace[1566747398] 'read index received' (duration: 40.395012ms)","trace[1566747398] 'applied index is now lower than readState.Index' (duration: 298.923382ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:14:20.693725Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:14:20.349917Z","time spent":"343.05616ms","remote":"127.0.0.1:38946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":509,"response count":0,"response size":40,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-08-22T15:14:20.752841Z","caller":"traceutil/trace.go:171","msg":"trace[365566384] transaction","detail":"{read_only:false; response_revision:20523; number_of_response:1; }","duration":"256.496477ms","start":"2026-08-22T15:14:20.496318Z","end":"2026-08-22T15:14:20.752815Z","steps":["trace[365566384] 'process raft request' (duration: 256.246672ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:14:20.752866Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"317.615703ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/cilium-operator-resource-lock\" ","response":"range_response_count:1 size:495"} {"level":"info","ts":"2026-08-22T15:14:20.752922Z","caller":"traceutil/trace.go:171","msg":"trace[47003862] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:20523; }","duration":"317.708704ms","start":"2026-08-22T15:14:20.435197Z","end":"2026-08-22T15:14:20.752906Z","steps":["trace[47003862] 'agreement among raft nodes before linearized reading' (duration: 317.482539ms)"],"step_count":1} {"level":"warn","ts":"2026-08-22T15:14:20.752961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:14:20.435181Z","time spent":"317.773676ms","remote":"127.0.0.1:38946","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-22T15:14:20.918117Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"326.562485ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:509"} {"level":"info","ts":"2026-08-22T15:14:20.918219Z","caller":"traceutil/trace.go:171","msg":"trace[517379744] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:20523; }","duration":"326.707967ms","start":"2026-08-22T15:14:20.591487Z","end":"2026-08-22T15:14:20.918195Z","steps":["trace[517379744] 'agreement among raft nodes before linearized reading' (duration: 161.47287ms)","trace[517379744] 'range keys from in-memory index tree' (duration: 164.978773ms)"],"step_count":2} {"level":"warn","ts":"2026-08-22T15:14:20.91821Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"151.999398ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:18"} {"level":"warn","ts":"2026-08-22T15:14:20.91827Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-08-22T15:14:20.591469Z","time spent":"326.7917ms","remote":"127.0.0.1:38946","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":"info","ts":"2026-08-22T15:14:20.918385Z","caller":"traceutil/trace.go:171","msg":"trace[174305845] transaction","detail":"{read_only:false; response_revision:20525; number_of_response:1; }","duration":"158.499991ms","start":"2026-08-22T15:14:20.759862Z","end":"2026-08-22T15:14:20.918362Z","steps":["trace[174305845] 'process raft request' (duration: 158.44654ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:14:20.918623Z","caller":"traceutil/trace.go:171","msg":"trace[1784265665] transaction","detail":"{read_only:false; response_revision:20524; number_of_response:1; }","duration":"222.77076ms","start":"2026-08-22T15:14:20.695694Z","end":"2026-08-22T15:14:20.918465Z","steps":["trace[1784265665] 'process raft request' (duration: 70.431405ms)","trace[1784265665] 'compare' (duration: 151.883636ms)"],"step_count":2} {"level":"info","ts":"2026-08-22T15:18:09.2386Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":20134} {"level":"info","ts":"2026-08-22T15:18:09.355192Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":20134,"took":"113.145654ms","hash":1119736911,"current-db-size-bytes":59133952,"current-db-size":"59 MB","current-db-size-in-use-bytes":40271872,"current-db-size-in-use":"40 MB"} {"level":"info","ts":"2026-08-22T15:18:09.355239Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1119736911,"revision":20134,"compact-revision":17672} {"level":"info","ts":"2026-08-22T15:18:17.246897Z","caller":"traceutil/trace.go:171","msg":"trace[1119131117] transaction","detail":"{read_only:false; response_revision:22975; number_of_response:1; }","duration":"101.480787ms","start":"2026-08-22T15:18:17.14539Z","end":"2026-08-22T15:18:17.246871Z","steps":["trace[1119131117] 'process raft request' (duration: 101.12684ms)"],"step_count":1} {"level":"info","ts":"2026-08-22T15:23:09.249282Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":22791} {"level":"info","ts":"2026-08-22T15:23:09.369959Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":22791,"took":"117.984219ms","hash":4086821386,"current-db-size-bytes":59133952,"current-db-size":"59 MB","current-db-size-in-use-bytes":34459648,"current-db-size-in-use":"34 MB"} {"level":"info","ts":"2026-08-22T15:23:09.37001Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4086821386,"revision":22791,"compact-revision":20134}