Unable to restore to new server

dhumes0524 · ‎04-14-2011

Hello!

I am running UCCX 7.0(1) SR4.

Attempting to restore all the data from this server to a new server I have created in a test environment.

OS matches (2003.1.4aSR7).

UCCX on both servers is on same version.

Both hostnames are exactly the same.

Both IP addresses are exactly the same.

I have installed UCCX 7.0(1) SR4 to the server and reach the First Time run page for the Application Administrator. I select the restore option and select the backup .TAR file.

Once I hit restore, it examines the file, and then returns the following error:

*Unable to perform restore because the IP/hostname in restore TAR file does not match.

I have double checked that the name on the new server is the same as on the existing server, and the IP address is exactly the same.

Is there anything else that could be causing this error?

Thanks,

frzhang · ‎04-14-2011

hostname is case sensitive.

dhumes0524 · ‎04-14-2011

Both servers show the same when I go into "Change Computer Name"...could it not be showing the case correctly? Do you know of somewhere else I should possibly look?

ankitaja · ‎04-14-2011

Can you please check in "cet": Go to Start-> Run -> Type cet -> Enter.

Go to com.cisco.crs.cluster.config.nodeconfig.

Makes sure Ip/hostname ther are same as old server.

Also, Check c:/Program Files/wfavvid/ClusterData/profile.ini and system32/drivers/etc/hosts file on both the servers see if everything is same.

You can also look at the MCVD file when you try to run the restore to see which IP is it complaining about and then do a search on the 
server or under the registry (Run -> regedit) to see if you see that ip anywhere.

Make sure that the NIC binding order is right on the new server.

NIC Binding order

Login to the CRS server
Right-click on My Network Places and select Properties
 In the Network Connections window identify the Connected NIC
At the top of this window click on Advanced->Advanced Settings
Under the Adapters and Bindings tab, ensure that the Connected NIC is 
first in the list. You would need to restart the server in order for the changes to take effect.

dhumes0524 · ‎04-15-2011

I checked in "cet" on both servers, and they look identical for hostname and IP address.

In the profile.ini, the file on both servers, the IP address matches, but I didn't see hostname listed.

The only listing in both HOSTS file is 127.0.0.1.

