{"level":"warn","ts":"2026-07-30T18:56:26.844383Z","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-30T18:56:26.844459Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://199.204.45.116: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.116:2380","--initial-cluster=instance=https://199.204.45.116:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://199.204.45.116:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://199.204.45.116: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-30T18:56:26.844518Z","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-30T18:56:26.844529Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-07-30T18:56:26.844549Z","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-30T18:56:26.845125Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"]} {"level":"info","ts":"2026-07-30T18:56:26.845191Z","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.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://199.204.45.116: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-30T18:56:26.849243Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"3.848533ms"} {"level":"info","ts":"2026-07-30T18:56:26.859944Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"5caa70ef1dd6dc04","cluster-id":"45afccab143d2c82"} {"level":"info","ts":"2026-07-30T18:56:26.860017Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=()"} {"level":"info","ts":"2026-07-30T18:56:26.860039Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 0"} {"level":"info","ts":"2026-07-30T18:56:26.860054Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft 5caa70ef1dd6dc04 [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-07-30T18:56:26.860065Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became follower at term 1"} {"level":"info","ts":"2026-07-30T18:56:26.860108Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"warn","ts":"2026-07-30T18:56:26.870509Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-07-30T18:56:26.871946Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-07-30T18:56:26.873026Z","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-30T18:56:26.875206Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"5caa70ef1dd6dc04","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-07-30T18:56:26.875384Z","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-30T18:56:26.875429Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"5caa70ef1dd6dc04","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-07-30T18:56:26.875527Z","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-30T18:56:26.875544Z","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-30T18:56:26.881496Z","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-30T18:56:26.881657Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-07-30T18:56:26.881689Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"199.204.45.116:2380"} {"level":"info","ts":"2026-07-30T18:56:26.881792Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"5caa70ef1dd6dc04","initial-advertise-peer-urls":["https://199.204.45.116:2380"],"listen-peer-urls":["https://199.204.45.116:2380"],"advertise-client-urls":["https://199.204.45.116:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://199.204.45.116:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-07-30T18:56:26.881851Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-07-30T18:56:26.883282Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 switched to configuration voters=(6677273569830296580)"} {"level":"info","ts":"2026-07-30T18:56:26.883392Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","added-peer-id":"5caa70ef1dd6dc04","added-peer-peer-urls":["https://199.204.45.116:2380"]} {"level":"info","ts":"2026-07-30T18:56:27.561269Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 is starting a new election at term 1"} {"level":"info","ts":"2026-07-30T18:56:27.561343Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became pre-candidate at term 1"} {"level":"info","ts":"2026-07-30T18:56:27.561385Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgPreVoteResp from 5caa70ef1dd6dc04 at term 1"} {"level":"info","ts":"2026-07-30T18:56:27.561405Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became candidate at term 2"} {"level":"info","ts":"2026-07-30T18:56:27.561417Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 received MsgVoteResp from 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-07-30T18:56:27.561438Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"5caa70ef1dd6dc04 became leader at term 2"} {"level":"info","ts":"2026-07-30T18:56:27.561458Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: 5caa70ef1dd6dc04 elected leader 5caa70ef1dd6dc04 at term 2"} {"level":"info","ts":"2026-07-30T18:56:27.562417Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T18:56:27.563487Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"5caa70ef1dd6dc04","local-member-attributes":"{Name:instance ClientURLs:[https://199.204.45.116:2379]}","request-path":"/0/members/5caa70ef1dd6dc04/attributes","cluster-id":"45afccab143d2c82","publish-timeout":"7s"} {"level":"info","ts":"2026-07-30T18:56:27.56363Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T18:56:27.563736Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-07-30T18:56:27.564154Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"45afccab143d2c82","local-member-id":"5caa70ef1dd6dc04","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T18:56:27.564299Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T18:56:27.56434Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-07-30T18:56:27.565812Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"199.204.45.116:2379"} {"level":"info","ts":"2026-07-30T18:56:27.565967Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-07-30T18:56:27.566081Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"info","ts":"2026-07-30T18:56:27.568016Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-07-30T18:56:29.089532Z","caller":"traceutil/trace.go:171","msg":"trace[1474967398] transaction","detail":"{read_only:false; response_revision:3; number_of_response:1; }","duration":"195.444184ms","start":"2026-07-30T18:56:28.89405Z","end":"2026-07-30T18:56:29.089495Z","steps":["trace[1474967398] 'process raft request' (duration: 194.395024ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:29.089556Z","caller":"traceutil/trace.go:171","msg":"trace[398078570] linearizableReadLoop","detail":"{readStateIndex:6; appliedIndex:4; }","duration":"183.899945ms","start":"2026-07-30T18:56:28.905579Z","end":"2026-07-30T18:56:29.089479Z","steps":["trace[398078570] 'read index received' (duration: 65.499502ms)","trace[398078570] 'applied index is now lower than readState.Index' (duration: 118.399153ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T18:56:29.089745Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"184.14378ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/csinodes/instance\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-30T18:56:29.089873Z","caller":"traceutil/trace.go:171","msg":"trace[2135465260] range","detail":"{range_begin:/registry/csinodes/instance; range_end:; response_count:0; response_revision:3; }","duration":"184.301802ms","start":"2026-07-30T18:56:28.905543Z","end":"2026-07-30T18:56:29.089845Z","steps":["trace[2135465260] 'agreement among raft nodes before linearized reading' (duration: 184.040957ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:29.092711Z","caller":"traceutil/trace.go:171","msg":"trace[2055699044] transaction","detail":"{read_only:false; response_revision:4; number_of_response:1; }","duration":"117.124429ms","start":"2026-07-30T18:56:28.975562Z","end":"2026-07-30T18:56:29.092687Z","steps":["trace[2055699044] 'process raft request' (duration: 116.936675ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:29.092822Z","caller":"traceutil/trace.go:171","msg":"trace[837185277] transaction","detail":"{read_only:false; response_revision:7; number_of_response:1; }","duration":"113.961328ms","start":"2026-07-30T18:56:28.97884Z","end":"2026-07-30T18:56:29.092802Z","steps":["trace[837185277] 'process raft request' (duration: 113.858906ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:29.092905Z","caller":"traceutil/trace.go:171","msg":"trace[546014556] transaction","detail":"{read_only:false; response_revision:5; number_of_response:1; }","duration":"115.429276ms","start":"2026-07-30T18:56:28.977432Z","end":"2026-07-30T18:56:29.092862Z","steps":["trace[546014556] 'process raft request' (duration: 115.193742ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:29.09287Z","caller":"traceutil/trace.go:171","msg":"trace[1822504722] transaction","detail":"{read_only:false; response_revision:6; number_of_response:1; }","duration":"115.475757ms","start":"2026-07-30T18:56:28.977333Z","end":"2026-07-30T18:56:29.092809Z","steps":["trace[1822504722] 'process raft request' (duration: 115.331705ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:29.093003Z","caller":"traceutil/trace.go:171","msg":"trace[589450871] transaction","detail":"{read_only:false; response_revision:8; number_of_response:1; }","duration":"113.796416ms","start":"2026-07-30T18:56:28.979145Z","end":"2026-07-30T18:56:29.092942Z","steps":["trace[589450871] 'process raft request' (duration: 113.582292ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:29.093073Z","caller":"traceutil/trace.go:171","msg":"trace[1528264230] transaction","detail":"{read_only:false; response_revision:9; number_of_response:1; }","duration":"113.811486ms","start":"2026-07-30T18:56:28.979249Z","end":"2026-07-30T18:56:29.093061Z","steps":["trace[1528264230] 'process raft request' (duration: 113.51595ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:29.093179Z","caller":"traceutil/trace.go:171","msg":"trace[1432201004] transaction","detail":"{read_only:false; response_revision:10; number_of_response:1; }","duration":"103.892219ms","start":"2026-07-30T18:56:28.989259Z","end":"2026-07-30T18:56:29.093151Z","steps":["trace[1432201004] 'process raft request' (duration: 103.749386ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:56:29.09326Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.028953ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-30T18:56:29.093305Z","caller":"traceutil/trace.go:171","msg":"trace[1883134238] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"149.144475ms","start":"2026-07-30T18:56:28.944147Z","end":"2026-07-30T18:56:29.093291Z","steps":["trace[1883134238] 'agreement among raft nodes before linearized reading' (duration: 149.021053ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:56:29.093302Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.986884ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/199.204.45.116\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-30T18:56:29.093347Z","caller":"traceutil/trace.go:171","msg":"trace[1052819715] range","detail":"{range_begin:/registry/masterleases/199.204.45.116; range_end:; response_count:0; response_revision:11; }","duration":"119.094796ms","start":"2026-07-30T18:56:28.974238Z","end":"2026-07-30T18:56:29.093333Z","steps":["trace[1052819715] 'agreement among raft nodes before linearized reading' (duration: 118.935833ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:56:29.093332Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"118.652997ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-30T18:56:29.09339Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"148.654777ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"warn","ts":"2026-07-30T18:56:29.093266Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"149.276398ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/namespaces/kube-system\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-30T18:56:29.093434Z","caller":"traceutil/trace.go:171","msg":"trace[1584876961] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"148.709118ms","start":"2026-07-30T18:56:28.944711Z","end":"2026-07-30T18:56:29.09342Z","steps":["trace[1584876961] 'agreement among raft nodes before linearized reading' (duration: 148.633616ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:29.093431Z","caller":"traceutil/trace.go:171","msg":"trace[1927553380] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"118.79176ms","start":"2026-07-30T18:56:28.974611Z","end":"2026-07-30T18:56:29.093402Z","steps":["trace[1927553380] 'agreement among raft nodes before linearized reading' (duration: 118.540666ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:29.093456Z","caller":"traceutil/trace.go:171","msg":"trace[1041549035] range","detail":"{range_begin:/registry/namespaces/kube-system; range_end:; response_count:0; response_revision:11; }","duration":"149.512282ms","start":"2026-07-30T18:56:28.943934Z","end":"2026-07-30T18:56:29.093446Z","steps":["trace[1041549035] 'agreement among raft nodes before linearized reading' (duration: 149.235507ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:56:29.093552Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"117.199821ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/kube-system/\" range_end:\"/registry/resourcequotas/kube-system0\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-07-30T18:56:29.09356Z","caller":"traceutil/trace.go:171","msg":"trace[234881488] transaction","detail":"{read_only:false; response_revision:11; number_of_response:1; }","duration":"103.886579ms","start":"2026-07-30T18:56:28.989663Z","end":"2026-07-30T18:56:29.093549Z","steps":["trace[234881488] 'process raft request' (duration: 103.408759ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:56:29.093594Z","caller":"traceutil/trace.go:171","msg":"trace[374189135] range","detail":"{range_begin:/registry/resourcequotas/kube-system/; range_end:/registry/resourcequotas/kube-system0; response_count:0; response_revision:11; }","duration":"117.277531ms","start":"2026-07-30T18:56:28.976301Z","end":"2026-07-30T18:56:29.093578Z","steps":["trace[374189135] 'agreement among raft nodes before linearized reading' (duration: 117.207769ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:11.426942Z","caller":"traceutil/trace.go:171","msg":"trace[80224434] transaction","detail":"{read_only:false; response_revision:631; number_of_response:1; }","duration":"127.758605ms","start":"2026-07-30T18:57:11.299152Z","end":"2026-07-30T18:57:11.42691Z","steps":["trace[80224434] 'process raft request' (duration: 127.545481ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:11.598737Z","caller":"traceutil/trace.go:171","msg":"trace[1242919011] transaction","detail":"{read_only:false; response_revision:632; number_of_response:1; }","duration":"243.622593ms","start":"2026-07-30T18:57:11.355084Z","end":"2026-07-30T18:57:11.598707Z","steps":["trace[1242919011] 'process raft request' (duration: 237.470104ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:57:12.08507Z","caller":"traceutil/trace.go:171","msg":"trace[1517299355] transaction","detail":"{read_only:false; response_revision:635; number_of_response:1; }","duration":"425.546911ms","start":"2026-07-30T18:57:11.659345Z","end":"2026-07-30T18:57:12.084892Z","steps":["trace[1517299355] 'process raft request' (duration: 425.377627ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:12.085616Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:11.659325Z","time spent":"425.821385ms","remote":"127.0.0.1:55946","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1627,"response count":0,"response size":38,"request content":"compare: success:> failure:<>"} {"level":"info","ts":"2026-07-30T18:57:12.093716Z","caller":"traceutil/trace.go:171","msg":"trace[2303442] transaction","detail":"{read_only:false; response_revision:636; number_of_response:1; }","duration":"432.230039ms","start":"2026-07-30T18:57:11.661467Z","end":"2026-07-30T18:57:12.093697Z","steps":["trace[2303442] 'process raft request' (duration: 432.126337ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:57:12.09406Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T18:57:11.661449Z","time spent":"432.3165ms","remote":"127.0.0.1:55648","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":17134,"response count":0,"response size":38,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-07-30T18:57:27.000373Z","caller":"traceutil/trace.go:171","msg":"trace[1619243586] transaction","detail":"{read_only:false; response_revision:794; number_of_response:1; }","duration":"249.132318ms","start":"2026-07-30T18:57:26.751214Z","end":"2026-07-30T18:57:27.000346Z","steps":["trace[1619243586] 'process raft request' (duration: 248.950595ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:31.622065Z","caller":"traceutil/trace.go:171","msg":"trace[1533008216] linearizableReadLoop","detail":"{readStateIndex:1401; appliedIndex:1400; }","duration":"189.174003ms","start":"2026-07-30T18:58:31.432865Z","end":"2026-07-30T18:58:31.622039Z","steps":["trace[1533008216] 'read index received' (duration: 188.983069ms)","trace[1533008216] 'applied index is now lower than readState.Index' (duration: 189.804µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T18:58:31.622219Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"189.338356ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/podtemplates/\" range_end:\"/registry/podtemplates0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T18:58:31.622252Z","caller":"traceutil/trace.go:171","msg":"trace[1168215995] range","detail":"{range_begin:/registry/podtemplates/; range_end:/registry/podtemplates0; response_count:0; response_revision:1357; }","duration":"189.430978ms","start":"2026-07-30T18:58:31.432811Z","end":"2026-07-30T18:58:31.622242Z","steps":["trace[1168215995] 'agreement among raft nodes before linearized reading' (duration: 189.351176ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:31.622275Z","caller":"traceutil/trace.go:171","msg":"trace[765614125] transaction","detail":"{read_only:false; response_revision:1357; number_of_response:1; }","duration":"242.157513ms","start":"2026-07-30T18:58:31.380081Z","end":"2026-07-30T18:58:31.622239Z","steps":["trace[765614125] 'process raft request' (duration: 241.785816ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:58:32.247446Z","caller":"traceutil/trace.go:171","msg":"trace[1323383978] transaction","detail":"{read_only:false; response_revision:1362; number_of_response:1; }","duration":"136.680342ms","start":"2026-07-30T18:58:32.110738Z","end":"2026-07-30T18:58:32.247418Z","steps":["trace[1323383978] 'process raft request' (duration: 136.52688ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:58:34.011083Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"153.007696ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:505"} {"level":"info","ts":"2026-07-30T18:58:34.011185Z","caller":"traceutil/trace.go:171","msg":"trace[913934010] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:1365; }","duration":"153.20414ms","start":"2026-07-30T18:58:33.857958Z","end":"2026-07-30T18:58:34.011163Z","steps":["trace[913934010] 'range keys from in-memory index tree' (duration: 152.740531ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:23.498905Z","caller":"traceutil/trace.go:171","msg":"trace[1552024065] transaction","detail":"{read_only:false; response_revision:2149; number_of_response:1; }","duration":"108.72299ms","start":"2026-07-30T18:59:23.390154Z","end":"2026-07-30T18:59:23.498877Z","steps":["trace[1552024065] 'process raft request' (duration: 108.558707ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:24.016979Z","caller":"traceutil/trace.go:171","msg":"trace[1724186189] transaction","detail":"{read_only:false; response_revision:2151; number_of_response:1; }","duration":"250.804827ms","start":"2026-07-30T18:59:23.766143Z","end":"2026-07-30T18:59:24.016948Z","steps":["trace[1724186189] 'process raft request' (duration: 181.997776ms)","trace[1724186189] 'compare' (duration: 68.450884ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:59:24.017206Z","caller":"traceutil/trace.go:171","msg":"trace[1953109422] transaction","detail":"{read_only:false; response_revision:2152; number_of_response:1; }","duration":"248.113396ms","start":"2026-07-30T18:59:23.769057Z","end":"2026-07-30T18:59:24.01717Z","steps":["trace[1953109422] 'process raft request' (duration: 247.669767ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:35.445114Z","caller":"traceutil/trace.go:171","msg":"trace[538653354] linearizableReadLoop","detail":"{readStateIndex:2332; appliedIndex:2331; }","duration":"104.475539ms","start":"2026-07-30T18:59:35.340609Z","end":"2026-07-30T18:59:35.445084Z","steps":["trace[538653354] 'read index received' (duration: 104.184944ms)","trace[538653354] 'applied index is now lower than readState.Index' (duration: 288.995µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:59:35.445184Z","caller":"traceutil/trace.go:171","msg":"trace[797773488] transaction","detail":"{read_only:false; response_revision:2251; number_of_response:1; }","duration":"177.743704ms","start":"2026-07-30T18:59:35.267395Z","end":"2026-07-30T18:59:35.445138Z","steps":["trace[797773488] 'process raft request' (duration: 177.481119ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:35.445467Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"104.844917ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/percona-xtradb-pxc-0\" ","response":"range_response_count:1 size:12233"} {"level":"info","ts":"2026-07-30T18:59:35.445604Z","caller":"traceutil/trace.go:171","msg":"trace[1452568610] range","detail":"{range_begin:/registry/pods/openstack/percona-xtradb-pxc-0; range_end:; response_count:1; response_revision:2251; }","duration":"104.949478ms","start":"2026-07-30T18:59:35.340583Z","end":"2026-07-30T18:59:35.445533Z","steps":["trace[1452568610] 'agreement among raft nodes before linearized reading' (duration: 104.609602ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:56.359296Z","caller":"traceutil/trace.go:171","msg":"trace[1902824257] linearizableReadLoop","detail":"{readStateIndex:2495; appliedIndex:2494; }","duration":"102.757723ms","start":"2026-07-30T18:59:56.256513Z","end":"2026-07-30T18:59:56.359271Z","steps":["trace[1902824257] 'read index received' (duration: 102.525959ms)","trace[1902824257] 'applied index is now lower than readState.Index' (duration: 230.604µs)"],"step_count":2} {"level":"warn","ts":"2026-07-30T18:59:56.359519Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"102.987368ms","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-30T18:59:56.359566Z","caller":"traceutil/trace.go:171","msg":"trace[1755202282] range","detail":"{range_begin:/registry/pxc.percona.com/perconaxtradbclusters/openstack/percona-xtradb; range_end:; response_count:1; response_revision:2408; }","duration":"103.05304ms","start":"2026-07-30T18:59:56.256497Z","end":"2026-07-30T18:59:56.35955Z","steps":["trace[1755202282] 'agreement among raft nodes before linearized reading' (duration: 102.884546ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T18:59:56.359749Z","caller":"traceutil/trace.go:171","msg":"trace[1724388283] transaction","detail":"{read_only:false; response_revision:2408; number_of_response:1; }","duration":"135.667132ms","start":"2026-07-30T18:59:56.223681Z","end":"2026-07-30T18:59:56.359348Z","steps":["trace[1724388283] 'process raft request' (duration: 135.425067ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:56.865798Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"196.083757ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-07-30T18:59:56.866066Z","caller":"traceutil/trace.go:171","msg":"trace[629023798] linearizableReadLoop","detail":"{readStateIndex:2501; appliedIndex:2499; }","duration":"189.97841ms","start":"2026-07-30T18:59:56.676068Z","end":"2026-07-30T18:59:56.866047Z","steps":["trace[629023798] 'read index received' (duration: 101.876566ms)","trace[629023798] 'applied index is now lower than readState.Index' (duration: 88.100914ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:59:56.866114Z","caller":"traceutil/trace.go:171","msg":"trace[1987978654] transaction","detail":"{read_only:false; response_revision:2413; number_of_response:1; }","duration":"216.084949ms","start":"2026-07-30T18:59:56.649981Z","end":"2026-07-30T18:59:56.866066Z","steps":["trace[1987978654] 'process raft request' (duration: 19.469372ms)","trace[1987978654] 'compare' (duration: 195.825032ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T18:59:56.86618Z","caller":"traceutil/trace.go:171","msg":"trace[185859868] transaction","detail":"{read_only:false; response_revision:2414; number_of_response:1; }","duration":"216.103629ms","start":"2026-07-30T18:59:56.650051Z","end":"2026-07-30T18:59:56.866155Z","steps":["trace[185859868] 'process raft request' (duration: 215.864975ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:56.866159Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"190.088042ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-07-30T18:59:56.866287Z","caller":"traceutil/trace.go:171","msg":"trace[371055011] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:2414; }","duration":"190.213805ms","start":"2026-07-30T18:59:56.67606Z","end":"2026-07-30T18:59:56.866274Z","steps":["trace[371055011] 'agreement among raft nodes before linearized reading' (duration: 190.062372ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T18:59:57.733573Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"178.406529ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-30T18:59:57.733677Z","caller":"traceutil/trace.go:171","msg":"trace[622006724] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:2417; }","duration":"178.570403ms","start":"2026-07-30T18:59:57.555081Z","end":"2026-07-30T18:59:57.733651Z","steps":["trace[622006724] 'range keys from in-memory index tree' (duration: 178.205055ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:06:27.599519Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":3026} {"level":"info","ts":"2026-07-30T19:06:27.687861Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":3026,"took":"87.193854ms","hash":1316877373,"current-db-size-bytes":35790848,"current-db-size":"36 MB","current-db-size-in-use-bytes":8798208,"current-db-size-in-use":"8.8 MB"} {"level":"info","ts":"2026-07-30T19:06:27.687923Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1316877373,"revision":3026,"compact-revision":-1} {"level":"info","ts":"2026-07-30T19:11:27.605546Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":4997} {"level":"info","ts":"2026-07-30T19:11:27.646437Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":4997,"took":"39.117977ms","hash":1034539460,"current-db-size-bytes":35790848,"current-db-size":"36 MB","current-db-size-in-use-bytes":11567104,"current-db-size-in-use":"12 MB"} {"level":"info","ts":"2026-07-30T19:11:27.64651Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":1034539460,"revision":4997,"compact-revision":3026} {"level":"info","ts":"2026-07-30T19:16:27.613318Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":7252} {"level":"info","ts":"2026-07-30T19:16:27.671021Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":7252,"took":"56.212515ms","hash":693486420,"current-db-size-bytes":35790848,"current-db-size":"36 MB","current-db-size-in-use-bytes":13148160,"current-db-size-in-use":"13 MB"} {"level":"info","ts":"2026-07-30T19:16:27.671104Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":693486420,"revision":7252,"compact-revision":4997} {"level":"info","ts":"2026-07-30T19:16:33.577232Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":10001,"local-member-snapshot-index":0,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-30T19:16:33.583458Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":10001} {"level":"info","ts":"2026-07-30T19:16:33.58364Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":5001} {"level":"warn","ts":"2026-07-30T19:21:06.574195Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"419.059818ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-30T19:21:06.574451Z","caller":"traceutil/trace.go:171","msg":"trace[1509424021] transaction","detail":"{read_only:false; response_revision:11814; number_of_response:1; }","duration":"241.569243ms","start":"2026-07-30T19:21:06.332862Z","end":"2026-07-30T19:21:06.574432Z","steps":["trace[1509424021] 'process raft request' (duration: 241.440751ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:06.574481Z","caller":"traceutil/trace.go:171","msg":"trace[1231928255] linearizableReadLoop","detail":"{readStateIndex:12188; appliedIndex:12187; }","duration":"402.323759ms","start":"2026-07-30T19:21:06.172113Z","end":"2026-07-30T19:21:06.574437Z","steps":["trace[1231928255] 'read index received' (duration: 46.111µs)","trace[1231928255] 'applied index is now lower than readState.Index' (duration: 402.274858ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:21:06.574704Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"355.634916ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true ","response":"range_response_count:0 size:5"} {"level":"warn","ts":"2026-07-30T19:21:06.574747Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"402.618915ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" ","response":"range_response_count:1 size:532"} {"level":"info","ts":"2026-07-30T19:21:06.574788Z","caller":"traceutil/trace.go:171","msg":"trace[115855332] range","detail":"{range_begin:/registry/leases/kube-system/rbd.csi.ceph.com-kube-system; range_end:; response_count:1; response_revision:11814; }","duration":"402.704996ms","start":"2026-07-30T19:21:06.172071Z","end":"2026-07-30T19:21:06.574776Z","steps":["trace[115855332] 'agreement among raft nodes before linearized reading' (duration: 402.520862ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:21:06.574793Z","caller":"traceutil/trace.go:171","msg":"trace[1644640665] range","detail":"{range_begin:/registry/resourcequotas/; range_end:/registry/resourcequotas0; response_count:0; response_revision:11814; }","duration":"355.767698ms","start":"2026-07-30T19:21:06.219004Z","end":"2026-07-30T19:21:06.574772Z","steps":["trace[1644640665] 'agreement among raft nodes before linearized reading' (duration: 355.617575ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:21:06.574816Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:06.172055Z","time spent":"402.753787ms","remote":"127.0.0.1:55724","response type":"/etcdserverpb.KV/Range","request count":0,"request size":59,"response count":1,"response size":554,"request content":"key:\"/registry/leases/kube-system/rbd.csi.ceph.com-kube-system\" "} {"level":"warn","ts":"2026-07-30T19:21:06.574834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-07-30T19:21:06.218988Z","time spent":"355.835819ms","remote":"127.0.0.1:55556","response type":"/etcdserverpb.KV/Range","request count":0,"request size":56,"response count":0,"response size":27,"request content":"key:\"/registry/resourcequotas/\" range_end:\"/registry/resourcequotas0\" count_only:true "} {"level":"info","ts":"2026-07-30T19:21:27.61896Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":9652} {"level":"info","ts":"2026-07-30T19:21:27.676181Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":9652,"took":"56.149214ms","hash":4026425603,"current-db-size-bytes":35790848,"current-db-size":"36 MB","current-db-size-in-use-bytes":14954496,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-07-30T19:21:27.676261Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":4026425603,"revision":9652,"compact-revision":7252} {"level":"warn","ts":"2026-07-30T19:22:36.408778Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"143.653249ms","expected-duration":"100ms","prefix":"","request":"header: lease_revoke:","response":"size:27"} {"level":"info","ts":"2026-07-30T19:26:27.625255Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":12168} {"level":"info","ts":"2026-07-30T19:26:27.69705Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":12168,"took":"69.471914ms","hash":2151181181,"current-db-size-bytes":35790848,"current-db-size":"36 MB","current-db-size-in-use-bytes":15941632,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-07-30T19:26:27.697112Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2151181181,"revision":12168,"compact-revision":9652} {"level":"info","ts":"2026-07-30T19:31:27.635118Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":14447} {"level":"info","ts":"2026-07-30T19:31:27.712525Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":14447,"took":"75.328655ms","hash":2857548920,"current-db-size-bytes":35790848,"current-db-size":"36 MB","current-db-size-in-use-bytes":16347136,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-07-30T19:31:27.712596Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":2857548920,"revision":14447,"compact-revision":12168} {"level":"warn","ts":"2026-07-30T19:35:43.493787Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"200.994385ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/pods/openstack/nova-cell-setup-6l26w\" ","response":"range_response_count:1 size:9713"} {"level":"info","ts":"2026-07-30T19:35:43.493895Z","caller":"traceutil/trace.go:171","msg":"trace[238665381] range","detail":"{range_begin:/registry/pods/openstack/nova-cell-setup-6l26w; range_end:; response_count:1; response_revision:18739; }","duration":"201.169319ms","start":"2026-07-30T19:35:43.2927Z","end":"2026-07-30T19:35:43.493869Z","steps":["trace[238665381] 'range keys from in-memory index tree' (duration: 200.774481ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:35:43.493937Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"125.8903ms","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-07-30T19:35:43.494052Z","caller":"traceutil/trace.go:171","msg":"trace[1101632430] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:18739; }","duration":"126.398889ms","start":"2026-07-30T19:35:43.36763Z","end":"2026-07-30T19:35:43.494029Z","steps":["trace[1101632430] 'range keys from in-memory index tree' (duration: 125.696897ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:35:51.048674Z","caller":"traceutil/trace.go:171","msg":"trace[675491970] linearizableReadLoop","detail":"{readStateIndex:19377; appliedIndex:19376; }","duration":"112.640462ms","start":"2026-07-30T19:35:50.936007Z","end":"2026-07-30T19:35:51.048648Z","steps":["trace[675491970] 'read index received' (duration: 112.56815ms)","trace[675491970] 'applied index is now lower than readState.Index' (duration: 70.902µs)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:35:51.048747Z","caller":"traceutil/trace.go:171","msg":"trace[192962001] transaction","detail":"{read_only:false; response_revision:18792; number_of_response:1; }","duration":"184.788009ms","start":"2026-07-30T19:35:50.863907Z","end":"2026-07-30T19:35:51.048695Z","steps":["trace[192962001] 'process raft request' (duration: 184.520853ms)"],"step_count":1} {"level":"warn","ts":"2026-07-30T19:35:51.048922Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.897236ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com\" ","response":"range_response_count:1 size:538"} {"level":"info","ts":"2026-07-30T19:35:51.048993Z","caller":"traceutil/trace.go:171","msg":"trace[1480834311] range","detail":"{range_begin:/registry/leases/kube-system/external-snapshotter-leader-rbd-csi-ceph-com; range_end:; response_count:1; response_revision:18792; }","duration":"113.010479ms","start":"2026-07-30T19:35:50.935962Z","end":"2026-07-30T19:35:51.048972Z","steps":["trace[1480834311] 'agreement among raft nodes before linearized reading' (duration: 112.778794ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:35:51.08661Z","caller":"traceutil/trace.go:171","msg":"trace[176507039] transaction","detail":"{read_only:false; response_revision:18793; number_of_response:1; }","duration":"105.983596ms","start":"2026-07-30T19:35:50.980584Z","end":"2026-07-30T19:35:51.086568Z","steps":["trace[176507039] 'process raft request' (duration: 105.65267ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:35:51.883902Z","caller":"traceutil/trace.go:171","msg":"trace[1866839085] transaction","detail":"{read_only:false; response_revision:18800; number_of_response:1; }","duration":"201.769199ms","start":"2026-07-30T19:35:51.682098Z","end":"2026-07-30T19:35:51.883867Z","steps":["trace[1866839085] 'process raft request' (duration: 199.217361ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:35:51.883981Z","caller":"traceutil/trace.go:171","msg":"trace[1036903639] linearizableReadLoop","detail":"{readStateIndex:19385; appliedIndex:19384; }","duration":"198.977827ms","start":"2026-07-30T19:35:51.684966Z","end":"2026-07-30T19:35:51.883943Z","steps":["trace[1036903639] 'read index received' (duration: 196.370827ms)","trace[1036903639] 'applied index is now lower than readState.Index' (duration: 2.603469ms)"],"step_count":2} {"level":"warn","ts":"2026-07-30T19:35:51.884252Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"199.261052ms","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-07-30T19:35:51.884323Z","caller":"traceutil/trace.go:171","msg":"trace[339028179] range","detail":"{range_begin:/registry/leases/kube-system/cilium-operator-resource-lock; range_end:; response_count:1; response_revision:18801; }","duration":"199.355953ms","start":"2026-07-30T19:35:51.684953Z","end":"2026-07-30T19:35:51.884309Z","steps":["trace[339028179] 'agreement among raft nodes before linearized reading' (duration: 199.083318ms)"],"step_count":1} {"level":"info","ts":"2026-07-30T19:35:52.137005Z","caller":"traceutil/trace.go:171","msg":"trace[399197554] transaction","detail":"{read_only:false; response_revision:18802; number_of_response:1; }","duration":"248.58382ms","start":"2026-07-30T19:35:51.888386Z","end":"2026-07-30T19:35:52.13697Z","steps":["trace[399197554] 'process raft request' (duration: 207.768063ms)","trace[399197554] 'compare' (duration: 38.760668ms)"],"step_count":2} {"level":"info","ts":"2026-07-30T19:36:27.6418Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":16890} {"level":"info","ts":"2026-07-30T19:36:27.716077Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":16890,"took":"72.267021ms","hash":3837290744,"current-db-size-bytes":35790848,"current-db-size":"36 MB","current-db-size-in-use-bytes":16183296,"current-db-size-in-use":"16 MB"} {"level":"info","ts":"2026-07-30T19:36:27.716156Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3837290744,"revision":16890,"compact-revision":14447} {"level":"info","ts":"2026-07-30T19:37:12.525114Z","caller":"etcdserver/server.go:1401","msg":"triggering snapshot","local-member-id":"5caa70ef1dd6dc04","local-member-applied-index":20002,"local-member-snapshot-index":10001,"local-member-snapshot-count":10000} {"level":"info","ts":"2026-07-30T19:37:12.53378Z","caller":"etcdserver/server.go:2420","msg":"saved snapshot","snapshot-index":20002} {"level":"info","ts":"2026-07-30T19:37:12.53395Z","caller":"etcdserver/server.go:2450","msg":"compacted Raft logs","compact-index":15002} {"level":"info","ts":"2026-07-30T19:41:27.649361Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":19101} {"level":"info","ts":"2026-07-30T19:41:27.718244Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":19101,"took":"67.369696ms","hash":830239385,"current-db-size-bytes":35790848,"current-db-size":"36 MB","current-db-size-in-use-bytes":14778368,"current-db-size-in-use":"15 MB"} {"level":"info","ts":"2026-07-30T19:41:27.718312Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":830239385,"revision":19101,"compact-revision":16890} {"level":"info","ts":"2026-07-30T19:46:27.655771Z","caller":"mvcc/index.go:214","msg":"compact tree index","revision":21084} {"level":"info","ts":"2026-07-30T19:46:27.72776Z","caller":"mvcc/kvstore_compaction.go:68","msg":"finished scheduled compaction","compact-revision":21084,"took":"70.328038ms","hash":3692521045,"current-db-size-bytes":35790848,"current-db-size":"36 MB","current-db-size-in-use-bytes":14200832,"current-db-size-in-use":"14 MB"} {"level":"info","ts":"2026-07-30T19:46:27.727847Z","caller":"mvcc/hash.go:137","msg":"storing new hash","hash":3692521045,"revision":21084,"compact-revision":19101}