DICE-UNC / ployglot-microservices

DFC microservices to do file format conversion via Drexel polyglot
BSD 3-Clause "New" or "Revised" License
0 stars 0 forks source link

error running microservices on testdfc1 after build/install #1

Open michael-conway opened 9 years ago

michael-conway commented 9 years ago

manually set avu to ConvertMe yields


Sep 24 09:00:49 pid:14399 DEBUG: msiExecCmd: rsExecCmd failed for file, status = -344000
Sep 24 09:00:49 pid:14399 NOTICE: chlDeleteAVUMetadata cmlExecuteNoAnswerSql delete failure -819000
Sep 24 09:00:49 pid:14399 NOTICE: chlDeleteAVUMetadata cmlExecuteNoAnswerSql(rollback) succeeded
Sep 24 09:00:49 pid:14399 NOTICE: rsModAVUMetadata: rcModAVUMetadata failed
Sep 24 09:00:49 pid:14399 ERROR: [-]    iRODS/server/re/src/rules.cpp:669:actionTableLookUp :  status [PLUGIN_ERROR]  errno [] -- message []
        [-]     iRODS/server/re/src/irods_ms_plugin.cpp:108:load_microservice_plugin :  status [PLUGIN_ERROR]  errno [] -- message [Failed to create ms plugin entry.]
                [-]     iRODS/lib/core/include/irods_load_plugin.hpp:111:load_plugin :  status [PLUGIN_ERROR]  errno [] -- message [failed to open shared object file [/var/lib/irods/plugins/microservices/libmodAVUMetadata.so] :: dlerror: is [/var/lib/irods/plugins/microservices/libmodAVUMetadata.so: cannot open shared object file: No such file or directory]]

Sep 24 09:00:49 pid:14399 ERROR: executeRuleAction Failed for modAVUMetadata status = -1097000 NO_RULE_OR_MSI_FUNCTION_FOUND_ERR
Sep 24 09:00:49 pid:14399 NOTICE: executeRuleBody: Microservice or Action modAVUMetadata Failed with status -1097000
Sep 24 09:00:49 pid:14399 ERROR: executeRuleAction Failed for if status = -1097000 NO_RULE_OR_MSI_FUNCTION_FOUND_ERR
Sep 24 09:00:49 pid:14399 NOTICE: executeRuleBody: Microservice or Action if Failed with status -1097000
Sep 24 09:00:49 pid:14399 NOTICE: execRuleNodeRes: applyRule Failed: postProcForModifyAVUMetadata with status -1097000
Sep 24 09:00:49 pid:14399 NOTICE: writeLine: inString = processing ConvertMe for /dfc1/home/test1/jackson.jpg
Sep 24 09:00:49 pid:14399 NOTICE: writeLine: inString = getObjId of /dfc1/home/test1/jackson.jpg, jackson.jpg, /dfc1/home/test1
Sep 24 09:00:49 pid:14399 NOTICE: writeLine: inString = /dfc1/home/test1/jackson.jpg@1411563648
Sep 24 09:00:49 pid:14399 NOTICE: writeLine: inString = ampqSend: sending message '
michael-conway commented 9 years ago

after fixing makefile, current result


    LocalHostName:  localhost, testdfc1.renci.org, Port Num: 1247.

Sep 24 10:31:53 pid:17435 NOTICE:    RescName: bundleResc, VaultPath: /bundle
Zone Info:
    ZoneName: dfc1   Type: LOCAL_ICAT    HostAddr: localhost   PortNum: 1247

    ZoneName: dfc2   Type: REMOTE_ICAT    HostAddr: testdfc2.renci.org   PortNum: 1247

reHost:   localhost