Here is the MCVD log and the error it throws when I am trying to restore (I don't know what it means):

14: Apr 14 13:21:33.704 EDT %MCVD-ARCHIVE_MGR-3-EXCEPTION: at com.cisco.util.ThreadPoolFactory$ThreadImpl.run(ThreadPoolFactory.java:853)
1515: Apr 14 13:21:33.704 EDT %MCVD-ARCHIVE_MGR-2-BARS_SESSION_LOST:BARS Session lost: BARS Address=null
1516: Apr 14 13:21:33.704 EDT %MCVD-ARCHIVE_MGR-7-UNK:SessionThread.abort(com.cisco.archive.impl.ArchiveFailureException: Session with BARBI client was lost; nested exception is:
java.io.EOFException)
1517: Apr 14 13:21:33.704 EDT %MCVD-ARCHIVE_MGR-7-UNK:Removing session: Thread[MCVD_ARCHIVE_MGR_SESSION_null-42-0,4]
1518: Apr 14 13:21:33.704 EDT %MCVD-ARCHIVE_MGR-7-UNK:Removed (true) session: Thread[MCVD_ARCHIVE_MGR_SESSION_null-42-0,4]
1519: Apr 14 13:21:33.704 EDT %MCVD-ARCHIVE_MGR-7-UNK:SessionThread.abort(com.cisco.archive.impl.ArchiveFailureException: Session with BARBI client was lost; nested exception is:
java.io.EOFException) - done
1520: Apr 14 13:21:33.704 EDT %MCVD-ARCHIVE_MGR-7-UNK:SessionThread is no longer active; lost
1521: Apr 14 13:21:33.704 EDT %MCVD-ARCHIVE_MGR-7-UNK:Closing input/output stream from SessionThread...
1522: Apr 14 13:21:33.704 EDT %MCVD-ARCHIVE_MGR-7-UNK:Executor trace: Action=CMD_DONE,Executor id=SESSION_null,Mnemonic=HANDLE,Executor type=null,Thread=MCVD_ARCHIVE_MGR_SESSION_null-42-0,Thread priority=4,Original Thread=null,Original thread priority=null,Time=516,Exception=null
1523: Apr 14 13:21:33.704 EDT %MCVD-ARCHIVE_MGR-7-UNK:Executor trace: Action=THREAD_RELEASED,Executor id=SESSION_null,Mnemonic=null,Executor type=com.cisco.executor.Thread,Thread=ExecutorManagerImpl.pool-15,Thread priority=4,Original Thread=MCVD_ARCHIVE_MGR_SESSION_null-42-0,Original thread priority=null,Time=null,Exception=null
1524: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-MISSING_RESOURCE:Missing thread priority resource: Facility=MCVD,Sub-Facility=BAR_CLIENT,Executor id=BARBI_MEDIATOR_,Exception=java.util.MissingResourceException: Can't find resource
1525: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION:java.util.MissingResourceException: Can't find resource
1526: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at com.cisco.util.ResourceBundle.getObject(ResourceBundle.java:1821)
1527: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at com.cisco.util.ResourceBundle.getString(ResourceBundle.java:1680)
1528: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at com.cisco.executor.impl.ExecutorManagerImpl.getPriority(ExecutorManagerImpl.java:270)
1529: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at com.cisco.executor.impl.ExecutorStubImpl.(ExecutorStubImpl.java:193)
1530: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at com.cisco.executor.impl.ThreadStubImpl.(ThreadStubImpl.java:117)
1531: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at com.cisco.executor.impl.ExecutorManagerImpl.defineStub(ExecutorManagerImpl.java:524)
1532: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at com.cisco.executor.Thread.(Thread.java:360)
1533: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at com.cisco.executor.Thread.(Thread.java:216)
1534: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at com.cisco.executor.Thread.(Thread.java:259)
1535: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at com.cisco.archive.impl.barbi.client.BARBIMediator.(BARBIMediator.java:157)
1536: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at com.cisco.archive.impl.barbi.client.MediatorManager.getMediator(MediatorManager.java:81)
1537: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at com.cisco.archive.impl.ArchiveManagerImpl$2.startRestore(ArchiveManagerImpl.java:850)
1538: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
1539: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
1540: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
1541: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at java.lang.reflect.Method.invoke(Method.java:597)
1542: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at sun.rmi.server.UnicastServerRef.dispatch(UnicastServerRef.java:305)
1543: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at sun.rmi.transport.Transport$1.run(Transport.java:159)
1544: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at java.security.AccessController.doPrivileged(Native Method)
1545: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at sun.rmi.transport.Transport.serviceCall(Transport.java:155)
1546: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at sun.rmi.transport.tcp.TCPTransport.handleMessages(TCPTransport.java:535)
1547: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run0(TCPTransport.java:790)
1548: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at sun.rmi.transport.tcp.TCPTransport$ConnectionHandler.run(TCPTransport.java:649)
1549: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at java.util.concurrent.ThreadPoolExecutor$Worker.runTask(ThreadPoolExecutor.java:886)
1550: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:908)
1551: Apr 14 13:47:41.321 EDT %MCVD-EXECUTOR_MGR-4-EXCEPTION: at java.lang.Thread.run(Thread.java:619)
1552: Apr 14 13:47:41.321 EDT %MCVD-BAR_CLIENT-7-UNK:Executor trace: Action=EXECUTOR_CREATED,Executor id=BARBI_MEDIATOR_,Mnemonic=null,Executor type=com.cisco.executor.Thread,Thread=null,Thread priority=null,Original Thread=null,Original thread priority=null,Time=null,Exception=null
1553: Apr 14 13:47:41.321 EDT %MCVD-BAR_CLIENT-7-UNK:*** Iniitiating BARBIMediator. Archive id: CRS_1302803261321_
1554: Apr 14 13:47:41.321 EDT %MCVD-ARCHIVE_MGR-6-ACCEPTING_BARS_CONN:Accepting BARS connection: BARS Connection=com.cisco.archive.impl.barbi.client.ComPipe$ComEndPoint@7a6c34
1555: Apr 14 13:47:41.321 EDT %MCVD-ARCHIVE_MGR-7-UNK:Executor trace: Action=EXECUTOR_CREATED,Executor id=SESSION_null,Mnemonic=null,Executor type=com.cisco.executor.Thread,Thread=null,Thread priority=null,Original Thread=null,Original thread priority=null,Time=null,Exception=null
1556: Apr 14 13:47:41.321 EDT %MCVD-ARCHIVE_MGR-7-UNK:SessionThread.init(com.cisco.archive.impl.barbi.client.ComPipe$ComEndPoint@7a6c34)
1557: Apr 14 13:47:41.321 EDT %MCVD-ARCHIVE_MGR-6-BARS_CONN_ESTABLISHED:Connection established with BARS: BARS Address=null
1558: Apr 14 13:47:41.321 EDT %MCVD-ARCHIVE_MGR-7-UNK:Executor trace: Action=EXECUTE_CMD,Executor id=SESSION_null,Mnemonic=HANDLE,Executor type=null,Thread=null,Thread priority=null,Original Thread=null,Original thread priority=null,Time=null,Exception=null
1559: Apr 14 13:47:41.321 EDT %MCVD-ARCHIVE_MGR-7-UNK:Executor trace: Action=THREAD_ALLOCATED,Executor id=SESSION_null,Mnemonic=null,Executor type=com.cisco.executor.Thread,Thread=MCVD_ARCHIVE_MGR_SESSION_null-44-0,Thread priority=5,Original Thread=ExecutorManagerImpl.pool-17,Original thread priority=null,Time=null,Exception=null
1560: Apr 14 13:47:41.321 EDT %MCVD-ARCHIVE_MGR-7-UNK:Executor trace: Action=THREAD_PRIORITY_CHANGED,Executor id=SESSION_null,Mnemonic=null,Executor type=com.cisco.executor.Thread,Thread=MCVD_ARCHIVE_MGR_SESSION_null-44-0,Thread priority=4,Original Thread=null,Original thread priority=5,Time=null,Exception=null
1561: Apr 14 13:47:41.321 EDT %MCVD-ARCHIVE_MGR-7-UNK:Adding session: Thread[MCVD_ARCHIVE_MGR_SESSION_null-44-0,4]
1562: Apr 14 13:47:41.321 EDT %MCVD-ARCHIVE_MGR-7-UNK:BARBI connection initialized
1563: Apr 14 13:47:41.321 EDT %MCVD-BAR_CLIENT-7-UNK:*** BARBIMediator: startRestore()...
1564: Apr 14 13:47:41.321 EDT %MCVD-BAR_CLIENT-7-UNK:*** startRestore() parameters:
1565: Apr 14 13:47:41.321 EDT %MCVD-BAR_CLIENT-7-UNK:*** requester = User[type=System User,id=,authenticated=true]
1566: Apr 14 13:47:41.321 EDT %MCVD-ARCHIVE_MGR-7-UNK:Executor trace: Action=CMD_RUN,Executor id=SESSION_null,Mnemonic=HANDLE,Executor type=null,Thread=MCVD_ARCHIVE_MGR_SESSION_null-44-0-HANDLE,Thread priority=4,Original Thread=null,Original thread priority=null,Time=0,Exception=null
1567: Apr 14 13:47:41.321 EDT %MCVD-BAR_CLIENT-7-UNK:*** backupHost = /10.5.1.3
1568: Apr 14 13:47:41.321 EDT %MCVD-ARCHIVE_MGR-7-UNK:Waiting for the next BARBI message
1569: Apr 14 13:47:41.321 EDT %MCVD-BAR_CLIENT-7-UNK:*** backupLocationPath = c$\backup.tar
1570: Apr 14 13:47:41.321 EDT %MCVD-BAR_CLIENT-7-UNK:*** backupLocationUsername = Administrator
1571: Apr 14 13:47:41.321 EDT %MCVD-BAR_CLIENT-7-UNK:*** backupDeviceName = null
1572: Apr 14 13:47:41.321 EDT %MCVD-BAR_CLIENT-7-UNK:*** archive directory = c$
1573: Apr 14 13:47:41.321 EDT %MCVD-BAR_CLIENT-7-UNK:*** archive file name = backup.tar
1574: Apr 14 13:47:41.336 EDT %MCVD-BAR_CLIENT-7-UNK:*** NT Admin user = CRSAdministrator
1575: Apr 14 13:47:41.336 EDT %MCVD-BAR_CLIENT-7-UNK:*** Extracting archive = backup.tar
1576: Apr 14 13:47:41.336 EDT %MCVD-BAR_CLIENT-7-UNK:*** BARBIMediator: extractArchive()...
1577: Apr 14 13:47:41.336 EDT %MCVD-BAR_CLIENT-7-UNK:*** Extracting to staging directory = C:\STI\Backup
1578: Apr 14 13:47:41.336 EDT %MCVD-BAR_CLIENT-7-UNK:*** Creating staging directory = C:\STI\Backup, Creation flag = true
1579: Apr 14 13:47:41.336 EDT %MCVD-BAR_CLIENT-7-UNK:*** Creating network connection for remote host...
1580: Apr 14 13:47:41.336 EDT %MCVD-BAR_CLIENT-7-UNK:*** host = 10.5.1.3
1581: Apr 14 13:47:41.336 EDT %MCVD-BAR_CLIENT-7-UNK:*** path = c$
1582: Apr 14 13:47:41.336 EDT %MCVD-BAR_CLIENT-7-UNK:*** user = Administrator
1583: Apr 14 13:47:43.602 EDT %MCVD-ARCHIVE_MGR-7-UNK:WinRemoteConnection::connStatus - 10.5.1.3, host:10.5.1.3, user:Administrator, password:3575 >> CONNECT
1584: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-7-UNK:*** Removing the network connection for remote host that was made for the extract operation
1585: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-7-UNK:*** Failed to extract archive... Sending ArchivingFailureEvent
1586: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-7-UNK:Executor trace: Action=EXECUTE_CMD,Executor id=BARBI_MEDIATOR_,Mnemonic=BARBI,Executor type=null,Thread=null,Thread priority=null,Original Thread=null,Original thread priority=null,Time=null,Exception=null
1587: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-7-UNK:Executor trace: Action=THREAD_ALLOCATED,Executor id=BARBI_MEDIATOR_,Mnemonic=null,Executor type=com.cisco.executor.Thread,Thread=MCVD_BAR_CLIENT_BARBI_MEDIATOR_-43-0,Thread priority=5,Original Thread=ExecutorManagerImpl.pool-18,Original thread priority=null,Time=null,Exception=null
1588: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-7-UNK:Executor trace: Action=CMD_RUN,Executor id=BARBI_MEDIATOR_,Mnemonic=BARBI,Executor type=null,Thread=MCVD_BAR_CLIENT_BARBI_MEDIATOR_-43-0-BARBI,Thread priority=5,Original Thread=null,Original thread priority=null,Time=0,Exception=null
1589: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-7-UNK:*** Starting BARBI client thread...
1590: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-7-UNK:*** BARBIMediator: doRestore()...
1591: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-7-UNK:*** BARBIMediator: openSession()...
1592: Apr 14 13:47:43.602 EDT %MCVD-ARCHIVE_MGR-7-UNK:processing BARBI message: OpenSessionRequest id=7, inbound=true, version=2, idleTimeout=60000
1593: Apr 14 13:47:43.602 EDT %MCVD-ARCHIVE_MGR-7-UNK:Session is not active: processing message...
1594: Apr 14 13:47:43.602 EDT %MCVD-ARCHIVE_MGR-6-BARS_PROTOCOL_VERSION:The BARS Protocol version received: BARS Address=null,BARS Version=2
1595: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-7-UNK:*** BARBIMediator: executeRestoreSession()...
1596: Apr 14 13:47:43.602 EDT %MCVD-ARCHIVE_MGR-6-BARS_SESSION_ESTABLISHED:BARS session established: BARS Address=null
1597: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-7-UNK:*** Sending Restore Request... Archive id = CRS_1302803261321_
1598: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-7-UNK:HeartbeatDispatherThread started, idleTimeout=60000, heartbeatInterval=15000
1599: Apr 14 13:47:43.602 EDT %MCVD-ARCHIVE_MGR-7-UNK:SessionThread is active
1600: Apr 14 13:47:43.602 EDT %MCVD-ARCHIVE_MGR-7-UNK:Waiting for the next BARBI message
1601: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-HAND_OFF_LOCK_ERROR:Hand off UI error occurs during a backup/restore session: Exception=java.lang.reflect.InvocationTargetException
1602: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION:java.lang.reflect.InvocationTargetException
1603: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at EDU.oswego.cs.dl.util.concurrent.FutureResult.setException(FutureResult.java:152)
1604: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.archive.impl.barbi.client.BARBIMediator.run(BARBIMediator.java:480)
1605: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.runCommand(ExecutorStubImpl.java:690)
1606: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.run(ExecutorStubImpl.java:486)
1607: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.run(ExecutorStubImpl.java:762)
1608: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.executor.impl.ThreadStubImpl$RequestImpl.run(ThreadStubImpl.java:570)
1609: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.util.ThreadPoolFactory$ThreadImpl.run(ThreadPoolFactory.java:853)
1610: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION:Caused by: com.cisco.archive.impl.barbi.client.BARBIException: Restore failed due to IOException; nested exception is:
1611: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: java.io.FileNotFoundException: \STI\Backup\00Catalog.ini (The system cannot find the file specified)
1612: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.archive.impl.barbi.client.BARBIMediator.doRestore(BARBIMediator.java:450)
1613: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.archive.impl.barbi.client.BARBIMediator.run(BARBIMediator.java:472)
1614: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: ... 5 more
1615: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION:Caused by: java.io.FileNotFoundException: \STI\Backup\00Catalog.ini (The system cannot find the file specified)
1616: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at java.io.FileInputStream.open(Native Method)
1617: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at java.io.FileInputStream.(FileInputStream.java:106)
1618: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at java.io.FileInputStream.(FileInputStream.java:66)
1619: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.archive.impl.barbi.client.BARBIArchiveInfo.readArchiveInfo(BARBIArchiveInfo.java:380)
1620: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.archive.impl.barbi.client.BARBIArchiveInfo.getArchiveVersion(BARBIArchiveInfo.java:221)
1621: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.archive.impl.barbi.client.BARBIMediator.executeRestoreSession(BARBIMediator.java:879)
1622: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.archive.impl.barbi.client.BARBIMediator.doRestore(BARBIMediator.java:445)
1623: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: ... 6 more
1624: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-OPERATION_ERROR:Backup/Restore failed due to the following reason: Exception=com.cisco.archive.impl.barbi.client.BARBIException: Restore failed due to IOException; nested exception is:
java.io.FileNotFoundException: \STI\Backup\00Catalog.ini (The system cannot find the file specified)
1625: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION:com.cisco.archive.impl.barbi.client.BARBIException: Restore failed due to IOException; nested exception is:
1626: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: java.io.FileNotFoundException: \STI\Backup\00Catalog.ini (The system cannot find the file specified)
1627: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.archive.impl.barbi.client.BARBIMediator.doRestore(BARBIMediator.java:450)
1628: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.archive.impl.barbi.client.BARBIMediator.run(BARBIMediator.java:472)
1629: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.runCommand(ExecutorStubImpl.java:690)
1630: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.run(ExecutorStubImpl.java:486)
1631: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.run(ExecutorStubImpl.java:762)
1632: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.executor.impl.ThreadStubImpl$RequestImpl.run(ThreadStubImpl.java:570)
1633: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.util.ThreadPoolFactory$ThreadImpl.run(ThreadPoolFactory.java:853)
1634: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION:Caused by: java.io.FileNotFoundException: \STI\Backup\00Catalog.ini (The system cannot find the file specified)
1635: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at java.io.FileInputStream.open(Native Method)
1636: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at java.io.FileInputStream.(FileInputStream.java:106)
1637: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at java.io.FileInputStream.(FileInputStream.java:66)
1638: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.archive.impl.barbi.client.BARBIArchiveInfo.readArchiveInfo(BARBIArchiveInfo.java:380)
1639: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.archive.impl.barbi.client.BARBIArchiveInfo.getArchiveVersion(BARBIArchiveInfo.java:221)
1640: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.archive.impl.barbi.client.BARBIMediator.executeRestoreSession(BARBIMediator.java:879)
1641: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: at com.cisco.archive.impl.barbi.client.BARBIMediator.doRestore(BARBIMediator.java:445)
1642: Apr 14 13:47:43.602 EDT %MCVD-BAR_CLIENT-3-EXCEPTION: ... 6 more
1643: Apr 14 13:47:43.602 EDT %MCVD-ARCHIVE_MGR-7-UNK:processing BARBI message: ArchivingFailureEvent id=8, archiveId=CRS_1302803261321_, errorCode=UNSUPPORTED_REQUEST_ERROR, statusMessage=Unable to process the restore request, err: Restore failed due to IOException; nested exception is:
java.io.FileNotFoundException: \STI\Backup\00Catalog.ini (The system cannot find the file specified)
1644: Apr 14 13:47:43.602 EDT %MCVD-ARCHIVE_MGR-7-UNK:Session is active: processing message...
1645: Apr 14 13:47:43.602 EDT %MCVD-ARCHIVE_MGR-6-IGNORING_BARS_MSG:Ignoring BARS message received: BARS Address=null,Archive id=CRS_1302803261321_,BARS Message=ArchivingFailureEvent id=8, archiveId=CRS_1302803261321_, errorCode=UNSUPPORTED_REQUEST_ERROR, statusMessage=Unable to process the restore request, err: Restore failed due to IOException; nested exception is:
java.io.FileNotFoundException: \STI\Backup\00Catalog.ini (The system cannot find the file specified)
1646: Apr 14 13:47:43.602 EDT %MCVD-ARCHIVE_MGR-7-UNK:Waiting for the next BARBI message
1647: Apr 14 13:47:44.102 EDT %MCVD-BAR_CLIENT-7-UNK:*** Operation is complete. Shutting down comunication pipes...
1648: Apr 14 13:47:44.102 EDT %MCVD-BAR_CLIENT-7-UNK:*** Closing heartbeat thread...
1649: Apr 14 13:47:44.102 EDT %MCVD-BAR_CLIENT-7-UNK:HeartbeatDispatherThread completed
1650: Apr 14 13:47:44.102 EDT %MCVD-BAR_CLIENT-7-UNK:*** Closing executor thread...
1651: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-3-DECODING_ERROR:BARS Message decoding error: BARS Address=null,Exception=java.io.EOFException
1652: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-3-EXCEPTION:java.io.EOFException
1653: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-3-EXCEPTION: at java.io.DataInputStream.readInt(DataInputStream.java:375)
1654: Apr 14 13:47:44.102 EDT %MCVD-BAR_CLIENT-7-UNK:*** deleting staging directory = \STI\Backup\
1655: Apr 14 13:47:44.102 EDT %MCVD-BAR_CLIENT-7-UNK:*** Clean staging directory. result = true
1656: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-3-EXCEPTION: at com.cisco.archive.impl.barbi.BarBIInputStream.readInt(BarBIInputStream.java:255)
1657: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-3-EXCEPTION: at com.cisco.archive.impl.barbi.BarBIInputStream.read(BarBIInputStream.java:68)
1658: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-3-EXCEPTION: at com.cisco.archive.impl.ArchiveManagerImpl$SessionThread.run(ArchiveManagerImpl.java:3557)
1659: Apr 14 13:47:44.102 EDT %MCVD-BAR_CLIENT-7-UNK:Executor trace: Action=CMD_DONE,Executor id=BARBI_MEDIATOR_,Mnemonic=BARBI,Executor type=null,Thread=MCVD_BAR_CLIENT_BARBI_MEDIATOR_-43-0,Thread priority=5,Original Thread=null,Original thread priority=null,Time=500,Exception=null
1660: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-3-EXCEPTION: at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.runCommand(ExecutorStubImpl.java:690)
1661: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-3-EXCEPTION: at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.run(ExecutorStubImpl.java:486)
1662: Apr 14 13:47:44.102 EDT %MCVD-BAR_CLIENT-7-UNK:Executor trace: Action=THREAD_RELEASED,Executor id=BARBI_MEDIATOR_,Mnemonic=null,Executor type=com.cisco.executor.Thread,Thread=ExecutorManagerImpl.pool-18,Thread priority=5,Original Thread=MCVD_BAR_CLIENT_BARBI_MEDIATOR_-43-0,Original thread priority=null,Time=null,Exception=null
1663: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-3-EXCEPTION: at com.cisco.executor.impl.ExecutorStubImpl$RequestImpl.run(ExecutorStubImpl.java:762)
1664: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-3-EXCEPTION: at com.cisco.executor.impl.ThreadStubImpl$RequestImpl.run(ThreadStubImpl.java:570)
1665: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-3-EXCEPTION: at com.cisco.util.ThreadPoolFactory$ThreadImpl.run(ThreadPoolFactory.java:853)
1666: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-2-BARS_SESSION_LOST:BARS Session lost: BARS Address=null
1667: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-7-UNK:SessionThread.abort(com.cisco.archive.impl.ArchiveFailureException: Session with BARBI client was lost; nested exception is:
java.io.EOFException)
1668: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-7-UNK:Removing session: Thread[MCVD_ARCHIVE_MGR_SESSION_null-44-0,4]
1669: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-7-UNK:Removed (true) session: Thread[MCVD_ARCHIVE_MGR_SESSION_null-44-0,4]
1670: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-7-UNK:SessionThread.abort(com.cisco.archive.impl.ArchiveFailureException: Session with BARBI client was lost; nested exception is:
java.io.EOFException) - done
1671: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-7-UNK:SessionThread is no longer active; lost
1672: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-7-UNK:Closing input/output stream from SessionThread...
1673: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-7-UNK:Executor trace: Action=CMD_DONE,Executor id=SESSION_null,Mnemonic=HANDLE,Executor type=null,Thread=MCVD_ARCHIVE_MGR_SESSION_null-44-0,Thread priority=4,Original Thread=null,Original thread priority=null,Time=2,781,Exception=null
1674: Apr 14 13:47:44.102 EDT %MCVD-ARCHIVE_MGR-7-UNK:Executor trace: Action=THREAD_RELEASED,Executor id=SESSION_null,Mnemonic=null,Executor type=com.cisco.executor.Thread,Thread=ExecutorManagerImpl.pool-17,Thread priority=4,Original Thread=MCVD_ARCHIVE_MGR_SESSION_null-44-0,Original thread priority=null,Time=null,Exception=null

The NIC Binding order was incorrect previously, but I have since changed it and rebooted several times, but still doesn't work.

I am beginning to run out of ideas and am wondering if I should just rebuild the new server, in hopes that the problems won't return.

dhumes0524 · ‎04-15-2011

Well, I ended up rebuilding my test server and trying the restore, which now works. Thanks for the assistance everyone. Unsure of what the issue was but a format/reinstall resolved.