Dec 19, 2018 19:13:37.578 [0x7f7ebd3a1700] INFO - Plex Media Scanner v1.14.1.5488-cc260c476 - ubuntu PC x86_64 - build: linux-ubuntu-x86_64 - GMT 00:00
Dec 19, 2018 19:13:37.578 [0x7f7ebd3a1700] INFO - Linux version: 3.10.105 (#23824 SMP Fri Oct 12 18:29:40 CST 2018), language: en-US
Dec 19, 2018 19:13:37.578 [0x7f7ebd3a1700] INFO - Processor       Intel(R) Pentium(R) CPU  N3710  @ 1.60GHz
Dec 19, 2018 19:13:37.578 [0x7f7ebd3a1700] INFO - /volume1/@appstore/Plex Media Server/Plex Media Scanner --analyze --log-file-suffix  Analysis --item 1887991,1887992,1887993
Dec 19, 2018 19:13:37.501 [0x7f7ec8067800] DEBUG - Opening 20 database sessions to library (com.plexapp.plugins.library), SQLite 3.13.0, threadsafe=1
Dec 19, 2018 19:13:37.922 [0x7f7ec8067800] DEBUG - Analyzing media parts for item 1887991 (P1020139): 1495449
Dec 19, 2018 19:13:37.922 [0x7f7ec8067800] DEBUG - [ID 1496126] Media part analysis: /volume1/photo/photo backup/iPhoto Library/Data/2015/01/26/20150126-224045/zjihFJoyQo2tcIRvlgZIUg/P1020139.jpg
Dec 19, 2018 19:13:38.952 [0x7f7ec8067800] DEBUG - Streams didn't change in analysis, not saving.
Dec 19, 2018 19:13:38.954 [0x7f7ec8067800] DEBUG - Updating media item 1495449, size=29238, metadata_item_id=1887991
Dec 19, 2018 19:13:38.955 [0x7f7ec8067800] DEBUG - Updating part with ID=1496126 [/volume1/photo/photo backup/iPhoto Library/Data/2015/01/26/20150126-224045/zjihFJoyQo2tcIRvlgZIUg/P1020139.jpg]
Dec 19, 2018 19:13:38.965 [0x7f7ec8067800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 19, 2018 19:13:38.965 [0x7f7ec8067800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 19, 2018 19:13:38.970 [0x7f7ec8067800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 19, 2018 19:13:38.971 [0x7f7ec8067800] DEBUG - Updating metadata item (save) (P1020139) with ID 1887991
Dec 19, 2018 19:13:38.977 [0x7f7eb9fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887991&metadataType=13&state=-1&mediaState=analyzing
Dec 19, 2018 19:13:38.980 [0x7f7eb9fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887991&metadataType=13&state=-1&mediaState=analyzing
Dec 19, 2018 19:13:38.981 [0x7f7eb9fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887991&metadataType=13&state=-1&mediaState=thumbnailing
Dec 19, 2018 19:13:38.983 [0x7f7ec8067800] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/services/clustering/process/4/1887991?force=0
Dec 19, 2018 19:13:38.985 [0x7f7eb9fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887991&metadataType=13&state=-1&mediaState=thumbnailing
Dec 19, 2018 19:13:38.986 [0x7f7eb9fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887991&metadataType=13&state=-1&mediaState=idle
Dec 19, 2018 19:13:38.987 [0x7f7ec8067800] DEBUG - HTTP 200 response from PUT http://127.0.0.1:32400/services/clustering/process/4/1887991?force=0
Dec 19, 2018 19:13:38.989 [0x7f7ec8067800] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/services/imagga/process/1887991
Dec 19, 2018 19:13:38.990 [0x7f7eb9fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887991&metadataType=13&state=-1&mediaState=idle
Dec 19, 2018 19:13:38.992 [0x7f7ec8067800] DEBUG - HTTP 200 response from PUT http://127.0.0.1:32400/services/imagga/process/1887991
Dec 19, 2018 19:13:38.999 [0x7f7ec8067800] DEBUG - Analyzing media parts for item 1887992 (P1020139_1024): 1495450
Dec 19, 2018 19:13:39.000 [0x7f7ec8067800] DEBUG - [ID 1496127] Media part analysis: /volume1/photo/photo backup/iPhoto Library/Data/2015/01/26/20150126-224045/zjihFJoyQo2tcIRvlgZIUg/P1020139_1024.jpg
Dec 19, 2018 19:13:39.060 [0x7f7ec8067800] DEBUG - Streams didn't change in analysis, not saving.
Dec 19, 2018 19:13:39.061 [0x7f7ec8067800] DEBUG - Updating media item 1495450, size=171369, metadata_item_id=1887992
Dec 19, 2018 19:13:39.062 [0x7f7ec8067800] DEBUG - Updating part with ID=1496127 [/volume1/photo/photo backup/iPhoto Library/Data/2015/01/26/20150126-224045/zjihFJoyQo2tcIRvlgZIUg/P1020139_1024.jpg]
Dec 19, 2018 19:13:39.085 [0x7f7ec8067800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 19, 2018 19:13:39.086 [0x7f7ec8067800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 19, 2018 19:13:39.090 [0x7f7ec8067800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 19, 2018 19:13:39.091 [0x7f7ec8067800] DEBUG - Updating metadata item (save) (P1020139_1024) with ID 1887992
Dec 19, 2018 19:13:39.095 [0x7f7eb9fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887992&metadataType=13&state=-1&mediaState=analyzing
Dec 19, 2018 19:13:39.098 [0x7f7eb9fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887992&metadataType=13&state=-1&mediaState=analyzing
Dec 19, 2018 19:13:39.099 [0x7f7eb9fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887992&metadataType=13&state=-1&mediaState=thumbnailing
Dec 19, 2018 19:13:39.100 [0x7f7ec8067800] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/services/clustering/process/4/1887992?force=0
Dec 19, 2018 19:13:39.102 [0x7f7eb9fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887992&metadataType=13&state=-1&mediaState=thumbnailing
Dec 19, 2018 19:13:39.103 [0x7f7eb9fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887992&metadataType=13&state=-1&mediaState=idle
Dec 19, 2018 19:13:39.106 [0x7f7eb9fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887992&metadataType=13&state=-1&mediaState=idle
Dec 19, 2018 19:13:39.109 [0x7f7ec8067800] DEBUG - HTTP 200 response from PUT http://127.0.0.1:32400/services/clustering/process/4/1887992?force=0
Dec 19, 2018 19:13:39.117 [0x7f7ec8067800] DEBUG - Analyzing media parts for item 1887993 (P1020139_face0): 1495451
Dec 19, 2018 19:13:39.117 [0x7f7ec8067800] DEBUG - [ID 1496128] Media part analysis: /volume1/photo/photo backup/iPhoto Library/Data/2015/01/26/20150126-224045/zjihFJoyQo2tcIRvlgZIUg/P1020139_face0.jpg
Dec 19, 2018 19:13:40.139 [0x7f7ec8067800] WARN - Waited one whole second for a busy database.
Dec 19, 2018 19:13:41.063 [0x7f7ec8067800] DEBUG - Streams didn't change in analysis, not saving.
Dec 19, 2018 19:13:41.064 [0x7f7ec8067800] DEBUG - Updating media item 1495451, size=46622, metadata_item_id=1887993
Dec 19, 2018 19:13:41.064 [0x7f7ec8067800] DEBUG - Updating part with ID=1496128 [/volume1/photo/photo backup/iPhoto Library/Data/2015/01/26/20150126-224045/zjihFJoyQo2tcIRvlgZIUg/P1020139_face0.jpg]
Dec 19, 2018 19:13:41.071 [0x7f7ec8067800] DEBUG - Downloading document http://127.0.0.1:32400/library/changestamp
Dec 19, 2018 19:13:41.071 [0x7f7ec8067800] DEBUG - HTTP requesting GET http://127.0.0.1:32400/library/changestamp
Dec 19, 2018 19:13:41.075 [0x7f7ec8067800] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/library/changestamp
Dec 19, 2018 19:13:41.076 [0x7f7ec8067800] DEBUG - Updating metadata item (save) (P1020139_face0) with ID 1887993
Dec 19, 2018 19:13:41.079 [0x7f7eb9fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887993&metadataType=13&state=-1&mediaState=analyzing
Dec 19, 2018 19:13:41.082 [0x7f7eb9fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887993&metadataType=13&state=-1&mediaState=analyzing
Dec 19, 2018 19:13:41.083 [0x7f7eb9fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887993&metadataType=13&state=-1&mediaState=thumbnailing
Dec 19, 2018 19:13:41.086 [0x7f7eb9fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887993&metadataType=13&state=-1&mediaState=thumbnailing
Dec 19, 2018 19:13:41.086 [0x7f7eb9fff700] DEBUG - HTTP requesting GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887993&metadataType=13&state=-1&mediaState=idle
Dec 19, 2018 19:13:41.088 [0x7f7ec8067800] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/services/clustering/process/4/1887993?force=0
Dec 19, 2018 19:13:41.089 [0x7f7eb9fff700] DEBUG - HTTP 200 response from GET http://127.0.0.1:32400/:/metadata/notify/changeItemState?librarySectionID=4&metadataItemID=1887993&metadataType=13&state=-1&mediaState=idle
Dec 19, 2018 19:13:41.093 [0x7f7ec8067800] DEBUG - HTTP 200 response from PUT http://127.0.0.1:32400/services/clustering/process/4/1887993?force=0
Dec 19, 2018 19:13:41.094 [0x7f7ec8067800] DEBUG - HTTP requesting PUT http://127.0.0.1:32400/services/imagga/process/1887993
Dec 19, 2018 19:13:41.097 [0x7f7ec8067800] DEBUG - HTTP 200 response from PUT http://127.0.0.1:32400/services/imagga/process/1887993