Sep 24 10:31:53 pid:17435 NOTICE: rodsServer Release version rods4.0.3 - API Version d is up
Sep 24 10:31:53 pid:17440 NOTICE: Starting irodsReServer
Sep 24 10:32:15 pid:17435 NOTICE: Agent process 17463 started for puser=test1 and cuser=test1 from 152.54.15.222
Sep 24 10:32:15 pid:17463 NOTICE: readAndProcClientMsg: received disconnect msg from client
Sep 24 10:32:15 pid:17463 NOTICE: Agent exiting with status = 0
Sep 24 10:32:18 pid:17435 NOTICE: Agent process 17463 exited with status 0
Sep 24 10:32:18 pid:17435 NOTICE: Agent process 17468 started for puser=test1 and cuser=test1 from 152.54.15.222
Sep 24 10:32:18 pid:17468 NOTICE: readAndProcClientMsg: received disconnect msg from client
Sep 24 10:32:18 pid:17468 NOTICE: Agent exiting with status = 0
Sep 24 10:32:18 pid:17435 NOTICE: Agent process 17468 exited with status 0
Sep 24 10:32:18 pid:17435 NOTICE: Agent process 17473 started for puser=test1 and cuser=test1 from 152.54.15.222
Sep 24 10:32:33 pid:17473 ERROR: converstion returned error code 500
Sep 24 10:32:33 pid:17473 NOTICE: writeLine: inString = acPostProcForOpen: /dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties, 0, dataSize = 1340
Sep 24 10:32:33 pid:17473 NOTICE: writeLine: inString = getObjId of /dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties, social_science_place.properties, /dfc1/home/test1/testLoadSocialSciencePlace
Sep 24 10:32:33 pid:17473 NOTICE: writeLine: inString = skip data:id
Sep 24 10:32:33 pid:17473 NOTICE: writeLine: inString = /dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153
Sep 24 10:32:33 pid:17473 NOTICE: writeLine: inString = getObjId of /dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties, social_science_place.properties, /dfc1/home/test1/testLoadSocialSciencePlace
Sep 24 10:32:33 pid:17473 NOTICE: writeLine: inString = /dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153
Sep 24 10:32:33 pid:17473 NOTICE: writeLine: inString = ampqSend: sending message '
    {
        "messages" : [ {
            "operation" : "create",
            "hasPart" : [
                {
                    "type" : "Access",
                    "uri" : "/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153a1411569153data_object_get",
                    "title" : "data object get",
                    "linkingDataEntity" : [
                        {
                            "uri": "dataEntityLink1411569153000",
                            "dataEntity" : {
                                "type" : "DataObject",
                                "uri": "/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153"
                            }
                        }
                    ],
                    "linkingUser" : [
                        {
                            "uri": "userLink1411569153000",
                            "user" : {
                                "uri": "test1"
                            }
                        }   
                    ],
                    "created" : "1411569153000",
                    "description" : "<img src='images/icons/get.png' />/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties",
                    "partOf" :  [ {
                        "type": "Session",
                        "uri": "Unspecified"
                    } ]
                }
            ]
        } ]
    }
    '
Sep 24 10:32:33 pid:17484 NOTICE: execCmd: argv: "amqp://localhost/metaQueue"  "
    {
        \"messages\" : [ {
            \"operation\" : \"create\",
            \"hasPart\" : [
                {
                    \"type\" : \"Access\",
                    \"uri\" : \"/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153a1411569153data_object_get\",
                    \"title\" : \"data object get\",
                    \"linkingDataEntity\" : [
                        {
                            \"uri\": \"dataEntityLink1411569153000\",
                            \"dataEntity\" : {
                                \"type\" : \"DataObject\",
                                \"uri\": \"/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153\"
                            }
                        }
                    ],
                    \"linkingUser\" : [
                        {
                            \"uri\": \"userLink1411569153000\",
                            \"user\" : {
                                \"uri\": \"test1\"
                            }
                        }   
                    ],
                    \"created\" : \"1411569153000\",
                    \"description\" : \"<img src=\'images/icons/get.png\' />/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties\",
                    \"partOf\" :  [ {
                        \"type\": \"Session\",
                        \"uri\": \"Unspecified\"
                    } ]
                }
            ]
        } ]
    }
    "
Sep 24 10:32:33 pid:17484 NOTICE: getServerHostByFileInx: fileInx 3 not active
Sep 24 10:32:33 pid:17473 NOTICE: writeLine: inString = skip data:id
Sep 24 10:32:33 pid:17473 NOTICE: writeLine: inString = getCollId of /dfc1/trash/home/test1
Sep 24 10:32:33 pid:17473 NOTICE: writeLine: inString = /dfc1/trash/home/test1@1411563648
Sep 24 10:32:33 pid:17473 NOTICE: writeLine: inString = getCollId of /dfc1/trash/home/test1/testLoadSocialSciencePlace
Sep 24 10:32:33 pid:17473 NOTICE: writeLine: inString = /dfc1/trash/home/test1/testLoadSocialSciencePlace@1411569153
Sep 24 10:32:33 pid:17473 NOTICE: writeLine: inString = ampqSend: sending message '
    {
        "messages" : [
            {
                "operation" : "create",
                "hasPart" : [
                    {
                        "type" : "Collection",
                        "uri" : "/dfc1/trash/home/test1/testLoadSocialSciencePlace@1411569153",
                        "partOf" : [ {
                            "type": "Collection",
                            "uri": "/dfc1/trash/home/test1@1411563648"
                        } ],
                        "label" : "/dfc1/trash/home/test1/testLoadSocialSciencePlace"
                    }
                ]
            }
        ]
    }'
