This package of data here is causing Ingest to fail in Archivematica 1.7:
To recreate:
- Untar the data and begin transfer
- Transfer will complete but will hang on Normalize -> Validate Preservation Derivatives job
If we look at the MCP Server Log we see a large chunk of MediaConch output, followed by a SQL failure:
2[1]\\" formatid=\\"0xBF\\">0x434FD850</value>\\n </test>\\n <test outcome=\\"pass\\">\\n <value name=\\"CRC-32\\" offset=\\"300\\" context=\\"/Segment[1]/Info[1]/CRC-32[1]\\" formatid=\\"0xBF\\">0xBAB0729C</value>\\n </test>\\n <test outcome=\\"pass\\">\\n <value name=\\"CRC-32\\" offset=\\"387\\" context=\\"/Segment[1]/Tracks[1]/CRC-32[1]\\" formatid=\\"0xBF\\">0xAD6CDF0C</value>\\n </test>\\n <test outcome=\\"pass\\">\\n <value name=\\"CRC-32\\" offset=\\"657\\" context=\\"/Segment[1]/Tags[1]/CRC-32[1]\\" formatid=\\"0xBF\\">0x634624A1</value>\\n </test>\\n <test outcome=\\"pass\\">\\n <value name=\\"CRC-32\\" offset=\\"336713942\\" context=\\"/Segment[1]/Cues[1]/CRC-32[1]\\" formatid=\\"0xBF\\">0x96E4D111</value>\\n </test>\\n </check>\\n <check icid=\\"EBML-CRC-VALID\\" version=\\"1\\" tests_run=\\"5\\" fail_count=\\"0\\" pass_count=\\"5\\">\\n <test outcome=\\"pass\\">\\n <value name=\\"CRC-32\\" offset=\\"65\\" context=\\"/Segment[1]/SeekHead[1]/CRC-32[1]\\" formatid=\\"0xBF\\">0x434FD850</value>\\n </test>\\n <test outcome=\\"pass\\">\\n <value name=\\"CRC-32\\" offset=\\"300\\" context=\\"/Segment[1]/Info[1]/CRC-32[1]\\" formatid=\\"0xBF\\">0xBAB0729C</value>\\n </test>\\n <test outcome=\\"pass\\">\\n <value name=\\"CRC-32\\" offset=\\"387\\" context=\\"/Segment[1]/Tracks[1]/CRC-32[1]\\" formatid=\\"0xBF\\">0xAD6CDF0C</value>\\n </test>\\n <test outcome=\\"pass\\">\\n <value name=\\"CRC-32\\" offset=\\"657\\" context=\\"/Segment[1]/Tags[1]/CRC-32[1]\\" formatid=\\"0xBF\\">0x634624A1</value>\\n </test>\\n <test outcome=\\"pass\\">\\n <value name=\\"CRC-32\\" offset=\\"336713942\\" context=\\"/Segment[1]/Cues[1]/CRC-32[1]\\" formatid=\\"0xBF\\">0x96E4D111</value>\\n </test>\\n </check>\\n <check icid=\\"MKV-VALID-TRACKTYPE-VALUE\\" version=\\"1\\" tests_run=\\"2\\" fail_count=\\"0\\" pass_count=\\"2\\">\\n <context name=\\"Valid Values\\">1 2 3 16 17 18 32</context>\\n <test outcome=\\"pass\\">\\n <value name=\\"TrackType\\" offset=\\"419\\" context=\\"/Segment[1]/Tracks[1]/TrackEntry[1]/TrackType[1]\\" formatid=\\"0x83\\">1</value>\\n <value offset=\\"419\\" name=\\"TrackType\\">1</value>\\n </test>\\n <test outcome=\\"pass\\">\\n <value name=\\"TrackType\\" offset=\\"616\\" context=\\"/Segment[1]/Tracks[1]/TrackEntry[2]/TrackType[1]\\" formatid=\\"0x83\\">2</value>\\n <value offset=\\"616\\" name=\\"TrackType\\">2</value>\\n </test>\\n </check>\\n <check icid=\\"MKV-VALID-BOOLEANS\\" version=\\"1\\" tests_run=\\"2\\" fail_count=\\"0\\" pass_count=\\"2\\">\\n <context name=\\"Valid Values\\">0 1</context>\\n <test outcome=\\"pass\\">\\n <value name=\\"FlagLacing\\" offset=\\"409\\" context=\\"/Segment[1]/Tracks[1]/TrackEntry[1]/FlagLacing[1]\\" formatid=\\"0x9C\\">0</value>\\n <value offset=\\"409\\" name=\\"FlagLacing\\">0</value>\\n </test>\\n <test outcome=\\"pass\\">\\n <value name=\\"FlagLacing\\" offset=\\"591\\" context=\\"/Segment[1]/Tracks[1]/TrackEntry[2]/FlagLacing[1]\\" formatid=\\"0x9C\\">0</value>\\n <value offset=\\"591\\" name=\\"FlagLacing\\">0</value>\\n </test>\\n </check>\\n </implementationChecks>\\n <implementationChecks checks_run=\\"0\\" fail_count=\\"0\\" pass_count=\\"0\\">\\n <name>MediaConch FFV1 Implementation Checker</name>\\n </implementationChecks>\\n <implementationChecks checks_run=\\"1\\" fail_count=\\"0\\" pass_count=\\"1\\">\\n <name>MediaConch PCM Implementation Checker</name>\\n <check icid=\\"PCM-IS-CBR\\" version=\\"1\\" tests_run=\\"1\\" fail_count=\\"0\\" pass_count=\\"1\\">\\n <context name=\\"Valid Values\\">CBR</context>\\n <test outcome=\\"pass\\">\\n <value offset=\\"\\" name=\\"\\">CBR</value>\\n </test>\\n </check>\\n </implementationChecks>\\n </media>\\n</MediaConch>\\n\\r\\n\\n", "eventOutcomeDetailNote": "MediaConch implementation check result: The implementation check MediaConch EBML Implementation Checker returned failure for the following check(s): EBML-ELEMENT-VALID-PARENT."}\n\n'}
archivematica-mcp-server_1 | ERROR 2018-03-09 03:06:01 archivematica.mcp.server:utils:wrapped:16: Uncaught exception
archivematica-mcp-server_1 | Traceback (most recent call last):
archivematica-mcp-server_1 | File "/src/MCPServer/lib/utils.py", line 14, in wrapped
archivematica-mcp-server_1 | return fn(*args, **kwargs)
archivematica-mcp-server_1 | File "/src/archivematicaCommon/lib/databaseFunctions.py", line 47, in wrapper
archivematica-mcp-server_1 | return f(*args, **kwargs)
archivematica-mcp-server_1 | File "/src/MCPServer/lib/taskStandard.py", line 91, in performTask
archivematica-mcp-server_1 | self.check_request_status(completed_job_request)
archivematica-mcp-server_1 | File "/src/MCPServer/lib/taskStandard.py", line 100, in check_request_status
archivematica-mcp-server_1 | self.linkTaskManager.taskCompletedCallBackFunction(self)
archivematica-mcp-server_1 | File "/src/MCPServer/lib/linkTaskManagerFiles.py", line 143, in taskCompletedCallBackFunction
archivematica-mcp-server_1 | databaseFunctions.logTaskCompletedSQL(task)
archivematica-mcp-server_1 | File "/src/archivematicaCommon/lib/databaseFunctions.py", line 263, in logTaskCompletedSQL
archivematica-mcp-server_1 | task.save()
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/models/base.py", line 734, in save
archivematica-mcp-server_1 | force_update=force_update, update_fields=update_fields)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/models/base.py", line 762, in save_base
archivematica-mcp-server_1 | updated = self._save_table(raw, cls, force_insert, force_update, using, update_fields)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/models/base.py", line 827, in _save_table
archivematica-mcp-server_1 | forced_update)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/models/base.py", line 877, in _do_update
archivematica-mcp-server_1 | return filtered._update(values) > 0
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/models/query.py", line 580, in _update
archivematica-mcp-server_1 | return query.get_compiler(self.db).execute_sql(CURSOR)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/models/sql/compiler.py", line 1062, in execute_sql
archivematica-mcp-server_1 | cursor = super(SQLUpdateCompiler, self).execute_sql(result_type)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/models/sql/compiler.py", line 840, in execute_sql
archivematica-mcp-server_1 | cursor.execute(sql, params)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/backends/utils.py", line 64, in execute
archivematica-mcp-server_1 | return self.cursor.execute(sql, params)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/utils.py", line 98, in __exit__
archivematica-mcp-server_1 | six.reraise(dj_exc_type, dj_exc_value, traceback)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/backends/utils.py", line 64, in execute
archivematica-mcp-server_1 | return self.cursor.execute(sql, params)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/backends/mysql/base.py", line 124, in execute
archivematica-mcp-server_1 | return self.cursor.execute(query, args)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/MySQLdb/cursors.py", line 226, in execute
archivematica-mcp-server_1 | self.errorhandler(self, exc, value)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/MySQLdb/connections.py", line 36, in defaulterrorhandler
archivematica-mcp-server_1 | raise errorvalue
archivematica-mcp-server_1 | OperationalError: (2006, 'MySQL server has gone away')
archivematica-mcp-server_1 | Exception in thread Thread-1105:
archivematica-mcp-server_1 | Traceback (most recent call last):
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/threading.py", line 801, in __bootstrap_inner
archivematica-mcp-server_1 | self.run()
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/threading.py", line 754, in run
archivematica-mcp-server_1 | self.__target(*self.__args, **self.__kwargs)
archivematica-mcp-server_1 | File "/src/MCPServer/lib/utils.py", line 14, in wrapped
archivematica-mcp-server_1 | return fn(*args, **kwargs)
archivematica-mcp-server_1 | File "/src/archivematicaCommon/lib/databaseFunctions.py", line 47, in wrapper
archivematica-mcp-server_1 | return f(*args, **kwargs)
archivematica-mcp-server_1 | File "/src/MCPServer/lib/taskStandard.py", line 91, in performTask
archivematica-mcp-server_1 | self.check_request_status(completed_job_request)
archivematica-mcp-server_1 | File "/src/MCPServer/lib/taskStandard.py", line 100, in check_request_status
archivematica-mcp-server_1 | self.linkTaskManager.taskCompletedCallBackFunction(self)
archivematica-mcp-server_1 | File "/src/MCPServer/lib/linkTaskManagerFiles.py", line 143, in taskCompletedCallBackFunction
archivematica-mcp-server_1 | databaseFunctions.logTaskCompletedSQL(task)
archivematica-mcp-server_1 | File "/src/archivematicaCommon/lib/databaseFunctions.py", line 263, in logTaskCompletedSQL
archivematica-mcp-server_1 | task.save()
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/models/base.py", line 734, in save
archivematica-mcp-server_1 | force_update=force_update, update_fields=update_fields)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/models/base.py", line 762, in save_base
archivematica-mcp-server_1 | updated = self._save_table(raw, cls, force_insert, force_update, using, update_fields)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/models/base.py", line 827, in _save_table
archivematica-mcp-server_1 | forced_update)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/models/base.py", line 877, in _do_update
archivematica-mcp-server_1 | return filtered._update(values) > 0
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/models/query.py", line 580, in _update
archivematica-mcp-server_1 | return query.get_compiler(self.db).execute_sql(CURSOR)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/models/sql/compiler.py", line 1062, in execute_sql
archivematica-mcp-server_1 | cursor = super(SQLUpdateCompiler, self).execute_sql(result_type)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/models/sql/compiler.py", line 840, in execute_sql
archivematica-mcp-server_1 | cursor.execute(sql, params)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/backends/utils.py", line 64, in execute
archivematica-mcp-server_1 | return self.cursor.execute(sql, params)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/utils.py", line 98, in __exit__
archivematica-mcp-server_1 | six.reraise(dj_exc_type, dj_exc_value, traceback)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/backends/utils.py", line 64, in execute
archivematica-mcp-server_1 | return self.cursor.execute(sql, params)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/django/db/backends/mysql/base.py", line 124, in execute
archivematica-mcp-server_1 | return self.cursor.execute(query, args)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/MySQLdb/cursors.py", line 226, in execute
archivematica-mcp-server_1 | self.errorhandler(self, exc, value)
archivematica-mcp-server_1 | File "/usr/local/lib/python2.7/site-packages/MySQLdb/connections.py", line 36, in defaulterrorhandler
archivematica-mcp-server_1 | raise errorvalue
archivematica-mcp-server_1 | OperationalError: (2006, 'MySQL server has gone away')
This has been seen with other transfer material, but wasn't measured and recreated under the same control circumstances as here.
Will investigate more as I get an opportunity.
Type: bug