{"level":"warn","ts":"2026-09-29T15:35:02.319961Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-29T15:35:02.320103Z","caller":"etcdmain/etcd.go:73","msg":"Running: ","args":["etcd","--advertise-client-urls=https://162.253.55.27: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://162.253.55.27:2380","--initial-cluster=instance=https://162.253.55.27:2380","--key-file=/etc/kubernetes/pki/etcd/server.key","--listen-client-urls=https://127.0.0.1:2379,https://162.253.55.27:2379","--listen-metrics-urls=http://127.0.0.1:2381","--listen-peer-urls=https://162.253.55.27: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-09-29T15:35:02.320201Z","caller":"embed/config.go:679","msg":"Running http and grpc server on single port. This is not recommended for production."} {"level":"info","ts":"2026-09-29T15:35:02.320232Z","caller":"embed/etcd.go:127","msg":"configuring peer listeners","listen-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-09-29T15:35:02.32027Z","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-09-29T15:35:02.320996Z","caller":"embed/etcd.go:135","msg":"configuring client listeners","listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"]} {"level":"info","ts":"2026-09-29T15:35:02.321129Z","caller":"embed/etcd.go:308","msg":"starting an etcd server","etcd-version":"3.5.12","git-sha":"e7b3bb6cc","go-version":"go1.20.13","go-os":"linux","go-arch":"amd64","max-cpu-set":8,"max-cpu-available":8,"member-initialized":false,"name":"instance","data-dir":"/var/lib/etcd","wal-dir":"","wal-dir-dedicated":"","member-dir":"/var/lib/etcd/member","force-new-cluster":false,"heartbeat-interval":"100ms","election-timeout":"1s","initial-election-tick-advance":true,"snapshot-count":10000,"max-wals":5,"max-snapshots":5,"snapshot-catchup-entries":5000,"initial-advertise-peer-urls":["https://162.253.55.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"],"cors":["*"],"host-whitelist":["*"],"initial-cluster":"instance=https://162.253.55.27: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-09-29T15:35:02.325818Z","caller":"etcdserver/backend.go:81","msg":"opened backend db","path":"/var/lib/etcd/member/snap/db","took":"4.442725ms"} {"level":"info","ts":"2026-09-29T15:35:02.332782Z","caller":"etcdserver/raft.go:495","msg":"starting local member","local-member-id":"d9b565cb482924cf","cluster-id":"5bc3f8d4cf836b6c"} {"level":"info","ts":"2026-09-29T15:35:02.332879Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=()"} {"level":"info","ts":"2026-09-29T15:35:02.3329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 0"} {"level":"info","ts":"2026-09-29T15:35:02.332911Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"newRaft d9b565cb482924cf [peers: [], term: 0, commit: 0, applied: 0, lastindex: 0, lastterm: 0]"} {"level":"info","ts":"2026-09-29T15:35:02.332923Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became follower at term 1"} {"level":"info","ts":"2026-09-29T15:35:02.332956Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"warn","ts":"2026-09-29T15:35:02.344772Z","caller":"auth/store.go:1241","msg":"simple token is not cryptographically signed"} {"level":"info","ts":"2026-09-29T15:35:02.346686Z","caller":"mvcc/kvstore.go:407","msg":"kvstore restored","current-rev":1} {"level":"info","ts":"2026-09-29T15:35:02.348767Z","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-09-29T15:35:02.351778Z","caller":"etcdserver/server.go:860","msg":"starting etcd server","local-member-id":"d9b565cb482924cf","local-server-version":"3.5.12","cluster-version":"to_be_decided"} {"level":"info","ts":"2026-09-29T15:35:02.356746Z","caller":"etcdserver/server.go:744","msg":"started as single-node; fast-forwarding election ticks","local-member-id":"d9b565cb482924cf","forward-ticks":9,"forward-duration":"900ms","election-ticks":10,"election-timeout":"1s"} {"level":"info","ts":"2026-09-29T15:35:02.357062Z","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-09-29T15:35:02.357205Z","caller":"embed/etcd.go:277","msg":"now serving peer/client/metrics","local-member-id":"d9b565cb482924cf","initial-advertise-peer-urls":["https://162.253.55.27:2380"],"listen-peer-urls":["https://162.253.55.27:2380"],"advertise-client-urls":["https://162.253.55.27:2379"],"listen-client-urls":["https://127.0.0.1:2379","https://162.253.55.27:2379"],"listen-metrics-urls":["http://127.0.0.1:2381"]} {"level":"info","ts":"2026-09-29T15:35:02.357229Z","caller":"embed/etcd.go:857","msg":"serving metrics","address":"http://127.0.0.1:2381"} {"level":"info","ts":"2026-09-29T15:35:02.357264Z","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-09-29T15:35:02.357296Z","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-09-29T15:35:02.357306Z","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-09-29T15:35:02.357417Z","caller":"embed/etcd.go:597","msg":"serving peer traffic","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-09-29T15:35:02.357428Z","caller":"embed/etcd.go:569","msg":"cmux::serve","address":"162.253.55.27:2380"} {"level":"info","ts":"2026-09-29T15:35:02.35762Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf switched to configuration voters=(15687556800778413263)"} {"level":"info","ts":"2026-09-29T15:35:02.357757Z","caller":"membership/cluster.go:421","msg":"added member","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","added-peer-id":"d9b565cb482924cf","added-peer-peer-urls":["https://162.253.55.27:2380"]} {"level":"info","ts":"2026-09-29T15:35:02.534188Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf is starting a new election at term 1"} {"level":"info","ts":"2026-09-29T15:35:02.534299Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became pre-candidate at term 1"} {"level":"info","ts":"2026-09-29T15:35:02.534329Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgPreVoteResp from d9b565cb482924cf at term 1"} {"level":"info","ts":"2026-09-29T15:35:02.53444Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became candidate at term 2"} {"level":"info","ts":"2026-09-29T15:35:02.534515Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf received MsgVoteResp from d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-09-29T15:35:02.534533Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"d9b565cb482924cf became leader at term 2"} {"level":"info","ts":"2026-09-29T15:35:02.534607Z","logger":"raft","caller":"etcdserver/zap_raft.go:77","msg":"raft.node: d9b565cb482924cf elected leader d9b565cb482924cf at term 2"} {"level":"info","ts":"2026-09-29T15:35:02.53581Z","caller":"etcdserver/server.go:2068","msg":"published local member to cluster through raft","local-member-id":"d9b565cb482924cf","local-member-attributes":"{Name:instance ClientURLs:[https://162.253.55.27:2379]}","request-path":"/0/members/d9b565cb482924cf/attributes","cluster-id":"5bc3f8d4cf836b6c","publish-timeout":"7s"} {"level":"info","ts":"2026-09-29T15:35:02.535936Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-29T15:35:02.536038Z","caller":"embed/serve.go:103","msg":"ready to serve client requests"} {"level":"info","ts":"2026-09-29T15:35:02.539206Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"127.0.0.1:2379"} {"level":"info","ts":"2026-09-29T15:35:02.544676Z","caller":"etcdserver/server.go:2578","msg":"setting up initial cluster version using v2 API","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T15:35:02.547375Z","caller":"embed/serve.go:250","msg":"serving client traffic securely","traffic":"grpc+http","address":"162.253.55.27:2379"} {"level":"info","ts":"2026-09-29T15:35:02.54907Z","caller":"membership/cluster.go:584","msg":"set initial cluster version","cluster-id":"5bc3f8d4cf836b6c","local-member-id":"d9b565cb482924cf","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T15:35:02.549285Z","caller":"api/capability.go:75","msg":"enabled capabilities for version","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T15:35:02.549432Z","caller":"etcdserver/server.go:2602","msg":"cluster version is updated","cluster-version":"3.5"} {"level":"info","ts":"2026-09-29T15:35:02.549267Z","caller":"etcdmain/main.go:44","msg":"notifying init daemon"} {"level":"info","ts":"2026-09-29T15:35:02.549575Z","caller":"etcdmain/main.go:50","msg":"successfully notified init daemon"} {"level":"warn","ts":"2026-09-29T15:35:05.618627Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"112.456365ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-29T15:35:05.61878Z","caller":"traceutil/trace.go:171","msg":"trace[624689124] linearizableReadLoop","detail":"{readStateIndex:81; appliedIndex:80; }","duration":"180.859144ms","start":"2026-09-29T15:35:05.437898Z","end":"2026-09-29T15:35:05.618758Z","steps":["trace[624689124] 'read index received' (duration: 67.533306ms)","trace[624689124] 'applied index is now lower than readState.Index' (duration: 113.323888ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:35:05.61896Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"181.048979ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:4"} {"level":"info","ts":"2026-09-29T15:35:05.619001Z","caller":"traceutil/trace.go:171","msg":"trace[850315806] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:77; }","duration":"181.09427ms","start":"2026-09-29T15:35:05.437895Z","end":"2026-09-29T15:35:05.618989Z","steps":["trace[850315806] 'agreement among raft nodes before linearized reading' (duration: 180.943936ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:35:05.619267Z","caller":"traceutil/trace.go:171","msg":"trace[239776697] transaction","detail":"{read_only:false; response_revision:77; number_of_response:1; }","duration":"195.946746ms","start":"2026-09-29T15:35:05.423234Z","end":"2026-09-29T15:35:05.619181Z","steps":["trace[239776697] 'process raft request' (duration: 82.195687ms)","trace[239776697] 'compare' (duration: 112.25642ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:35:05.885553Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"142.563977ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:14"} {"level":"info","ts":"2026-09-29T15:35:05.885729Z","caller":"traceutil/trace.go:171","msg":"trace[117458237] transaction","detail":"{read_only:false; response_revision:79; number_of_response:1; }","duration":"188.611144ms","start":"2026-09-29T15:35:05.697098Z","end":"2026-09-29T15:35:05.885709Z","steps":["trace[117458237] 'process raft request' (duration: 45.816831ms)","trace[117458237] 'compare' (duration: 142.398143ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:35:11.329098Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"141.779793ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure:<>>","response":"size:16"} {"level":"info","ts":"2026-09-29T15:35:11.329221Z","caller":"traceutil/trace.go:171","msg":"trace[1077337406] linearizableReadLoop","detail":"{readStateIndex:308; appliedIndex:307; }","duration":"241.4409ms","start":"2026-09-29T15:35:11.087763Z","end":"2026-09-29T15:35:11.329204Z","steps":["trace[1077337406] 'read index received' (duration: 99.360849ms)","trace[1077337406] 'applied index is now lower than readState.Index' (duration: 142.078901ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:35:11.329313Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"241.554882ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-controller-manager\" ","response":"range_response_count:1 size:504"} {"level":"info","ts":"2026-09-29T15:35:11.329352Z","caller":"traceutil/trace.go:171","msg":"trace[100917009] range","detail":"{range_begin:/registry/leases/kube-system/kube-controller-manager; range_end:; response_count:1; response_revision:296; }","duration":"241.639485ms","start":"2026-09-29T15:35:11.087695Z","end":"2026-09-29T15:35:11.329335Z","steps":["trace[100917009] 'agreement among raft nodes before linearized reading' (duration: 241.550183ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:35:11.329323Z","caller":"traceutil/trace.go:171","msg":"trace[764457961] transaction","detail":"{read_only:false; response_revision:296; number_of_response:1; }","duration":"315.056741ms","start":"2026-09-29T15:35:11.014243Z","end":"2026-09-29T15:35:11.3293Z","steps":["trace[764457961] 'process raft request' (duration: 172.97928ms)","trace[764457961] 'compare' (duration: 141.63874ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:35:11.329426Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:35:11.014218Z","time spent":"315.173674ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":199,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-29T15:35:11.783104Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"230.704682ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T15:35:11.78321Z","caller":"traceutil/trace.go:171","msg":"trace[1652071249] linearizableReadLoop","detail":"{readStateIndex:309; appliedIndex:308; }","duration":"446.976071ms","start":"2026-09-29T15:35:11.336217Z","end":"2026-09-29T15:35:11.783193Z","steps":["trace[1652071249] 'read index received' (duration: 215.834178ms)","trace[1652071249] 'applied index is now lower than readState.Index' (duration: 231.140463ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:35:11.783258Z","caller":"traceutil/trace.go:171","msg":"trace[106045537] transaction","detail":"{read_only:false; response_revision:297; number_of_response:1; }","duration":"449.570857ms","start":"2026-09-29T15:35:11.333639Z","end":"2026-09-29T15:35:11.78321Z","steps":["trace[106045537] 'process raft request' (duration: 218.514156ms)","trace[106045537] 'compare' (duration: 230.554749ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:35:11.783322Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"447.112154ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" ","response":"range_response_count:1 size:214"} {"level":"info","ts":"2026-09-29T15:35:11.783361Z","caller":"traceutil/trace.go:171","msg":"trace[775486124] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/resourcequota-controller; range_end:; response_count:1; response_revision:297; }","duration":"447.189366ms","start":"2026-09-29T15:35:11.336161Z","end":"2026-09-29T15:35:11.78334Z","steps":["trace[775486124] 'agreement among raft nodes before linearized reading' (duration: 447.119984ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:35:11.7834Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:35:11.336142Z","time spent":"447.249718ms","remote":"127.0.0.1:59654","response type":"/etcdserverpb.KV/Range","request count":0,"request size":64,"response count":1,"response size":237,"request content":"key:\"/registry/serviceaccounts/kube-system/resourcequota-controller\" "} {"level":"warn","ts":"2026-09-29T15:35:11.78343Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:35:11.333606Z","time spent":"449.731412ms","remote":"127.0.0.1:59712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":489,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T15:35:11.963993Z","caller":"traceutil/trace.go:171","msg":"trace[876460803] linearizableReadLoop","detail":"{readStateIndex:310; appliedIndex:309; }","duration":"163.501205ms","start":"2026-09-29T15:35:11.800461Z","end":"2026-09-29T15:35:11.963962Z","steps":["trace[876460803] 'read index received' (duration: 163.2921ms)","trace[876460803] 'applied index is now lower than readState.Index' (duration: 208.005µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:35:11.964347Z","caller":"traceutil/trace.go:171","msg":"trace[1867346100] transaction","detail":"{read_only:false; response_revision:298; number_of_response:1; }","duration":"176.000689ms","start":"2026-09-29T15:35:11.788319Z","end":"2026-09-29T15:35:11.964319Z","steps":["trace[1867346100] 'process raft request' (duration: 175.512897ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:35:11.964464Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"163.907865ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/serviceaccounts/kube-system/certificate-controller\" ","response":"range_response_count:1 size:209"} {"level":"warn","ts":"2026-09-29T15:35:11.964556Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"133.001867ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-cp-lock\" ","response":"range_response_count:1 size:434"} {"level":"info","ts":"2026-09-29T15:35:11.964755Z","caller":"traceutil/trace.go:171","msg":"trace[1939968263] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:298; }","duration":"133.224133ms","start":"2026-09-29T15:35:11.831514Z","end":"2026-09-29T15:35:11.964738Z","steps":["trace[1939968263] 'agreement among raft nodes before linearized reading' (duration: 132.829673ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:35:11.964659Z","caller":"traceutil/trace.go:171","msg":"trace[1016776271] range","detail":"{range_begin:/registry/serviceaccounts/kube-system/certificate-controller; range_end:; response_count:1; response_revision:298; }","duration":"164.200733ms","start":"2026-09-29T15:35:11.800436Z","end":"2026-09-29T15:35:11.964637Z","steps":["trace[1016776271] 'agreement among raft nodes before linearized reading' (duration: 163.883625ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:35:43.441985Z","caller":"traceutil/trace.go:171","msg":"trace[1154924766] transaction","detail":"{read_only:false; response_revision:522; number_of_response:1; }","duration":"106.499084ms","start":"2026-09-29T15:35:43.335442Z","end":"2026-09-29T15:35:43.441941Z","steps":["trace[1154924766] 'process raft request' (duration: 66.173228ms)","trace[1154924766] 'compare' (duration: 39.912015ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:35:43.442506Z","caller":"traceutil/trace.go:171","msg":"trace[779913002] transaction","detail":"{read_only:false; response_revision:523; number_of_response:1; }","duration":"106.600916ms","start":"2026-09-29T15:35:43.335744Z","end":"2026-09-29T15:35:43.442345Z","steps":["trace[779913002] 'process raft request' (duration: 106.467003ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:35:43.458888Z","caller":"traceutil/trace.go:171","msg":"trace[871579982] transaction","detail":"{read_only:false; response_revision:524; number_of_response:1; }","duration":"104.551915ms","start":"2026-09-29T15:35:43.354321Z","end":"2026-09-29T15:35:43.458873Z","steps":["trace[871579982] 'process raft request' (duration: 104.415172ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:35:49.174734Z","caller":"traceutil/trace.go:171","msg":"trace[1796735755] linearizableReadLoop","detail":"{readStateIndex:624; appliedIndex:623; }","duration":"164.47116ms","start":"2026-09-29T15:35:49.010231Z","end":"2026-09-29T15:35:49.174702Z","steps":["trace[1796735755] 'read index received' (duration: 164.353877ms)","trace[1796735755] 'applied index is now lower than readState.Index' (duration: 116.113µs)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:35:49.174846Z","caller":"traceutil/trace.go:171","msg":"trace[678268734] transaction","detail":"{read_only:false; response_revision:600; number_of_response:1; }","duration":"265.55443ms","start":"2026-09-29T15:35:48.909205Z","end":"2026-09-29T15:35:49.174759Z","steps":["trace[678268734] 'process raft request' (duration: 264.076492ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:35:49.174951Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"164.702556ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:477"} {"level":"info","ts":"2026-09-29T15:35:49.175084Z","caller":"traceutil/trace.go:171","msg":"trace[829243984] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:600; }","duration":"164.89059ms","start":"2026-09-29T15:35:49.010179Z","end":"2026-09-29T15:35:49.175069Z","steps":["trace[829243984] 'agreement among raft nodes before linearized reading' (duration: 164.639264ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:35:49.800803Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"576.342131ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T15:35:49.802385Z","caller":"traceutil/trace.go:171","msg":"trace[1379090796] transaction","detail":"{read_only:false; response_revision:601; number_of_response:1; }","duration":"605.087593ms","start":"2026-09-29T15:35:49.197233Z","end":"2026-09-29T15:35:49.802321Z","steps":["trace[1379090796] 'process raft request' (duration: 26.958947ms)","trace[1379090796] 'compare' (duration: 575.770657ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:35:49.802503Z","caller":"traceutil/trace.go:171","msg":"trace[1088637200] linearizableReadLoop","detail":"{readStateIndex:627; appliedIndex:624; }","duration":"333.148156ms","start":"2026-09-29T15:35:49.469322Z","end":"2026-09-29T15:35:49.802471Z","steps":["trace[1088637200] 'read index received' (duration: 187.138279ms)","trace[1088637200] 'applied index is now lower than readState.Index' (duration: 146.008927ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:35:49.802642Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:35:49.1972Z","time spent":"605.297868ms","remote":"127.0.0.1:59712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":463,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T15:35:49.802865Z","caller":"traceutil/trace.go:171","msg":"trace[1758118913] transaction","detail":"{read_only:false; response_revision:602; number_of_response:1; }","duration":"600.889678ms","start":"2026-09-29T15:35:49.201936Z","end":"2026-09-29T15:35:49.802826Z","steps":["trace[1758118913] 'process raft request' (duration: 600.304643ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:35:49.802683Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"333.33437ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T15:35:49.80298Z","caller":"traceutil/trace.go:171","msg":"trace[725885672] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:603; }","duration":"333.670669ms","start":"2026-09-29T15:35:49.469279Z","end":"2026-09-29T15:35:49.80295Z","steps":["trace[725885672] 'agreement among raft nodes before linearized reading' (duration: 333.30451ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:35:49.80306Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:35:49.46926Z","time spent":"333.779371ms","remote":"127.0.0.1:59480","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-29T15:35:49.802797Z","caller":"traceutil/trace.go:171","msg":"trace[536427643] transaction","detail":"{read_only:false; response_revision:603; number_of_response:1; }","duration":"556.230166ms","start":"2026-09-29T15:35:49.246532Z","end":"2026-09-29T15:35:49.802762Z","steps":["trace[536427643] 'process raft request' (duration: 555.888178ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:35:49.803084Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:35:49.201923Z","time spent":"600.99421ms","remote":"127.0.0.1:59712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":520,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T15:35:49.80324Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:35:49.246513Z","time spent":"556.634586ms","remote":"127.0.0.1:59816","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1087,"response count":0,"response size":39,"request content":"compare: success:> failure:<>"} {"level":"warn","ts":"2026-09-29T15:35:50.699108Z","caller":"etcdserver/v3_server.go:897","msg":"waiting for ReadIndex response took too long, retrying","sent-request-id":2652515648769999382,"retry-timeout":"500ms"} {"level":"info","ts":"2026-09-29T15:35:50.96706Z","caller":"traceutil/trace.go:171","msg":"trace[2018575571] transaction","detail":"{read_only:false; response_revision:604; number_of_response:1; }","duration":"991.880942ms","start":"2026-09-29T15:35:49.975157Z","end":"2026-09-29T15:35:50.967038Z","steps":["trace[2018575571] 'process raft request' (duration: 991.756599ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:35:50.967222Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:35:49.975131Z","time spent":"992.016185ms","remote":"127.0.0.1:59712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":490,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T15:35:51.221478Z","caller":"traceutil/trace.go:171","msg":"trace[1562952344] linearizableReadLoop","detail":"{readStateIndex:629; appliedIndex:628; }","duration":"1.02300978s","start":"2026-09-29T15:35:50.198435Z","end":"2026-09-29T15:35:51.221445Z","steps":["trace[1562952344] 'read index received' (duration: 768.633729ms)","trace[1562952344] 'applied index is now lower than readState.Index' (duration: 254.374681ms)"],"step_count":2} {"level":"info","ts":"2026-09-29T15:35:51.221592Z","caller":"traceutil/trace.go:171","msg":"trace[1912959261] transaction","detail":"{read_only:false; response_revision:605; number_of_response:1; }","duration":"963.773503ms","start":"2026-09-29T15:35:50.257747Z","end":"2026-09-29T15:35:51.221521Z","steps":["trace[1912959261] 'process raft request' (duration: 962.908331ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:35:51.22181Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.023359419s","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" ","response":"range_response_count:1 size:437"} {"level":"warn","ts":"2026-09-29T15:35:51.221854Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"1.019583823s","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-09-29T15:35:51.221872Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:35:50.257697Z","time spent":"963.997698ms","remote":"127.0.0.1:59562","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":1036285,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"info","ts":"2026-09-29T15:35:51.2219Z","caller":"traceutil/trace.go:171","msg":"trace[1051666901] range","detail":"{range_begin:/registry/leases/kube-system/plndr-cp-lock; range_end:; response_count:1; response_revision:605; }","duration":"1.019680346s","start":"2026-09-29T15:35:50.202207Z","end":"2026-09-29T15:35:51.221887Z","steps":["trace[1051666901] 'agreement among raft nodes before linearized reading' (duration: 1.019454871s)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:35:51.221905Z","caller":"traceutil/trace.go:171","msg":"trace[207879777] range","detail":"{range_begin:/registry/leases/kube-system/plndr-svcs-lock; range_end:; response_count:1; response_revision:605; }","duration":"1.023505393s","start":"2026-09-29T15:35:50.198377Z","end":"2026-09-29T15:35:51.221882Z","steps":["trace[207879777] 'agreement among raft nodes before linearized reading' (duration: 1.023211896s)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:35:51.221942Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:35:50.202196Z","time spent":"1.019737307s","remote":"127.0.0.1:59712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":44,"response count":1,"response size":456,"request content":"key:\"/registry/leases/kube-system/plndr-cp-lock\" "} {"level":"warn","ts":"2026-09-29T15:35:51.221961Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:35:50.198358Z","time spent":"1.023591355s","remote":"127.0.0.1:59712","response type":"/etcdserverpb.KV/Range","request count":0,"request size":46,"response count":1,"response size":460,"request content":"key:\"/registry/leases/kube-system/plndr-svcs-lock\" "} {"level":"warn","ts":"2026-09-29T15:35:51.222183Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"752.422829ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/health\" ","response":"range_response_count:0 size:5"} {"level":"info","ts":"2026-09-29T15:35:51.222229Z","caller":"traceutil/trace.go:171","msg":"trace[1474962131] range","detail":"{range_begin:/registry/health; range_end:; response_count:0; response_revision:605; }","duration":"752.512651ms","start":"2026-09-29T15:35:50.469705Z","end":"2026-09-29T15:35:51.222218Z","steps":["trace[1474962131] 'agreement among raft nodes before linearized reading' (duration: 752.45042ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:35:51.222252Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:35:50.46968Z","time spent":"752.566883ms","remote":"127.0.0.1:59490","response type":"/etcdserverpb.KV/Range","request count":0,"request size":18,"response count":0,"response size":28,"request content":"key:\"/registry/health\" "} {"level":"info","ts":"2026-09-29T15:36:05.162962Z","caller":"traceutil/trace.go:171","msg":"trace[1715172891] transaction","detail":"{read_only:false; response_revision:757; number_of_response:1; }","duration":"117.356993ms","start":"2026-09-29T15:36:05.04557Z","end":"2026-09-29T15:36:05.162927Z","steps":["trace[1715172891] 'process raft request' (duration: 117.22837ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:36:08.168135Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"272.723585ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/leases/kube-system/kube-scheduler\" ","response":"range_response_count:1 size:479"} {"level":"info","ts":"2026-09-29T15:36:08.168236Z","caller":"traceutil/trace.go:171","msg":"trace[969996762] range","detail":"{range_begin:/registry/leases/kube-system/kube-scheduler; range_end:; response_count:1; response_revision:775; }","duration":"272.889399ms","start":"2026-09-29T15:36:07.895327Z","end":"2026-09-29T15:36:08.168216Z","steps":["trace[969996762] 'range keys from in-memory index tree' (duration: 272.467999ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:36:47.045956Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"101.056083ms","expected-duration":"100ms","prefix":"read-only range ","request":"key:\"/registry/masterleases/162.253.55.27\" ","response":"range_response_count:1 size:133"} {"level":"info","ts":"2026-09-29T15:36:47.046074Z","caller":"traceutil/trace.go:171","msg":"trace[1193289364] range","detail":"{range_begin:/registry/masterleases/162.253.55.27; range_end:; response_count:1; response_revision:1035; }","duration":"101.217807ms","start":"2026-09-29T15:36:46.94483Z","end":"2026-09-29T15:36:47.046048Z","steps":["trace[1193289364] 'range keys from in-memory index tree' (duration: 100.859328ms)"],"step_count":1} {"level":"warn","ts":"2026-09-29T15:36:48.174689Z","caller":"etcdserver/util.go:170","msg":"apply request took too long","took":"548.543599ms","expected-duration":"100ms","prefix":"","request":"header: txn: success:> failure: >>","response":"size:16"} {"level":"info","ts":"2026-09-29T15:36:48.174892Z","caller":"traceutil/trace.go:171","msg":"trace[704854277] transaction","detail":"{read_only:false; response_revision:1040; number_of_response:1; }","duration":"313.415571ms","start":"2026-09-29T15:36:47.861454Z","end":"2026-09-29T15:36:48.17487Z","steps":["trace[704854277] 'process raft request' (duration: 313.341899ms)"],"step_count":1} {"level":"info","ts":"2026-09-29T15:36:48.174972Z","caller":"traceutil/trace.go:171","msg":"trace[709873501] transaction","detail":"{read_only:false; response_revision:1039; number_of_response:1; }","duration":"596.967908ms","start":"2026-09-29T15:36:47.577986Z","end":"2026-09-29T15:36:48.174954Z","steps":["trace[709873501] 'process raft request' (duration: 48.06349ms)","trace[709873501] 'compare' (duration: 548.442736ms)"],"step_count":2} {"level":"warn","ts":"2026-09-29T15:36:48.174982Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:36:47.861431Z","time spent":"313.504123ms","remote":"127.0.0.1:59712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":535,"response count":0,"response size":39,"request content":"compare: success:> failure: >"} {"level":"warn","ts":"2026-09-29T15:36:48.175086Z","caller":"v3rpc/interceptor.go:197","msg":"request stats","start time":"2026-09-29T15:36:47.57797Z","time spent":"597.04184ms","remote":"127.0.0.1:59712","response type":"/etcdserverpb.KV/Txn","request count":1,"request size":419,"response count":0,"response size":39,"request content":"compare: success:> failure: >"}