Sep 24 10:32:33 pid:17494 NOTICE: execCmd: argv: "amqp://localhost/metaQueue"  "
    {
        \"messages\" : [
            {
                \"operation\" : \"create\",
                \"hasPart\" : [
                    {
                        \"type\" : \"Collection\",
                        \"uri\" : \"/dfc1/trash/home/test1/testLoadSocialSciencePlace@1411569153\",
                        \"partOf\" : [ {
                            \"type\": \"Collection\",
                            \"uri\": \"/dfc1/trash/home/test1@1411563648\"
                        } ],
                        \"label\" : \"/dfc1/trash/home/test1/testLoadSocialSciencePlace\"
                    }
                ]
            }
        ]
    }"
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = ampqSend: sending message '
    {
        "messages" : [ {
            "operation" : "create",
            "hasPart" : [
                {
                    "type" : "Access",
                    "uri" : "/dfc1/trash/home/test1/testLoadSocialSciencePlace@1411569153a1411569154collection_create",
                    "title" : "collection create",
                    "linkingDataEntity" : [
                        {
                            "uri": "dataEntityLink1411569154000",
                            "dataEntity" : {
                                "type" : "DataObject",
                                "uri": "/dfc1/trash/home/test1/testLoadSocialSciencePlace@1411569153"
                            }
                        }
                    ],
                    "linkingUser" : [
                        {
                            "uri": "userLink1411569154000",
                            "user" : {
                                "uri": "test1"
                            }
                        }   
                    ],
                    "created" : "1411569154000",
                    "description" : "/dfc1/trash/home/test1/testLoadSocialSciencePlace collection create",
                    "partOf" :  [ {
                        "type": "Session",
                        "uri": "Unspecified"
                    } ]
                }
            ]
        } ]
    }
    '
Sep 24 10:32:34 pid:17501 NOTICE: execCmd: argv: "amqp://localhost/metaQueue"  "
    {
        \"messages\" : [ {
            \"operation\" : \"create\",
            \"hasPart\" : [
                {
                    \"type\" : \"Access\",
                    \"uri\" : \"/dfc1/trash/home/test1/testLoadSocialSciencePlace@1411569153a1411569154collection_create\",
                    \"title\" : \"collection create\",
                    \"linkingDataEntity\" : [
                        {
                            \"uri\": \"dataEntityLink1411569154000\",
                            \"dataEntity\" : {
                                \"type\" : \"DataObject\",
                                \"uri\": \"/dfc1/trash/home/test1/testLoadSocialSciencePlace@1411569153\"
                            }
                        }
                    ],
                    \"linkingUser\" : [
                        {
                            \"uri\": \"userLink1411569154000\",
                            \"user\" : {
                                \"uri\": \"test1\"
                            }
                        }   
                    ],
                    \"created\" : \"1411569154000\",
                    \"description\" : \"/dfc1/trash/home/test1/testLoadSocialSciencePlace collection create\",
                    \"partOf\" :  [ {
                        \"type\": \"Session\",
                        \"uri\": \"Unspecified\"
                    } ]
                }
            ]
        } ]
    }
    "
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = getCollId of /dfc1/home/test1/testLoadSocialSciencePlace
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = skip data:id
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = /dfc1/home/test1/testLoadSocialSciencePlace@1411569154
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = getCollId of /dfc1/trash/home/test1/testLoadSocialSciencePlace
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = /dfc1/trash/home/test1/testLoadSocialSciencePlace@1411569153
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = getObjId of /dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx, social_science_place.xxx, /dfc1/trash/home/test1/testLoadSocialSciencePlace
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = skip data:id
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = /dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx@1411569154
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = ampqSend: sending message '
    {
        "messages": [
            {
                "operation" : "modify",
                "hasPart" : [
                    {
                        "type" : "DataObject",
                        "uri" : "/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx@1411569154",
                        "partOf" : [
                            {
                                "type" : "Collection",
                                "uri" : "/dfc1/home/test1/testLoadSocialSciencePlace@1411569154"
                            }
                        ]
                    }, {
                        "type" : "DataObject",
                        "label" : "/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx",
                        "partOf" : [
                            {
                                "type" : "Collection",
                                "uri" : "/dfc1/trash/home/test1/testLoadSocialSciencePlace@1411569153"
                            }
                        ]
                    }
                ]               
            }
        ]
    }
    '
