First chunk load extremely slow

XLordalX commented 2 years ago

Describe the issue The first world loaded by ASWM takes a lot of time to load the spawn chunk (world loading itself is fast). Any world loaded after the first one does not have this issue (it's super fast).

Spigot version This server is running Paper version git-Paper-"e294802" (MC: 1.19) (Implementing API version 1.19-R0.1-SNAPSHOT) (Git: e294802)

Plugin version 2.9.0

How to reproduce

Load a world using the following code:

val swm = SWMPlugin.getInstance()

val slimeWorld = swm.loadWorld(
    slimeLoader,
    worldName,
    false,
    SlimePropertyMap().apply {
        setValue(SlimeProperties.DIFFICULTY, "normal")
    }
)

swm.generateWorld(slimeWorld)

val world = Bukkit.getWorld(worldName)
    ?: throw java.lang.IllegalStateException("Generated slime world does not exist.")

world.setGameRule(GameRule.ANNOUNCE_ADVANCEMENTS, false)

Crash reports (if available)

Not actually a crash, the chunk just takes a lot of time to load:

[15:23:15 ERROR]: The server has not responded for 10 seconds! Creating thread dump
[15:23:15 ERROR]: ------------------------------
[15:23:15 ERROR]: Server thread dump (Look for plugins here before reporting to Paper!):
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread] Chunk wait task info below:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread] -1,-1 in 'e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread] Load Task - none
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread] Save Task - none
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread] Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread] Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread] Chunk Ticket Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread] Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread] Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread] Chunk Neighbors:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   -2,-2 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   Chunk Ticket Status - minecraft:features
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   0,0 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   Chunk Ticket Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   Chunk Neighbors:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     -1,1 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     Chunk Ticket Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     Chunk Neighbors:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       0,0 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       -1,0 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Ticket Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Neighbors:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         0,0 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         -1,1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         -2,1 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Ticket Status - minecraft:features
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         0,-1 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Ticket Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Neighbors: (Can't show, too deeply nested)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         0,1 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Ticket Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Neighbors: (Can't show, too deeply nested)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         -1,-1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         -2,0 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Ticket Status - minecraft:features
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         -2,-1 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Ticket Status - minecraft:features
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       -2,1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       0,1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       -2,0 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       -2,2 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Ticket Status - minecraft:features
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       -1,2 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Ticket Status - minecraft:features
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       0,2 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Ticket Status - minecraft:features
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     -1,0 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     1,0 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     Chunk Ticket Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     Chunk Neighbors:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       0,0 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       2,0 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Ticket Status - minecraft:features
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       2,1 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Ticket Status - minecraft:features
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       2,-1 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Ticket Status - minecraft:features
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       0,-1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       0,1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       1,1 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Ticket Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Neighbors:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         0,0 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         1,0 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         2,0 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         2,1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         0,1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         1,2 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Ticket Status - minecraft:features
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         2,2 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Ticket Status - minecraft:features
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         0,2 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       1,-1 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Ticket Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]       Chunk Neighbors:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         2,-2 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Ticket Status - minecraft:features
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         0,0 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         1,0 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         2,0 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         2,-1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         0,-1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         1,-2 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Ticket Status - minecraft:features
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         0,-2 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Ticket Status - minecraft:features
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]         Chunk Holder Priority - 46
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     0,-1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     0,1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     -1,-1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     1,1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]     1,-1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   -1,0 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   0,-1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   -2,0 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   0,-2 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   -2,-1 in e6961694-053c-4ce2-9b8e-c50c966955f1 (CIRCULAR)
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   -1,-2 in e6961694-053c-4ce2-9b8e-c50c966955f1:
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   Chunk Holder - non-null
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   Chunk Status - minecraft:full
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   Chunk Ticket Status - minecraft:features
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   Chunk Holder Status - minecraft:liquid_carvers
[15:23:15 ERROR]: [com.destroystokyo.paper.io.PaperFileIOThread]   Chunk Holder Priority - 46
[15:23:15 ERROR]: ------------------------------
[15:23:15 ERROR]: Current Thread: Server thread
[15:23:15 ERROR]:       PID: 24 | Suspended: false | Native: false | State: TIMED_WAITING
[15:23:15 ERROR]:       Stack:
[15:23:15 ERROR]:               java.base@17.0.1/jdk.internal.misc.Unsafe.park(Native Method)
[15:23:15 ERROR]:               java.base@17.0.1/java.util.concurrent.locks.LockSupport.parkNanos(LockSupport.java:252)
[15:23:15 ERROR]:               net.minecraft.util.thread.BlockableEventLoop.waitForTasks(BlockableEventLoop.java:148)
[15:23:15 ERROR]:               net.minecraft.util.thread.BlockableEventLoop.managedBlock(BlockableEventLoop.java:137)
[15:23:15 ERROR]:               net.minecraft.server.level.ServerChunkCache.getChunk(ServerChunkCache.java:661)
[15:23:15 ERROR]:               net.minecraft.world.level.chunk.ChunkSource.getChunk(ChunkSource.java:13)
[15:23:15 ERROR]:               io.papermc.paper.util.CollisionUtil.getCollisionsForBlocksOrWorldBorder(CollisionUtil.java:483)
[15:23:15 ERROR]:               net.minecraft.world.level.CollisionGetter.noCollision(CollisionGetter.java:40)
[15:23:15 ERROR]:               net.minecraft.server.players.PlayerList.respawn(PlayerList.java:949)
[15:23:15 ERROR]:               net.minecraft.server.players.PlayerList.respawn(PlayerList.java:829)
[15:23:15 ERROR]:               org.bukkit.craftbukkit.v1_19_R1.entity.CraftPlayer.teleport(CraftPlayer.java:1197)
[15:23:15 ERROR]:               org.bukkit.craftbukkit.v1_19_R1.entity.CraftEntity.teleport(CraftEntity.java:569)
[15:23:15 ERROR]:               ShadedCoreBukkit.jar//gg.shaded.core.bukkit.entity.EntityKt.ejectAndTeleport(Entity.kt:17)
[15:23:15 ERROR]:               Shyblock.jar//gg.shaded.shyblock.island.locator.LocalIslandLocation.summon$lambda-0(LocalIslandLocation.kt:24)
[15:23:15 ERROR]:               Shyblock.jar//gg.shaded.shyblock.island.locator.LocalIslandLocation$$Lambda$7777/0x00000008023c2cf0.accept(Unknown Source)
[15:23:15 ERROR]:               ShadedCoreBukkit.jar//io.reactivex.rxjava3.internal.operators.single.SingleDoOnSuccess$DoOnSuccess.onSuccess(SingleDoOnSuccess.java:54)
[15:23:15 ERROR]:               ShadedCoreBukkit.jar//io.reactivex.rxjava3.internal.operators.single.SingleObserveOn$ObserveOnSingleObserver.run(SingleObserveOn.java:81)
[15:23:15 ERROR]:               ShadedCoreBukkit.jar//io.reactivex.rxjava3.internal.schedulers.ExecutorScheduler$ExecutorWorker$BooleanRunnable.run(ExecutorScheduler.java:324)
[15:23:15 ERROR]:               org.bukkit.craftbukkit.v1_19_R1.scheduler.CraftTask.run(CraftTask.java:101)
[15:23:15 ERROR]:               org.bukkit.craftbukkit.v1_19_R1.scheduler.CraftScheduler.mainThreadHeartbeat(CraftScheduler.java:483)
[15:23:15 ERROR]:               net.minecraft.server.MinecraftServer.tickChildren(MinecraftServer.java:1490)
[15:23:15 ERROR]:               net.minecraft.server.dedicated.DedicatedServer.tickChildren(DedicatedServer.java:446)
[15:23:15 ERROR]:               net.minecraft.server.MinecraftServer.tickServer(MinecraftServer.java:1414)
[15:23:15 ERROR]:               net.minecraft.server.MinecraftServer.runServer(MinecraftServer.java:1187)
[15:23:15 ERROR]:               net.minecraft.server.MinecraftServer.lambda$spin$0(MinecraftServer.java:302)
[15:23:15 ERROR]:               net.minecraft.server.MinecraftServer$$Lambda$4504/0x000000080156f080.run(Unknown Source)
[15:23:15 ERROR]:               java.base@17.0.1/java.lang.Thread.run(Thread.java:833)

