I'm experimenting with Azure and have built a box with the 11.1 image
from the marketplace. I've tried to enable azure backup. I can start
the backup via the Azure portal but it fails consistently at 5:06
minutes. I assume this is because the timeout in the waagent.conf file
is 5 min by default. The error is about not being able to communicate
with the VM agent for snapshot status. I do have the azure-agent port
installed. The service is running. And for testing, I even have an
outbound "allow from any to any" rule in my network security group
attached to the VM's interface.
I opened a MS support case but the engineer is trying to tell me that
"this version of Linux does not support backup". Well being as he
doesn't even seem to understand that FBSD isn't Linux AND combined with
the fact that I can see what appears to be snapshot activity in the
waagent.log file, I don't believe this is the case.
Any clues as to where I should look next? I've included some
waagent.log entries below.
Thanks for your help,
Drew
Here are some excerpts from my waagent.log file:
Startup:
2018/05/04 14:47:37.419393 INFO Azure Linux Agent Version:2.2.14
2018/05/04 14:47:37.426690 INFO OS: freebsd 11.1
2018/05/04 14:47:37.432166 INFO Python: 2.7.14
2018/05/04 14:47:37.441456 INFO Run daemon
2018/05/04 14:47:37.446356 INFO No RDMA handler exists for
distro='freebsd' version='11.1'
2018/05/04 14:47:37.455506 INFO Activate resource disk
2018/05/04 14:47:37.865118 INFO Resource disk device da1 found.
2018/05/04 14:47:37.878351 INFO Resource disk partition /dev/da1s1 found.
2018/05/04 14:47:37.907269 INFO Resource disk /dev/da1s1 is already mounted
2018/05/04 14:47:37.915939 INFO Clean protocol
2018/05/04 14:47:38.162184 INFO Provisioning already completed, skipping.
2018/05/04 14:47:38.170414 INFO RDMA capabilities are not enabled, skipping
2018/05/04 14:47:38.183150 INFO Determined Agent WALinuxAgent-2.2.25 to
be the latest agent
2018/05/04 14:47:38.757435 INFO Agent WALinuxAgent-2.2.25 is running as
the goal state agent
2018/05/04 14:47:38.811231 INFO Detect protocol endpoints
2018/05/04 14:47:38.817556 INFO Clean protocol
2018/05/04 14:47:38.822305 INFO WireServer endpoint is not found. Rerun
dhcp handler
2018/05/04 14:47:38.834201 INFO Test for route to 168.63.129.16
2018/05/04 14:47:38.847024 WARNING No route exists to 168.63.129.16
2018/05/04 14:47:38.854270 INFO Checking for dhcp lease cache
2018/05/04 14:47:38.861161 INFO Cache exists [False]
2018/05/04 14:47:38.866216 INFO Send dhcp request
2018/05/04 14:47:39.071652 INFO Configure routes
2018/05/04 14:47:39.078703 INFO Gateway:192.168.240.1
2018/05/04 14:47:39.084136 INFO Routes:None
2018/05/04 14:47:39.091911 INFO Wire server endpoint:168.63.129.16
2018/05/04 14:47:39.262347 INFO Fabric preferred wire protocol
version:2015-04-05
2018/05/04 14:47:39.279462 INFO Wire protocol version:2012-11-30
2018/05/04 14:47:39.284741 INFO Server preferred version:2015-04-05
2018/05/04 14:47:39.768649 INFO Start env monitor service.
2018/05/04 14:47:39.779665 INFO Configure routes
2018/05/04 14:47:39.785601 INFO Gateway:None
2018/05/04 14:47:39.789602 INFO Routes:None
2018/05/04 14:47:39.804958 ERROR Command: 'iptables --version'
2018/05/04 14:47:39.812342 ERROR Return code: 127
2018/05/04 14:47:39.817996 ERROR Result: /bin/sh: iptables: not found
2018/05/04 14:47:39.854696 WARNING Unable to determine version of iptables
2018/05/04 14:47:39.862623 WARNING Unable to retrieve firewall packets
droppedUnable to determine version of iptables
2018/05/04 14:47:39.881192 INFO WALinuxAgent-2.2.25 running as process 28769
2018/05/04 14:47:39.889324 INFO Wire server endpoint:168.63.129.16
2018/05/04 14:47:39.906636 INFO Wire server endpoint:168.63.129.16
2018/05/04 14:47:39.926715 INFO Purging disk cache, current incarnation
is 25
2018/05/04 14:47:40.067783 INFO Wire server endpoint:168.63.129.16
2018/05/04 14:47:40.126880 INFO
[Microsoft.OSTCExtensions.VMAccessForLinux-1.4.7.1] Target handler
state: enabled
2018/05/04 14:47:40.142551 INFO
[Microsoft.OSTCExtensions.VMAccessForLinux-1.4.7.1] [Enable] current
handler state is: enabled
2018/05/04 14:47:40.156416 INFO
[Microsoft.OSTCExtensions.VMAccessForLinux-1.4.7.1] Update settings
file: 0.settings
2018/05/04 14:47:40.172996 INFO
[Microsoft.OSTCExtensions.VMAccessForLinux-1.4.7.1] Enable extension
[./vmaccess.py -enable]
And here is when I try and run a backup:
2018/05/04 14:51:32.998269 INFO
[Microsoft.OSTCExtensions.VMAccessForLinux-1.4.7.1] Target handler
state: enabled
2018/05/04 14:51:33.012562 INFO
[Microsoft.OSTCExtensions.VMAccessForLinux-1.4.7.1] [Enable] current
handler state is: enabled
2018/05/04 14:51:33.026063 INFO
[Microsoft.OSTCExtensions.VMAccessForLinux-1.4.7.1] Update settings
file: 0.settings
2018/05/04 14:51:33.039620 INFO
[Microsoft.OSTCExtensions.VMAccessForLinux-1.4.7.1] Enable extension
[./vmaccess.py -enable]
2018/05/04 14:51:33 VMAccess started to handle.
2018/05/04 14:51:33 [Microsoft.OSTCExtensions.VMAccessForLinux-1.4.7.1]
cwd is /var/lib/waagent/Microsoft.OSTCExtensions.VMAccessForLinux-1.4.7.1
2018/05/04 14:51:33 [Microsoft.OSTCExtensions.VMAccessForLinux-1.4.7.1]
Change log file to
/var/log/azure/Microsoft.OSTCExtensions.VMAccessForLinux/1.4.7.1/extension.log
2018/05/04 14:51:34.092901 INFO Event:
name=Microsoft.OSTCExtensions.VMAccessForLinux, op=Enable,
message=Launch command succeeded: ./vmaccess.py -enable, duration=1039
2018/05/04 14:51:34.162235 INFO
[Microsoft.Azure.RecoveryServices.VMSnapshotLinux-1.0.9131.0] Target
handler state: enabled
2018/05/04 14:51:34.175698 INFO
[Microsoft.Azure.RecoveryServices.VMSnapshotLinux-1.0.9131.0] [Enable]
current handler state is: enabled
2018/05/04 14:51:34.188821 INFO
[Microsoft.Azure.RecoveryServices.VMSnapshotLinux-1.0.9131.0] Update
settings file: 21.settings
2018/05/04 14:51:34.202148 INFO
[Microsoft.Azure.RecoveryServices.VMSnapshotLinux-1.0.9131.0] Enable
extension [main/handle.sh enable]
2018/05/04 14:51:35.255864 INFO Event:
name=Microsoft.Azure.RecoveryServices.VMSnapshotLinux, op=Enable,
message=Launch command succeeded: main/handle.sh enable, duration=1039
2018/05/04 14:51:35.360906 INFO Event: name=WALinuxAgent,
op=ProcessGoalState, message=Incarnation 26, duration=2529
2018/05/04 15:02:38.785362 INFO Agent WALinuxAgent-2.2.25 launched with
command 'python -u bin/WALinuxAgent-2.2.25-py2.7.egg -run-exthandlers'
is successfully running
2018/05/04 15:02:38.812292 INFO Event: name=WALinuxAgent, op=Enable,
message=Agent WALinuxAgent-2.2.25 launched with command 'python -u
bin/WALinuxAgent-2.2.25-py2.7.egg -run-exthandlers' is successfully running
--
Like card tricks?
Visit The Alchemist's Warehouse to
learn card magic secrets for free!
http://alchemistswarehouse.com
_______________________________________________
freebsd-cloud@freebsd.org mailing list
https://lists.freebsd.org/mailman/listinfo/freebsd-cloud
To unsubscribe, send any mail to "freebsd-cloud-unsubscr...@freebsd.org"