Sep 24 10:32:34 pid:17508 NOTICE: execCmd:¤ðŒ°ÿ argv: "amqp://localhost/metaQueue"  "
    {
        \"messages\": [
            {
                \"operation\" : \"modify\",
                \"hasPart\" : [
                    {
                        \"type\" : \"DataObject\",
                        \"uri\" : \"/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx@1411569154\",
                        \"partOf\" : [
                            {
                                \"type\" : \"Collection\",
                                \"uri\" : \"/dfc1/home/test1/testLoadSocialSciencePlace@1411569154\"
                            }
                        ]
                    }, {
                        \"type\" : \"DataObject\",
                        \"label\" : \"/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx\",
                        \"partOf\" : [
                            {
                                \"type\" : \"Collection\",
                                \"uri\" : \"/dfc1/trash/home/test1/testLoadSocialSciencePlace@1411569153\"
                            }
                        ]
                    }
                ]               
            }
        ]
    }
    "
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = ampqSend: sending message '
    {
        "messages" : [ {
            "operation" : "create",
            "hasPart" : [
                {
                    "type" : "Access",
                    "uri" : "/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx@1411569154a1411569154data_object_move",
                    "title" : "data object move",
                    "linkingDataEntity" : [
                        {
                            "uri": "dataEntityLink1411569154000",
                            "dataEntity" : {
                                "type" : "DataObject",
                                "uri": "/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx@1411569154"
                            }
                        }
                    ],
                    "linkingUser" : [
                        {
                            "uri": "userLink1411569154000",
                            "user" : {
                                "uri": "test1"
                            }
                        }   
                    ],
                    "created" : "1411569154000",
                    "description" : "/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.xxx<img src='images/icons/move.png' />/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx",
                    "partOf" :  [ {
                        "type": "Session",
                        "uri": "Unspecified"
                    } ]
                }
            ]
        } ]
    }
    '
Sep 24 10:32:34 pid:17515 NOTICE: execCmd: argv: "amqp://localhost/metaQueue"  "
    {
        \"messages\" : [ {
            \"operation\" : \"create\",
            \"hasPart\" : [
                {
                    \"type\" : \"Access\",
                    \"uri\" : \"/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx@1411569154a1411569154data_object_move\",
                    \"title\" : \"data object move\",
                    \"linkingDataEntity\" : [
                        {
                            \"uri\": \"dataEntityLink1411569154000\",
                            \"dataEntity\" : {
                                \"type\" : \"DataObject\",
                                \"uri\": \"/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx@1411569154\"
                            }
                        }
                    ],
                    \"linkingUser\" : [
                        {
                            \"uri\": \"userLink1411569154000\",
                            \"user\" : {
                                \"uri\": \"test1\"
                            }
                        }   
                    ],
                    \"created\" : \"1411569154000\",
                    \"description\" : \"/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.xxx<img src=\'images/icons/move.png\' />/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx\",
                    \"partOf\" :  [ {
                        \"type\": \"Session\",
                        \"uri\": \"Unspecified\"
                    } ]
                }
            ]
        } ]
    }
    "
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = getFileType: /dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.xxx, 
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = file  "-b"  ""
Sep 24 10:32:34 pid:17522 NOTICE: execCmd: argv: "-b"  ""
Sep 24 10:32:34 pid:17473 ERROR: _rsExecCmd: waitpid status = 17522, myExecCmdOut->status = 0, childStatus = 256
Sep 24 10:32:34 pid:17473 DEBUG: msiExecCmd: rsExecCmd failed for file, status = -344000
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = getFileType: /dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx, /var/lib/irods/iRODS/Vault/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = file  "-b"  "/var/lib/irods/iRODS/Vault/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx"
Sep 24 10:32:34 pid:17523 NOTICE: execCmd:@ argv: "-b"  "/var/lib/irods/iRODS/Vault/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx"
Sep 24 10:32:34 pid:17473 ERROR: _rsExecCmd: waitpid status = 17523, myExecCmdOut->status = 0, childStatus = 256
Sep 24 10:32:34 pid:17473 DEBUG: msiExecCmd: rsExecCmd failed for file, status = -344000
Sep 24 10:32:34 pid:17473 ERROR: converstion returned error code 500
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = acPostProcForOpen: /dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties, 0, dataSize = 1340
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = getObjId of /dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties, social_science_place.properties, /dfc1/home/test1/testLoadSocialSciencePlace
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = /dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = getObjId of /dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties, social_science_place.properties, /dfc1/home/test1/testLoadSocialSciencePlace
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = /dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = ampqSend: sending message '
    {
        "messages" : [ {
            "operation" : "create",
            "hasPart" : [
                {
                    "type" : "Access",
                    "uri" : "/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153a1411569154data_object_get",
                    "title" : "data object get",
                    "linkingDataEntity" : [
                        {
                            "uri": "dataEntityLink1411569154000",
                            "dataEntity" : {
                                "type" : "DataObject",
                                "uri": "/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153"
                            }
                        }
                    ],
                    "linkingUser" : [
                        {
                            "uri": "userLink1411569154000",
                            "user" : {
                                "uri": "test1"
                            }
                        }   
                    ],
                    "created" : "1411569154000",
                    "description" : "<img src='images/icons/get.png' />/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties",
                    "partOf" :  [ {
                        "type": "Session",
                        "uri": "Unspecified"
                    } ]
                }
            ]
        } ]
    }
    '