YouHaveTrouble commented 2 years ago

I also experienced this. Never to the point of watchdog reporting a hang, but it seems to happen when no worlds have been loaded on server boot, first world load takes extremely long, subsequent ones are fast as normal

XLordalX commented 2 years ago

I also experienced this. Never to the point of watchdog reporting a hang, but it seems to happen when no worlds have been loaded on server boot, first world load takes extremely long, subsequent ones are fast as normal

Were you ever able to figure out what causes this behavior?

HookWoods commented 2 years ago

Yup I had an experience like that long time ago, and found it was coming from ChunkTaskPriorityQueueSorter getProcessor in ChunkMap constructor, so it's coming from Minecraft with SWM, but don't know why

ComputerNerd100 commented 2 years ago

Thanks for this report and for including the thread dump. It seems we might need to dig into what Minecraft is doing in 1.19 a little bit more - we've already had issues with default world overriding so I'll see if we can work out what's causing this to take so long. Can I ask how big the world is you're loading? And can we get a copy of the world too so that we can test with this world during debugging?

YouHaveTrouble commented 2 years ago

I don't think the size of the world matters, it happened to me with a 1 chunk world with just a 16x16 stone box in it. I suspect that if there isn't a world loaded on boot there are some caches that fill up for the first time they're used and that causes the first world loaded to halt the thread. Just a theory, I didn't have time to look into it

XLordalX commented 2 years ago

Thanks for this report and for including the thread dump. It seems we might need to dig into what Minecraft is doing in 1.19 a little bit more - we've already had issues with default world overriding so I'll see if we can work out what's causing this to take so long. Can I ask how big the world is you're loading? And can we get a copy of the world too so that we can test with this world during debugging?

Here it is in Base64. It's a small starter island. https://www.toptal.com/developers/hastebin/raw/gumugifazo

Owen1212055 commented 2 years ago

Is this only on 1.19?

XLordalX commented 2 years ago

Is this only on 1.19?

No, I had the same issue on 1.18

Owen1212055 commented 2 years ago

Can you please send your config files? Does this happen each time btw?

XLordalX commented 2 years ago

Can you please send your config files? Does this happen each time btw?

It happens every first time after a reboot. So in other words, I restart the server, I load a world using the ASWM API and I teleport a player to the world's spawn chunk. The teleport then causes the chunk to load and this is the part that takes around ~15 seconds and freezes the server. But again, only the first time after a reboot.

Paper config: https://pastebin.com/w7wFA2MH Spigot config: https://pastebin.com/1ZeJWaaj

I'm assuming you meant those config files? If you need anything else, let me know.

tehchuanyeow commented 2 years ago

I'm having issues with the overworld overriding the worlds as well. And often before loading it lags the whole server.

Paul19988 commented 2 years ago

Overriding default worlds on 1.19 does not work, it's an issue we cannot get around at this time I'm afraid.

Paul19988 / Advanced-Slime-World-Manager

First chunk load extremely slow #141