Sep 24 10:32:34 pid:17526 NOTICE: execCmd: argv: "amqp://localhost/metaQueue"  "
    {
        \"messages\" : [ {
            \"operation\" : \"create\",
            \"hasPart\" : [
                {
                    \"type\" : \"Access\",
                    \"uri\" : \"/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153a1411569154data_object_get\",
                    \"title\" : \"data object get\",
                    \"linkingDataEntity\" : [
                        {
                            \"uri\": \"dataEntityLink1411569154000\",
                            \"dataEntity\" : {
                                \"type\" : \"DataObject\",
                                \"uri\": \"/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153\"
                            }
                        }
                    ],
                    \"linkingUser\" : [
                        {
                            \"uri\": \"userLink1411569154000\",
                            \"user\" : {
                                \"uri\": \"test1\"
                            }
                        }   
                    ],
                    \"created\" : \"1411569154000\",
                    \"description\" : \"<img src=\'images/icons/get.png\' />/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties\",
                    \"partOf\" :  [ {
                        \"type\": \"Session\",
                        \"uri\": \"Unspecified\"
                    } ]
                }
            ]
        } ]
    }
    "
Sep 24 10:32:34 pid:17526 NOTICE: getServerHostByFileInx: fileInx 3 not active
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = getCollId of /dfc1/home/test1/testLoadSocialSciencePlace
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = /dfc1/home/test1/testLoadSocialSciencePlace@1411569154
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = getCollId of /dfc1/trash/home/test1/testLoadSocialSciencePlace
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = /dfc1/trash/home/test1/testLoadSocialSciencePlace@1411569153
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = getObjId of /dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx.2063425283, social_science_place.xxx.2063425283, /dfc1/trash/home/test1/testLoadSocialSciencePlace
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = skip data:id
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = /dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx.2063425283@1411569154
Sep 24 10:32:34 pid:17473 NOTICE: writeLine: inString = ampqSend: sending message '
    {
        "messages": [
            {
                "operation" : "modify",
                "hasPart" : [
                    {
                        "type" : "DataObject",
                        "uri" : "/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx.2063425283@1411569154",
                        "partOf" : [
                            {
                                "type" : "Collection",
                                "uri" : "/dfc1/home/test1/testLoadSocialSciencePlace@1411569154"
                            }
                        ]
                    }, {
                        "type" : "DataObject",
                        "label" : "/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx.2063425283",
                        "partOf" : [
                            {
                                "type" : "Collection",
                                "uri" : "/dfc1/trash/home/test1/testLoadSocialSciencePlace@1411569153"
                            }
                        ]
                    }
                ]               
            }
        ]
    }
    '
Sep 24 10:32:34 pid:17533 NOTICE: execCmd:ÄSŒ°ÿ argv: "amqp://localhost/metaQueue"  "
    {
        \"messages\": [
            {
                \"operation\" : \"modify\",
                \"hasPart\" : [
                    {
                        \"type\" : \"DataObject\",
                        \"uri\" : \"/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx.2063425283@1411569154\",
                        \"partOf\" : [
                            {
                                \"type\" : \"Collection\",
                                \"uri\" : \"/dfc1/home/test1/testLoadSocialSciencePlace@1411569154\"
                            }
                        ]
                    }, {
                        \"type\" : \"DataObject\",
                        \"label\" : \"/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx.2063425283\",
                        \"partOf\" : [
                            {
                                \"type\" : \"Collection\",
                                \"uri\" : \"/dfc1/trash/home/test1/testLoadSocialSciencePlace@1411569153\"
                            }
                        ]
                    }
                ]               
            }
        ]
    }
    "
Sep 24 10:32:35 pid:17473 NOTICE: writeLine: inString = ampqSend: sending message '
    {
        "messages" : [ {
            "operation" : "create",
            "hasPart" : [
                {
                    "type" : "Access",
                    "uri" : "/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx.2063425283@1411569154a1411569155data_object_move",
                    "title" : "data object move",
                    "linkingDataEntity" : [
                        {
                            "uri": "dataEntityLink1411569155000",
                            "dataEntity" : {
                                "type" : "DataObject",
                                "uri": "/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx.2063425283@1411569154"
                            }
                        }
                    ],
                    "linkingUser" : [
                        {
                            "uri": "userLink1411569155000",
                            "user" : {
                                "uri": "test1"
                            }
                        }   
                    ],
                    "created" : "1411569155000",
                    "description" : "/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.xxx<img src='images/icons/move.png' />/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx.2063425283",
                    "partOf" :  [ {
                        "type": "Session",
                        "uri": "Unspecified"
                    } ]
                }
            ]
        } ]
    }
    '
Sep 24 10:32:35 pid:17540 NOTICE: execCmd: argv: "amqp://localhost/metaQueue"  "
    {
        \"messages\" : [ {
            \"operation\" : \"create\",
            \"hasPart\" : [
                {
                    \"type\" : \"Access\",
                    \"uri\" : \"/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx.2063425283@1411569154a1411569155data_object_move\",
                    \"title\" : \"data object move\",
                    \"linkingDataEntity\" : [
                        {
                            \"uri\": \"dataEntityLink1411569155000\",
                            \"dataEntity\" : {
                                \"type\" : \"DataObject\",
                                \"uri\": \"/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx.2063425283@1411569154\"
                            }
                        }
                    ],
                    \"linkingUser\" : [
                        {
                            \"uri\": \"userLink1411569155000\",
                            \"user\" : {
                                \"uri\": \"test1\"
                            }
                        }   
                    ],
                    \"created\" : \"1411569155000\",
                    \"description\" : \"/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.xxx<img src=\'images/icons/move.png\' />/dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx.2063425283\",
                    \"partOf\" :  [ {
                        \"type\": \"Session\",
                        \"uri\": \"Unspecified\"
                    } ]
                }
            ]
        } ]
    }
    "
Sep 24 10:32:35 pid:17473 NOTICE: writeLine: inString = getFileType: /dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.xxx, 
Sep 24 10:32:35 pid:17473 NOTICE: writeLine: inString = file  "-b"  ""
Sep 24 10:32:35 pid:17547 NOTICE: execCmd: argv: "-b"  ""
Sep 24 10:32:35 pid:17473 ERROR: _rsExecCmd: waitpid status = 17547, myExecCmdOut->status = 0, childStatus = 256
Sep 24 10:32:35 pid:17473 DEBUG: msiExecCmd: rsExecCmd failed for file, status = -344000
Sep 24 10:32:35 pid:17473 NOTICE: writeLine: inString = getFileType: /dfc1/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx.2063425283, /var/lib/irods/iRODS/Vault/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx.2063425283
Sep 24 10:32:35 pid:17473 NOTICE: writeLine: inString = file  "-b"  "/var/lib/irods/iRODS/Vault/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx.2063425283"
Sep 24 10:32:35 pid:17548 NOTICE: execCmd:@ argv: "-b"  "/var/lib/irods/iRODS/Vault/trash/home/test1/testLoadSocialSciencePlace/social_science_place.xxx.2063425283"
Sep 24 10:32:35 pid:17473 ERROR: _rsExecCmd: waitpid status = 17548, myExecCmdOut->status = 0, childStatus = 256
Sep 24 10:32:35 pid:17473 DEBUG: msiExecCmd: rsExecCmd failed for file, status = -344000
Sep 24 10:32:35 pid:17473 NOTICE: chlDeleteAVUMetadata cmlExecuteNoAnswerSql delete failure -819000
Sep 24 10:32:35 pid:17473 NOTICE: chlDeleteAVUMetadata cmlExecuteNoAnswerSql(rollback) succeeded
Sep 24 10:32:35 pid:17473 NOTICE: rsModAVUMetadata: rcModAVUMetadata failed
Sep 24 10:32:35 pid:17473 NOTICE: writeLine: inString = processing Conversion Error for /dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties
Sep 24 10:32:35 pid:17473 NOTICE: writeLine: inString = getObjId of /dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties, social_science_place.properties, /dfc1/home/test1/testLoadSocialSciencePlace
Sep 24 10:32:35 pid:17473 NOTICE: writeLine: inString = /dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153
Sep 24 10:32:35 pid:17473 NOTICE: writeLine: inString = ampqSend: sending message '
            {
                "messages" : [
                    {
                        "operation" : "union",
                        "hasPart" : [
                            {
                                "type" : "DataObject",
                                "uri" : "/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153"
                            }, {
                                "type" : "DataObject",
                                "metadata" : [
                                    {
                                        "attribute" : "Conversion Error",
                                        "value" : "xxx",
                                        "unit" : "dest"
                                    }
                                ]
                            }
                        ]

                    }
                ]
            }'
Sep 24 10:32:35 pid:17549 NOTICE: execCmd: argv: "amqp://localhost/metaQueue"  "
            {
                \"messages\" : [
                    {
                        \"operation\" : \"union\",
                        \"hasPart\" : [
                            {
                                \"type\" : \"DataObject\",
                                \"uri\" : \"/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153\"
                            }, {
                                \"type\" : \"DataObject\",
                                \"metadata\" : [
                                    {
                                        \"attribute\" : \"Conversion Error\",
                                        \"value\" : \"xxx\",
                                        \"unit\" : \"dest\"
                                    }
                                ]
                            }
                        ]

                    }
                ]
            }"
Sep 24 10:32:35 pid:17473 NOTICE: writeLine: inString = ampqSend: sending message '
    {
        "messages" : [ {
            "operation" : "create",
            "hasPart" : [
                {
                    "type" : "Access",
                    "uri" : "/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153a1411569155metadata_add",
                    "title" : "metadata add",
                    "linkingDataEntity" : [
                        {
                            "uri": "dataEntityLink1411569155000",
                            "dataEntity" : {
                                "type" : "DataObject",
                                "uri": "/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153"
                            }
                        }
                    ],
                    "linkingUser" : [
                        {
                            "uri": "userLink1411569155000",
                            "user" : {
                                "uri": "test1"
                            }
                        }   
                    ],
                    "created" : "1411569155000",
                    "description" : "Conversion Error metadata add",
                    "partOf" :  [ {
                        "type": "Session",
                        "uri": "Unspecified"
                    } ]
                }
            ]
        } ]
    }
    '
Sep 24 10:32:35 pid:17556 NOTICE: execCmd: argv: "amqp://localhost/metaQueue"  "
    {
        \"messages\" : [ {
            \"operation\" : \"create\",
            \"hasPart\" : [
                {
                    \"type\" : \"Access\",
                    \"uri\" : \"/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153a1411569155metadata_add\",
                    \"title\" : \"metadata add\",
                    \"linkingDataEntity\" : [
                        {
                            \"uri\": \"dataEntityLink1411569155000\",
                            \"dataEntity\" : {
                                \"type\" : \"DataObject\",
                                \"uri\": \"/dfc1/home/test1/testLoadSocialSciencePlace/social_science_place.properties@1411569153\"
                            }
                        }
                    ],
                    \"linkingUser\" : [
                        {
                            \"uri\": \"userLink1411569155000\",
                            \"user\" : {
                                \"uri\": \"test1\"
                            }
                        }   
                    ],
                    \"created\" : \"1411569155000\",
                    \"description\" : \"Conversion Error metadata add\",
                    \"partOf\" :  [ {
                        \"type\": \"Session\",
                        \"uri\": \"Unspecified\"
                    } ]
                }
            ]
        } ]
    }
    "
Sep 24 10:32:35 pid:17473 NOTICE: bindVar[1]=10050
Sep 24 10:32:35 pid:17473 NOTICE: bindVar[2]=10157
Sep 24 10:32:35 pid:17473 NOTICE: bindVar[3]=01411569155
Sep 24 10:32:35 pid:17473 NOTICE: bindVar[4]=01411569155
Sep 24 10:32:35 pid:17473 NOTICE: _cllExecSqlNoResult: SQLExecDirect error: -1 sql:insert into R_OBJT_METAMAP (object_id, meta_id, create_ts, modify_ts) values (?, ?, ?, ?)
Sep 24 10:32:35 pid:17473 NOTICE: SQLSTATE: S1010
Sep 24 10:32:35 pid:17473 NOTICE: SQLCODE: 0
Sep 24 10:32:35 pid:17473 NOTICE: SQL Error message: [unixODBC][Driver Manager]Function sequence error
Sep 24 10:32:35 pid:17473 NOTICE: SQLSTATE: 23505
Sep 24 10:32:35 pid:17473 NOTICE: SQLCODE: 7
Sep 24 10:32:35 pid:17473 NOTICE: SQL Error message: [unixODBC]ERROR: duplicate key value violates unique constraint "idx_objt_metamap1"
Key (object_id, meta_id)=(10050, 10157) already exists.;
Error while executing the query
Sep 24 10:32:35 pid:17473 NOTICE: chlAddAVUMetadata cmlExecuteNoAnswerSql insert failure -809000
Sep 24 10:32:35 pid:17473 NOTICE: chlAddAVUMetadata cmlExecuteNoAnswerSql(rollback) succeeded
Sep 24 10:32:35 pid:17473 NOTICE: rsModAVUMetadata: rcModAVUMetadata failed
Sep 24 10:32:38 pid:17473 NOTICE: readAndProcClientMsg: received disconnect msg from client
Sep 24 10:32:38 pid:17473 NOTICE: Agent exiting with status = 0
Sep 24 10:32:42 pid:17435 NOTICE: Agent process 17473 exited with status 0
Sep 24 10:32:42 pid:17435 NOTICE: Agent process 17564 started for puser=test1 and cuser=test1 from 152.54.15.222
Sep 24 10:32:42 pid:17564 NOTICE: readAndProcClientMsg: received disconnect msg from client
Sep 24 10:32:42 pid:17564 NOTICE: Agent exiting with status = 0
Sep 24 10:32:54 pid:17435 NOTICE: Agent process 17564 exited with status 0
Sep 24 10:32:54 pid:17435 NOTICE: Agent process 17575 started for puser=rods and cuser=rods from 152.54.1.87
Sep 24 10:32:54 pid:17575 NOTICE: readAndProcClientMsg: received disconnect msg from client
Sep 24 10:32:54 pid:17575 NOTICE: Agent exiting with status = 0
Sep 24 10:32:58 pid:17435 NOTICE: Agent process 17575 exited with status 0
Sep 24 10:32:58 pid:17435 NOTICE: Agent process 17582 started for puser=rods and cuser=rods from 152.54.1.87
Sep 24 10:32:58 pid:17582 NOTICE: writeLine: inString = skip data:id
Sep 24 10:32:58 pid:17582 NOTICE: writeLine: inString = getCollId of /dfc1/home/rods
Sep 24 10:32:58 pid:17582 NOTICE: writeLine: inString = skip data:id
Sep 24 10:32:58 pid:17582 NOTICE: writeLine: inString = /dfc1/home/rods@1411569178
Sep 24 10:32:58 pid:17582 NOTICE: writeLine: inString = getCollId of /dfc1/home/rods/logs
Sep 24 10:32:58 pid:17582 NOTICE: writeLine: inString = /dfc1/home/rods/logs@1411569178
Sep 24 10:32:58 pid:17582 NOTICE: writeLine: inString = ampqSend: sending message '
    {
        "messages" : [
            {
                "operation" : "create",
                "hasPart" : [
                    {
                        "type" : "Collection",
                        "uri" : "/dfc1/home/rods/logs@1411569178",
                        "partOf" : [ {
                            "type": "Collection",
                            "uri": "/dfc1/home/rods@1411569178"
                        } ],
                        "label" : "/dfc1/home/rods/logs"
                    }
                ]
            }
        ]
    }'
Sep 24 10:32:58 pid:17587 NOTICE: execCmd: argv: "amqp://localhost/metaQueue"  "
    {
        \"messages\" : [
            {
                \"operation\" : \"create\",
                \"hasPart\" : [
                    {
                        \"type\" : \"Collection\",
                        \"uri\" : \"/dfc1/home/rods/logs@1411569178\",
                        \"partOf\" : [ {
                            \"type\": \"Collection\",
                            \"uri\": \"/dfc1/home/rods@1411569178\"
                        } ],
                        \"label\" : \"/dfc1/home/rods/logs\"
                    }
                ]
            }
        ]
    }"
Sep 24 10:32:58 pid:17582 NOTICE: writeLine: inString = ampqSend: sending message '
    {
        "messages" : [ {
            "operation" : "create",
            "hasPart" : [
                {
                    "type" : "Access",
                    "uri" : "/dfc1/home/rods/logs@1411569178a1411569178collection_create",
                    "title" : "collection create",
                    "linkingDataEntity" : [
                        {
                            "uri": "dataEntityLink1411569178000",
                            "dataEntity" : {
                                "type" : "DataObject",
                                "uri": "/dfc1/home/rods/logs@1411569178"
                            }
                        }
                    ],
                    "linkingUser" : [
                        {
                            "uri": "userLink1411569178000",
                            "user" : {
                                "uri": "rods"
                            }
                        }   
                    ],
                    "created" : "1411569178000",
                    "description" : "/dfc1/home/rods/logs collection create",
                    "partOf" :  [ {
                        "type": "Session",
                        "uri": "Unspecified"
                    } ]
                }
            ]
        } ]
    }
    '
Sep 24 10:32:58 pid:17594 NOTICE: execCmd: argv: "amqp://localhost/metaQueue"  "
    {
        \"messages\" : [ {
            \"operation\" : \"create\",
            \"hasPart\" : [
                {
                    \"type\" : \"Access\",
                    \"uri\" : \"/dfc1/home/rods/logs@1411569178a1411569178collection_create\",
                    \"title\" : \"collection create\",
                    \"linkingDataEntity\" : [
                        {
                            \"uri\": \"dataEntityLink1411569178000\",
                            \"dataEntity\" : {
                                \"type\" : \"DataObject\",
                                \"uri\": \"/dfc1/home/rods/logs@1411569178\"
                            }
                        }
                    ],
                    \"linkingUser\" : [
                        {
                            \"uri\": \"userLink1411569178000\",
                            \"user\" : {
                                \"uri\": \"rods\"
                            }
                        }   
                    ],
                    \"created\" : \"1411569178000\",
                    \"description\" : \"/dfc1/home/rods/logs collection create\",
                    \"partOf\" :  [ {
                        \"type\": \"Session\",
                        \"uri\": \"Unspecified\"
                    } ]
                }
            ]
        } ]
    }
    "
Sep 24 10:32:58 pid:17582 NOTICE: readAndProcClientMsg: received disconnect msg from client
Sep 24 10:32:58 pid:17582 NOTICE: Agent exiting with status = 0