[0.17.4] [MacOS] Delays in control

This subforum contains all the issues which we already resolved.
queenjazz
Burner Inserter
Burner Inserter
Posts: 5
Joined: Tue Feb 26, 2019 9:03 pm
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by queenjazz »

Yeah I've been checking with every version just in case and the game just is not playable at all unfortunately. It'd be good to hear from the devs what the plans are for the mac issues, just to have an idea of when they will be approached, but I understand they probably aren't prioritizing it much at the moment
posila
Factorio Staff
Factorio Staff
Posts: 5357
Joined: Thu Jun 11, 2015 1:35 pm
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by posila »

I am almost afraid to ask after 3 pages of posts ... but, can you all please post your Factorio logs?
User avatar
ssilk
Global Moderator
Global Moderator
Posts: 12889
Joined: Tue Apr 16, 2013 10:35 pm
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by ssilk »

Ok here: I started the scenario twice, one without sync and the second time in fullscreen with sync. But that doesn't reflect in logs.

Code: Select all

   0.001 2019-03-02 20:15:05; Factorio 0.17.4 (build 43174, mac, alpha)
   0.001 Operating system: macOS 10.14.3
   0.001 Program arguments: "/Applications/factorio.app/Contents/MacOS/factorio" 
   0.002 Read data path: /Applications/factorio.app/Contents/data
   0.002 Write data path: /Users/ssilk/Library/Application Support/factorio [43707/476902MB]
   0.002 Binaries path: /Applications/factorio.app/Contents
   0.017 System info: [CPU: Intel(R) Core(TM) i7-4578U CPU @ 3.00GHz, 4 cores, RAM: 16384 MB]
   0.017 Display options: [FullScreen: 0] [VSync: 0] [UIScale: automatic (100.0%)] [MultiSampling: OFF] [Screen: 255] [Lang: en]
   0.149 Available displays: 1
   0.149  [0]: Color LCD - {[0,0], 1280x800, SDL_PIXELFORMAT_ARGB8888, 60Hz}
   0.221 Initialised OpenGL:[0] Intel Iris OpenGL Engine; driver: 4.1 INTEL-12.4.7
   0.221   [Extensions] s3tc:yes; KHR_debug:NO; ARB_clear_texture:NO, ARB_copy_image:NO
   0.221   [Version] 4.1
   0.222 Verbose GraphicsInterfaceOpenGL.cpp:751: [Caps] Tex:16384, TexArr:2048, TexBufSz:65536kB; TexUnits:80; UboSz:64kB
   0.358 Verbose PipelineStateObject.cpp:82: Time to load shaders: 0.136072 seconds.
   0.362 Graphics settings preset: high
   0.362   Dedicated video memory size 1536 MB (detected from Iris)
   0.362 Graphics options: [Graphics quality: normal] [Video memory usage: all] [Light scale: 25%] [DXT: high-quality]
   0.362                   [Max load threads: 32] [Max texture size: 0] [Tex.Stream.: 0] [Low quality rotation: 1]
   0.796 Loading mod settings FARL 3.0.0 (settings.lua)
   0.796 Loading mod settings AutoDeconstruct 0.1.12 (settings.lua)
   0.797 Loading mod settings Blue-Ghosts 0.17.1 (settings.lua)
   0.797 Loading mod settings BlueprintExtensions 0.3.1 (settings.lua)
   0.797 Loading mod settings cargo-ships 0.0.28 (settings.lua)
   0.797 Loading mod settings ClosestFirst 0.17.2 (settings.lua)
   0.798 Loading mod settings GhostScanner 1.4.0 (settings.lua)
   0.798 Loading mod settings Inventory Sensor 1.7.0 (settings.lua)
   0.798 Loading mod settings LoaderRedux 1.4.3 (settings.lua)
   0.798 Loading mod settings qol_research 2.3.0 (settings.lua)
   0.802 Loading mod settings Todo-List 17.0.0 (settings.lua)
   0.802 Loading mod settings LogisticTrainNetwork 1.10.0 (settings.lua)
   0.803 Loading mod settings LTN_Content_Reader 0.2.0 (settings.lua)
   0.812 Loading mod core 0.0.0 (data.lua)
   0.921 Loading mod base 0.17.4 (data.lua)
   1.198 Loading mod FARL 3.0.0 (data.lua)
   1.313 Loading mod Auto_Deploy_Destroyers 0.1.7 (data.lua)
   1.422 Loading mod Blue-Ghosts 0.17.1 (data.lua)
   1.536 Loading mod BlueprintExtensions 0.3.1 (data.lua)
   1.648 Loading mod cargo-ships 0.0.28 (data.lua)
   1.824 Loading mod EvoGUI 0.4.401 (data.lua)
   1.941 Loading mod GhostScanner 1.4.0 (data.lua)
   2.047 Loading mod Inventory Sensor 1.7.0 (data.lua)
   2.153 Loading mod LoaderRedux 1.4.3 (data.lua)
   2.257 Loading mod qol_research 2.3.0 (data.lua)
   2.371 Loading mod RealisticReactorGlow 1.17.2 (data.lua)
   2.485 Loading mod Robo-Charge-1x1 0.17.0 (data.lua)
   2.596 Loading mod Todo-List 17.0.0 (data.lua)
   2.711 Loading mod train-scaling 0.17.0 (data.lua)
   2.835 Loading mod Vehicle_Radar 0.5.0 (data.lua)
   2.944 Loading mod Waterbomb 0.17.1 (data.lua)
   3.067 Loading mod LogisticTrainNetwork 1.10.0 (data.lua)
   3.177 Loading mod LTN_Content_Reader 0.2.0 (data.lua)
   3.310 Loading mod base 0.17.4 (data-updates.lua)
   3.471 Loading mod FARL 3.0.0 (data-updates.lua)
   3.595 Loading mod ClosestFirst 0.17.2 (data-updates.lua)
   3.769 Loading mod LoaderRedux 1.4.3 (data-updates.lua)
   4.017 Loading mod train-scaling 0.17.0 (data-updates.lua)
   4.165 Loading mod FARL 3.0.0 (data-final-fixes.lua)
   4.294 Loading mod cargo-ships 0.0.28 (data-final-fixes.lua)
   4.420 Loading mod LogisticTrainNetwork 1.10.0 (data-final-fixes.lua)
   4.427 Script @__LogisticTrainNetwork__/data-final-fixes.lua:99: [LTN] found 295 items, 8 fluids, 4 locomotives, 6 wagons
   4.569 Loading mod LTN_Content_Reader 0.2.0 (data-final-fixes.lua)
   4.572 Script @__LTN_Content_Reader__/data-final-fixes.lua:17: [LTN Content Reader] setting combinator slots to 304
   4.732 Checksum for core: 3928040144
   4.732 Checksum of base: 60305742
   4.732 Checksum of FARL: 4093962643
   4.732 Checksum of AutoDeconstruct: 2582707891
   4.732 Checksum of Auto_Deploy_Destroyers: 3221460519
   4.732 Checksum of Blue-Ghosts: 1543450339
   4.732 Checksum of BlueprintExtensions: 1908590405
   4.732 Checksum of cargo-ships: 1977624544
   4.732 Checksum of ClosestFirst: 2913986104
   4.732 Checksum of EvoGUI: 3279161307
   4.732 Checksum of GhostScanner: 3992300921
   4.732 Checksum of Inventory Sensor: 3778797410
   4.732 Checksum of LoaderRedux: 4273848446
   4.732 Checksum of qol_research: 3931501733
   4.732 Checksum of RealisticReactorGlow: 567106501
   4.732 Checksum of Robo-Charge-1x1: 3067002289
   4.732 Checksum of Todo-List: 698491824
   4.732 Checksum of train-scaling: 244608136
   4.732 Checksum of Vehicle_Radar: 1153364161
   4.732 Checksum of Waterbomb: 2782655202
   4.732 Checksum of LogisticTrainNetwork: 983849668
   4.732 Checksum of LTN_Content_Reader: 3474598911
   5.434 Verbose ModManager.cpp:466: Time to load mods: 4.83458
   5.477 Loading sounds...
   5.778 Info PlayerData.cpp:67: Local player-data.json available, timestamp 1551484862
   5.778 Info PlayerData.cpp:74: Cloud player-data.json unavailable
   6.032 Initial atlas bitmap size is 16384
   6.036 Created atlas bitmap 16384x9252 [none]
   6.036 Created atlas bitmap 16384x688 [decal]
   6.037 Created atlas bitmap 16384x1312 [low-object]
   6.037 Created atlas bitmap 16384x656 [mipmap, linear-minification, linear-magnification, linear-mip-level, no-crop, no-scale]
   6.037 Created atlas bitmap 16384x2096 [terrain, mipmap, linear-minification, linear-mip-level, no-crop]
   6.037 Created atlas bitmap 16384x480 [smoke, mipmap, linear-minification, linear-magnification]
   6.037 Created atlas bitmap 16384x528 [not-compressed, mipmap, linear-minification, linear-magnification, linear-mip-level, no-crop, no-scale]
   6.037 Created atlas bitmap 12x12 [low-object, not-compressed, mipmap, linear-minification, linear-magnification, linear-mip-level, no-crop, no-scale]
   6.038 Created atlas bitmap 16384x216 [alpha-mask]
   6.041 Created atlas bitmap 16384x3776 [shadow, linear-magnification, alpha-mask]
   6.041 Created atlas bitmap 16384x384 [shadow, mipmap, linear-magnification, alpha-mask]
   6.357 Verbose TextureProcessor.cpp:839: GPU accelerated compression test: Time 316.068 ms; YCoCg_DXT PSNR: 35.83, BC3 PSNR: 33.82
   6.358 Texture processor created. GPU accelerated compression Supported: yes, Enabled: yes/yes. Test passed. YCoCgDXT PSNR: 35.83, BC3 PSNR: 33.82
   8.781 Parallel Sprite Loader initialized (threads: 3)
  18.126 Sprites loaded
  18.481 Generated mipmaps for atlas of size 16384x1312
  18.771 Generated mipmaps for atlas of size 16384x656
  19.402 Generated mipmaps for atlas of size 16384x2096
  19.880 Generated mipmaps for atlas of size 16384x480
  19.960 Generated mipmaps for atlas of size 16384x528
  19.973 Generated mipmaps for atlas of size 12x12
  20.038 Generated mipmaps for atlas of size 16384x384
  20.038 Verbose AtlasSystem.cpp:806: Atlas memory size: 381.16MB; 0.00MB (virtual)
  20.038 Verbose AtlasSystem.cpp:807: Size of sprites outside of atlas: 0.00MB
  20.051 Custom inputs active: 25
  20.117 Factorio initialised
  20.119 Info HttpSharedState.cpp:54: Downloading https://updater.factorio.com/updater/get-available-versions?username=ssilk&token=<private>&apiVersion=2
  21.558 Info HttpSharedState.cpp:147: Status code: 200
  21.558 0 packages available to download (experimental updates enabled).
  28.568 Error CachedChangelog.cpp:37: Failed to parse changelog for mod Air Filtering Advanced: invalid changelog file, error on line 1.
  28.575 Info HttpSharedState.cpp:54: Downloading https://mods.factorio.com/api/mods?page_size=max&namelist=air-filtering-patched&namelist=AutoDeconstruct&namelist=autofill&namelist=AutomaticTrainBuilder&namelist=AutoTrash&namelist=Auto_Deploy_Destroyers&namelist=axial-tilt&namelist=Blue-Ghosts&namelist=blueprint-train&namelist=BlueprintExtensions&namelist=Blueprint_Flip_Turn&namelist=Blueprint_Shortcuts&namelist=Bot%20Landfill&namelist=bullet-trails&namelist=cargo-ships&namelist=CliffDeconstruct&namelist=ClosestFirst&namelist=ConstructionSignaler&namelist=CopyAndPasteModules&namelist=DeadlockCrating&namelist=DeleteEmptyChunks&namelist=Dirt_Path&namelist=entity-symmetry&namelist=EquipmentHotkeys_fixed&namelist=er-quickprint&namelist=EvoGUI&namelist=FARL&namelist=fast-filter-fill&namelist=folk-stopthat&namelist=Foreman&namelist=free_circuit_wires&namelist=GhostScanner&namelist=Inventory%20Sensor&namelist=ItemFinder&namelist=keep-organized&namelist=Laser_Beam_Turrets&namelist=LoaderRedux&namelist=LogisticRequestHinter&namelist=LogisticTrainNetwork&namelist=long-reach&namelist=LTN_Content_Reader&namelist=Moar-Radar&namelist=MoreLogisticSlots&namelist=nixie-tubes&namelist=Orbital%20Ion%20Cannon&namelist=OutpostPlanner&namelist=OverloadedTrains&namelist=PickerExtended&namelist=PlannerCore&namelist=qol_research&namelist=RealisticReactorGlow&namelist=recursive-blueprints&namelist=Robo-Charge-1x1&namelist=ScanningRadar&namelist=ScoreExtended&namelist=SmartDisplayRedux&namelist=tape_measure_tool&namelist=textplates&namelist=TheFatController_0-16_Fix&namelist=Todo-List&namelist=train-scaling&namelist=TrainToggle&namelist=upgrade-planner&namelist=Vehicle_Radar&namelist=Waterbom
  30.632 Info HttpSharedState.cpp:147: Status code: 200
  41.080 Info PrototypeMigrationList.cpp:60: Activating migration cargo-ships/migrations-0-0-23.json
  41.080 Info PrototypeMigrationList.cpp:60: Activating migration cargo-ships/migrations_3.json
  41.080 Info PrototypeMigrationList.cpp:60: Activating migration Inventory Sensor/1.4.6.json
  41.080 Info PrototypeMigrationList.cpp:60: Activating migration LoaderRedux/0.1.1.json
  41.080 Info PrototypeMigrationList.cpp:60: Activating migration Waterbomb/Waterbomb_0.16.5.json
  41.080 Info PrototypeMigrationList.cpp:60: Activating migration LogisticTrainNetwork/1.3.0.json
  41.425 Verbose CompiledMapGenSettings.cpp:484: MapGenSettings compilation took 0.319982 seconds; deduplicated 88 procedures and 1444 sub-expressions; resulting program has 105 procedures, 3507 operations, and 125 registers
  41.762 Verbose CompiledMapGenSettings.cpp:484: MapGenSettings compilation took 0.320107 seconds; deduplicated 88 procedures and 1444 sub-expressions; resulting program has 105 procedures, 3507 operations, and 125 registers
  41.786 Verbose BlueprintLibrary.cpp:245: Loaded library shelves:
  41.786 Verbose BlueprintLibrary.cpp:867: Game shelf: playerIndex = 65535, nextRecordID = 0; timestamp = 0; records:
  41.786 Verbose BlueprintLibrary.cpp:869: Player shelf: playerIndex = 0, nextRecordID = 0; timestamp = 1550685097; records:
  41.806 Verbose BlueprintLibrary.cpp:64: Loaded external blueprint storage: playerIndex = 0, nextRecordID = 34; timestamp = 1514045845; records: (id: 0, 0; label: "", preview: false, empty: false; book) (id: 0, 8; label: "", preview: false, empty: false; book [id: 0, 1; label: "", preview: false, empty: false] [id: 0, 2; label: "", preview: false, empty: false] [id: 0, 3; label: "Miner24", preview: false, empty: false] [id: 0, 4; label: "", preview: false, empty: false] [id: 0, 5; label: "Destroyers", preview: false, empty: false] [id: 0, 6; label: "", preview: false, empty: false] [id: 0, 7; label: "", preview: false, empty: false]) (id: 0, 31; label: "General", preview: false, empty: false; book [id: 0, 16; label: "Miner24", preview: false, empty: false] [id: 0, 17; label: "", preview: false, empty: false] [id: 0, 18; label: "Destroyers", preview: false, empty: false] [id: 0, 19; label: "", preview: false, empty: false] [id: 0, 20; label: "", preview: false, empty: false] [id: 0, 21; label: "Laser Defense", preview: false, empty: false] [id: 0, 22; label: "2 Roboports", preview: false, empty: false] [id: 0, 23; label: "", preview: false, empty: false] [id: 0, 24; label: "", preview: false, empty: false] [id: 0, 25; label: "Rail Crossing", preview: false, empty: false] [id: 0, 26; label: "Logistic to LTN Filter", preview: false, empty: false] [id: 0, 27; label: "", preview: false, empty: false] [id: 0, 28; label: "Robot Requester", preview: false, empty: false] [id: 0, 29; label: "", preview: false, empty: false] [id: 0, 30; label: "", preview: false, empty: false]) (id: 0, 15; label: "LTN", preview: false, empty: false; book [id: 0, 9; label: "LTN Multi Provider Requester", preview: false, empty: false] [id: 0, 10; label: "", preview: false, empty: false] [id: 0, 11; label: "", preview: false, empty: false] [id: 0, 12; label: "LTN Depot", preview: false, empty: false] [id: 0, 13; label: "LTN Liquid IN", preview: false, empty: false] [id: 0, 14; label: "LTN Provide Weighted Bulk", preview: false, empty: false]) (id: 0, 33; label: "STD", preview: false, empty: false; book [id: 0, 32; label: "", preview: false, empty: false]
  41.826 Applying migration: Fully Automated Rail Layer: 2015-02-18_FARL_0.1.8.lua
  41.832 Applying migration: Fully Automated Rail Layer: 2016-09-28_FARL_0.6.1.lua
  41.837 Applying migration: Fully Automated Rail Layer: 2017-05-29_FARL_1.0.10.lua
  41.842 Applying migration: Blueprint Extensions: 2018-10-23-v0_3_0_gui_changes.lua
  41.843 Applying migration: Cargo Ships: migrations-0-0-18.lua
  41.844 Applying migration: Cargo Ships: migrations-0-0-23.lua
  41.845 Applying migration: Cargo Ships: migrations_2.lua
  41.846 Applying migration: Ghost Scanner: 1.0.1.lua
  41.851 Applying migration: Loader Redux: 1.4.0.lua
  41.856 Applying migration: Robot Charging Station 1x1: RoboCharge.lua
  41.862 Applying migration: Robot Charging Station 1x1: RoboCharge_0.14.3.lua
  41.866 Applying migration: Robot Charging Station 1x1: RoboCharge_0.14.4.lua
  41.871 Applying migration: Waterbomb: Waterbomb_0.16.5.lua
  41.872 Applying migration: LTN - Logistic Train Network: 1.9.6.lua
  41.877 Applying migration: LTN Content Reader: 0.1.9.lua
  41.994 Loading Level.dat: 3468874 bytes.
  41.994 Info Scenario.cpp:147: Map version 0.17.4-1
  42.056 Verbose BlueprintLibrary.cpp:245: Loaded library shelves:
  42.056 Verbose BlueprintLibrary.cpp:867: Game shelf: playerIndex = 65535, nextRecordID = 0; timestamp = 0; records:
  42.056 Verbose BlueprintLibrary.cpp:869: Player shelf: playerIndex = 0, nextRecordID = 0; timestamp = 1550685097; records:
  42.056 Verbose Scenario.cpp:179: Loading level.dat finished: 0.061443 seconds.
  42.073 Verbose BlueprintLibrary.cpp:64: Loaded external blueprint storage: playerIndex = 0, nextRecordID = 34; timestamp = 1514045845; records: (id: 0, 0; label: "", preview: false, empty: false; book) (id: 0, 8; label: "", preview: false, empty: false; book [id: 0, 1; label: "", preview: false, empty: false] [id: 0, 2; label: "", preview: false, empty: false] [id: 0, 3; label: "Miner24", preview: false, empty: false] [id: 0, 4; label: "", preview: false, empty: false] [id: 0, 5; label: "Destroyers", preview: false, empty: false] [id: 0, 6; label: "", preview: false, empty: false] [id: 0, 7; label: "", preview: false, empty: false]) (id: 0, 31; label: "General", preview: false, empty: false; book [id: 0, 16; label: "Miner24", preview: false, empty: false] [id: 0, 17; label: "", preview: false, empty: false] [id: 0, 18; label: "Destroyers", preview: false, empty: false] [id: 0, 19; label: "", preview: false, empty: false] [id: 0, 20; label: "", preview: false, empty: false] [id: 0, 21; label: "Laser Defense", preview: false, empty: false] [id: 0, 22; label: "2 Roboports", preview: false, empty: false] [id: 0, 23; label: "", preview: false, empty: false] [id: 0, 24; label: "", preview: false, empty: false] [id: 0, 25; label: "Rail Crossing", preview: false, empty: false] [id: 0, 26; label: "Logistic to LTN Filter", preview: false, empty: false] [id: 0, 27; label: "", preview: false, empty: false] [id: 0, 28; label: "Robot Requester", preview: false, empty: false] [id: 0, 29; label: "", preview: false, empty: false] [id: 0, 30; label: "", preview: false, empty: false]) (id: 0, 15; label: "LTN", preview: false, empty: false; book [id: 0, 9; label: "LTN Multi Provider Requester", preview: false, empty: false] [id: 0, 10; label: "", preview: false, empty: false] [id: 0, 11; label: "", preview: false, empty: false] [id: 0, 12; label: "LTN Depot", preview: false, empty: false] [id: 0, 13; label: "LTN Liquid IN", preview: false, empty: false] [id: 0, 14; label: "LTN Provide Weighted Bulk", preview: false, empty: false]) (id: 0, 33; label: "STD", preview: false, empty: false; book [id: 0, 32; label: "", preview: false, empty: false]
  42.073 Verbose Scenario.cpp:226: Entities setup finished: 0.017279 seconds.
  42.108 Checksum for script /Users/ssilk/Library/Application Support/factorio/temp/currently-playing/control.lua: 4289712941
  42.124 Checksum for script __FARL__/control.lua: 926390723
  42.127 Checksum for script __AutoDeconstruct__/control.lua: 2848553575
  42.130 Checksum for script __Auto_Deploy_Destroyers__/control.lua: 3589131844
  42.134 Checksum for script __BlueprintExtensions__/control.lua: 2796840486
  42.138 Checksum for script __cargo-ships__/control.lua: 2910148192
  42.140 Checksum for script __ClosestFirst__/control.lua: 3437870126
  42.145 Checksum for script __EvoGUI__/control.lua: 759905060
  42.146 Checksum for script __GhostScanner__/control.lua: 193045139
  42.149 Checksum for script __Inventory Sensor__/control.lua: 1936841053
  42.151 Checksum for script __LoaderRedux__/control.lua: 2210880114
  42.155 Checksum for script __qol_research__/control.lua: 685915736
  42.163 Checksum for script __Todo-List__/control.lua: 2978474940
  42.169 Checksum for script __train-scaling__/control.lua: 1686219420
  42.171 Checksum for script __Vehicle_Radar__/control.lua: 3865655671
  42.172 Checksum for script __Waterbomb__/control.lua: 1308229589
  42.177 Checksum for script __LogisticTrainNetwork__/control.lua: 3925305265
  42.178 Checksum for script __LTN_Content_Reader__/control.lua: 4006770532
  42.179 Script @__FARL__/control.lua:43: {
  count = 4,
  name = "rail"
}
  42.179 Script @__FARL__/control.lua:45: "straight-rail"
  42.179 Script @__FARL__/control.lua:43: {
  count = 4,
  name = "water-way"
}
  42.179 Script @__FARL__/control.lua:45: "straight-water-way"
  42.179 Script @__FARL__/control.lua:43: {
  count = 4,
  name = "water-way"
}
  42.179 Script @__FARL__/control.lua:45: "straight-water-way"
  42.180 Script @__FARL__/control.lua:71: {
  bridge_crossing = {
    item = "bridge_crossing",
    straight = "bridge_crossing"
  },
  invisible_rail = {
    item = "invisible_rail",
    straight = "invisible_rail"
  },
  rail = {
    curved = "curved-rail",
    index = 1,
    item = "rail",
    straight = "straight-rail"
  },
  ["water-way"] = {
    curved = "curved-water-way-placed",
    index = 2,
    item = "water-way",
    straight = "straight-water-way"
  },
  ["water-way-placed"] = {
    item = "water-way-placed",
    straight = "straight-water-way-placed"
  }
}
  42.185 Script @__Inventory Sensor__/control.lua:447: Inventory Sensor 1.7.0 initialized.
  42.205 Script @__Vehicle_Radar__/control.lua:24: [VT] Found 0 trains for force player
  42.205 Script @__Vehicle_Radar__/control.lua:25: [VT] Found 1 vehicles for force player
  42.207 Script @__Vehicle_Radar__/control.lua:24: [VT] Found 0 trains for force enemy
  42.207 Script @__Vehicle_Radar__/control.lua:25: [VT] Found 0 vehicles for force enemy
  42.209 Script @__Vehicle_Radar__/control.lua:24: [VT] Found 0 trains for force neutral
  42.209 Script @__Vehicle_Radar__/control.lua:25: [VT] Found 0 vehicles for force neutral
  42.211 Script @__Vehicle_Radar__/control.lua:24: [VT] Found 0 trains for force peaceful
  42.211 Script @__Vehicle_Radar__/control.lua:25: [VT] Found 0 vehicles for force peaceful
  42.213 Script @__Vehicle_Radar__/control.lua:24: [VT] Found 0 trains for force bystander
  42.213 Script @__Vehicle_Radar__/control.lua:25: [VT] Found 0 vehicles for force bystander
  42.214 Script @__LogisticTrainNetwork__/control.lua:324: [LTN] LogisticTrainNetwork 1.10.0 initialized.
  42.214 Verbose Scenario.cpp:267: Map setup finished: 0.220554 seconds.
  42.219 Verbose AppManager.cpp:592: Time to create game: 1.152774 seconds.
  42.242 Script @__Vehicle_Radar__/control.lua:24: [VT] Found 0 trains for force compi_res_force
  42.242 Script @__Vehicle_Radar__/control.lua:25: [VT] Found 0 vehicles for force compi_res_force
 117.127 Info PrototypeMigrationList.cpp:60: Activating migration cargo-ships/migrations-0-0-23.json
 117.127 Info PrototypeMigrationList.cpp:60: Activating migration cargo-ships/migrations_3.json
 117.127 Info PrototypeMigrationList.cpp:60: Activating migration Inventory Sensor/1.4.6.json
 117.127 Info PrototypeMigrationList.cpp:60: Activating migration LoaderRedux/0.1.1.json
 117.127 Info PrototypeMigrationList.cpp:60: Activating migration Waterbomb/Waterbomb_0.16.5.json
 117.127 Info PrototypeMigrationList.cpp:60: Activating migration LogisticTrainNetwork/1.3.0.json
 117.486 Verbose CompiledMapGenSettings.cpp:484: MapGenSettings compilation took 0.331213 seconds; deduplicated 88 procedures and 1444 sub-expressions; resulting program has 105 procedures, 3507 operations, and 125 registers
 117.840 Verbose CompiledMapGenSettings.cpp:484: MapGenSettings compilation took 0.333457 seconds; deduplicated 88 procedures and 1444 sub-expressions; resulting program has 105 procedures, 3507 operations, and 125 registers
 117.863 Verbose BlueprintLibrary.cpp:245: Loaded library shelves:
 117.863 Verbose BlueprintLibrary.cpp:867: Game shelf: playerIndex = 65535, nextRecordID = 0; timestamp = 0; records:
 117.863 Verbose BlueprintLibrary.cpp:869: Player shelf: playerIndex = 0, nextRecordID = 0; timestamp = 1550685097; records:
 117.881 Verbose BlueprintLibrary.cpp:64: Loaded external blueprint storage: playerIndex = 0, nextRecordID = 34; timestamp = 1514045845; records: (id: 0, 0; label: "", preview: false, empty: false; book) (id: 0, 8; label: "", preview: false, empty: false; book [id: 0, 1; label: "", preview: false, empty: false] [id: 0, 2; label: "", preview: false, empty: false] [id: 0, 3; label: "Miner24", preview: false, empty: false] [id: 0, 4; label: "", preview: false, empty: false] [id: 0, 5; label: "Destroyers", preview: false, empty: false] [id: 0, 6; label: "", preview: false, empty: false] [id: 0, 7; label: "", preview: false, empty: false]) (id: 0, 31; label: "General", preview: false, empty: false; book [id: 0, 16; label: "Miner24", preview: false, empty: false] [id: 0, 17; label: "", preview: false, empty: false] [id: 0, 18; label: "Destroyers", preview: false, empty: false] [id: 0, 19; label: "", preview: false, empty: false] [id: 0, 20; label: "", preview: false, empty: false] [id: 0, 21; label: "Laser Defense", preview: false, empty: false] [id: 0, 22; label: "2 Roboports", preview: false, empty: false] [id: 0, 23; label: "", preview: false, empty: false] [id: 0, 24; label: "", preview: false, empty: false] [id: 0, 25; label: "Rail Crossing", preview: false, empty: false] [id: 0, 26; label: "Logistic to LTN Filter", preview: false, empty: false] [id: 0, 27; label: "", preview: false, empty: false] [id: 0, 28; label: "Robot Requester", preview: false, empty: false] [id: 0, 29; label: "", preview: false, empty: false] [id: 0, 30; label: "", preview: false, empty: false]) (id: 0, 15; label: "LTN", preview: false, empty: false; book [id: 0, 9; label: "LTN Multi Provider Requester", preview: false, empty: false] [id: 0, 10; label: "", preview: false, empty: false] [id: 0, 11; label: "", preview: false, empty: false] [id: 0, 12; label: "LTN Depot", preview: false, empty: false] [id: 0, 13; label: "LTN Liquid IN", preview: false, empty: false] [id: 0, 14; label: "LTN Provide Weighted Bulk", preview: false, empty: false]) (id: 0, 33; label: "STD", preview: false, empty: false; book [id: 0, 32; label: "", preview: false, empty: false]
 117.902 Applying migration: Fully Automated Rail Layer: 2015-02-18_FARL_0.1.8.lua
 117.910 Applying migration: Fully Automated Rail Layer: 2016-09-28_FARL_0.6.1.lua
 117.917 Applying migration: Fully Automated Rail Layer: 2017-05-29_FARL_1.0.10.lua
 117.924 Applying migration: Blueprint Extensions: 2018-10-23-v0_3_0_gui_changes.lua
 117.925 Applying migration: Cargo Ships: migrations-0-0-18.lua
 117.926 Applying migration: Cargo Ships: migrations-0-0-23.lua
 117.927 Applying migration: Cargo Ships: migrations_2.lua
 117.928 Applying migration: Ghost Scanner: 1.0.1.lua
 117.935 Applying migration: Loader Redux: 1.4.0.lua
 117.943 Applying migration: Robot Charging Station 1x1: RoboCharge.lua
 117.950 Applying migration: Robot Charging Station 1x1: RoboCharge_0.14.3.lua
 117.956 Applying migration: Robot Charging Station 1x1: RoboCharge_0.14.4.lua
 117.963 Applying migration: Waterbomb: Waterbomb_0.16.5.lua
 117.964 Applying migration: LTN - Logistic Train Network: 1.9.6.lua
 117.970 Applying migration: LTN Content Reader: 0.1.9.lua
 118.103 Loading Level.dat: 3468874 bytes.
 118.104 Info Scenario.cpp:147: Map version 0.17.4-1
 118.201 Verbose BlueprintLibrary.cpp:245: Loaded library shelves:
 118.201 Verbose BlueprintLibrary.cpp:867: Game shelf: playerIndex = 65535, nextRecordID = 0; timestamp = 0; records:
 118.201 Verbose BlueprintLibrary.cpp:869: Player shelf: playerIndex = 0, nextRecordID = 0; timestamp = 1550685097; records:
 118.202 Verbose Scenario.cpp:179: Loading level.dat finished: 0.097108 seconds.
 118.223 Verbose BlueprintLibrary.cpp:64: Loaded external blueprint storage: playerIndex = 0, nextRecordID = 34; timestamp = 1514045845; records: (id: 0, 0; label: "", preview: false, empty: false; book) (id: 0, 8; label: "", preview: false, empty: false; book [id: 0, 1; label: "", preview: false, empty: false] [id: 0, 2; label: "", preview: false, empty: false] [id: 0, 3; label: "Miner24", preview: false, empty: false] [id: 0, 4; label: "", preview: false, empty: false] [id: 0, 5; label: "Destroyers", preview: false, empty: false] [id: 0, 6; label: "", preview: false, empty: false] [id: 0, 7; label: "", preview: false, empty: false]) (id: 0, 31; label: "General", preview: false, empty: false; book [id: 0, 16; label: "Miner24", preview: false, empty: false] [id: 0, 17; label: "", preview: false, empty: false] [id: 0, 18; label: "Destroyers", preview: false, empty: false] [id: 0, 19; label: "", preview: false, empty: false] [id: 0, 20; label: "", preview: false, empty: false] [id: 0, 21; label: "Laser Defense", preview: false, empty: false] [id: 0, 22; label: "2 Roboports", preview: false, empty: false] [id: 0, 23; label: "", preview: false, empty: false] [id: 0, 24; label: "", preview: false, empty: false] [id: 0, 25; label: "Rail Crossing", preview: false, empty: false] [id: 0, 26; label: "Logistic to LTN Filter", preview: false, empty: false] [id: 0, 27; label: "", preview: false, empty: false] [id: 0, 28; label: "Robot Requester", preview: false, empty: false] [id: 0, 29; label: "", preview: false, empty: false] [id: 0, 30; label: "", preview: false, empty: false]) (id: 0, 15; label: "LTN", preview: false, empty: false; book [id: 0, 9; label: "LTN Multi Provider Requester", preview: false, empty: false] [id: 0, 10; label: "", preview: false, empty: false] [id: 0, 11; label: "", preview: false, empty: false] [id: 0, 12; label: "LTN Depot", preview: false, empty: false] [id: 0, 13; label: "LTN Liquid IN", preview: false, empty: false] [id: 0, 14; label: "LTN Provide Weighted Bulk", preview: false, empty: false]) (id: 0, 33; label: "STD", preview: false, empty: false; book [id: 0, 32; label: "", preview: false, empty: false]
 118.223 Verbose Scenario.cpp:226: Entities setup finished: 0.021156 seconds.
 118.246 Checksum for script /Users/ssilk/Library/Application Support/factorio/temp/currently-playing/control.lua: 4289712941
 118.262 Checksum for script __FARL__/control.lua: 926390723
 118.266 Checksum for script __AutoDeconstruct__/control.lua: 2848553575
 118.269 Checksum for script __Auto_Deploy_Destroyers__/control.lua: 3589131844
 118.272 Checksum for script __BlueprintExtensions__/control.lua: 2796840486
 118.277 Checksum for script __cargo-ships__/control.lua: 2910148192
 118.279 Checksum for script __ClosestFirst__/control.lua: 3437870126
 118.283 Checksum for script __EvoGUI__/control.lua: 759905060
 118.284 Checksum for script __GhostScanner__/control.lua: 193045139
 118.287 Checksum for script __Inventory Sensor__/control.lua: 1936841053
 118.289 Checksum for script __LoaderRedux__/control.lua: 2210880114
 118.293 Checksum for script __qol_research__/control.lua: 685915736
 118.299 Checksum for script __Todo-List__/control.lua: 2978474940
 118.306 Checksum for script __train-scaling__/control.lua: 1686219420
 118.308 Checksum for script __Vehicle_Radar__/control.lua: 3865655671
 118.310 Checksum for script __Waterbomb__/control.lua: 1308229589
 118.316 Checksum for script __LogisticTrainNetwork__/control.lua: 3925305265
 118.318 Checksum for script __LTN_Content_Reader__/control.lua: 4006770532
 118.319 Script @__FARL__/control.lua:43: {
  count = 4,
  name = "rail"
}
 118.319 Script @__FARL__/control.lua:45: "straight-rail"
 118.319 Script @__FARL__/control.lua:43: {
  count = 4,
  name = "water-way"
}
 118.319 Script @__FARL__/control.lua:45: "straight-water-way"
 118.319 Script @__FARL__/control.lua:43: {
  count = 4,
  name = "water-way"
}
 118.319 Script @__FARL__/control.lua:45: "straight-water-way"
 118.321 Script @__FARL__/control.lua:71: {
  bridge_crossing = {
    item = "bridge_crossing",
    straight = "bridge_crossing"
  },
  invisible_rail = {
    item = "invisible_rail",
    straight = "invisible_rail"
  },
  rail = {
    curved = "curved-rail",
    index = 1,
    item = "rail",
    straight = "straight-rail"
  },
  ["water-way"] = {
    curved = "curved-water-way-placed",
    index = 2,
    item = "water-way",
    straight = "straight-water-way"
  },
  ["water-way-placed"] = {
    item = "water-way-placed",
    straight = "straight-water-way-placed"
  }
}
 118.327 Script @__Inventory Sensor__/control.lua:447: Inventory Sensor 1.7.0 initialized.
 118.354 Script @__Vehicle_Radar__/control.lua:24: [VT] Found 0 trains for force player
 118.354 Script @__Vehicle_Radar__/control.lua:25: [VT] Found 1 vehicles for force player
 118.357 Script @__Vehicle_Radar__/control.lua:24: [VT] Found 0 trains for force enemy
 118.357 Script @__Vehicle_Radar__/control.lua:25: [VT] Found 0 vehicles for force enemy
 118.358 Script @__Vehicle_Radar__/control.lua:24: [VT] Found 0 trains for force neutral
 118.358 Script @__Vehicle_Radar__/control.lua:25: [VT] Found 0 vehicles for force neutral
 118.360 Script @__Vehicle_Radar__/control.lua:24: [VT] Found 0 trains for force peaceful
 118.360 Script @__Vehicle_Radar__/control.lua:25: [VT] Found 0 vehicles for force peaceful
 118.361 Script @__Vehicle_Radar__/control.lua:24: [VT] Found 0 trains for force bystander
 118.362 Script @__Vehicle_Radar__/control.lua:25: [VT] Found 0 vehicles for force bystander
 118.362 Script @__LogisticTrainNetwork__/control.lua:324: [LTN] LogisticTrainNetwork 1.10.0 initialized.
 118.363 Verbose Scenario.cpp:267: Map setup finished: 0.259578 seconds.
 118.368 Verbose AppManager.cpp:592: Time to create game: 1.252146 seconds.
 118.397 Script @__Vehicle_Radar__/control.lua:24: [VT] Found 0 trains for force compi_res_force
 118.397 Script @__Vehicle_Radar__/control.lua:25: [VT] Found 0 vehicles for force compi_res_force
 165.908 Info GlobalContext.cpp:678: Waiting for child processes to exit: 
 166.173 Goodbye
Cool suggestion: Eatable MOUSE-pointers.
Have you used the Advanced Search today?
Need help, question? FAQ - Wiki - Forum help
I still like small signatures...
halfdanj
Manual Inserter
Manual Inserter
Posts: 1
Joined: Sat Mar 02, 2019 7:20 pm
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by halfdanj »

Here is one from me. I've tried with bunch of graphics settings without luck

Code: Select all

  
   0.000 2019-03-02 13:56:27; Factorio 0.17.4 (build 43174, mac, steam)
   0.000 Operating system: macOS 10.14.3
   0.000 Program arguments: "/Users/j/Library/Application Support/Steam/steamapps/common/Factorio/factorio.app/Contents/MacOS/factorio" 
   0.000 Read data path: /Users/j/Library/Application Support/Steam/steamapps/common/Factorio/factorio.app/Contents/data
   0.000 Write data path: /Users/j/Library/Application Support/factorio [23627/239072MB]
   0.000 Binaries path: /Users/j/Library/Application Support/Steam/steamapps/common/Factorio/factorio.app/Contents
   0.007 System info: [CPU: Intel(R) Core(TM) i7-6700HQ CPU @ 2.60GHz, 8 cores, RAM: 16384 MB]
   0.007 Display options: [FullScreen: 1] [VSync: 1] [UIScale: automatic (100.0%)] [MultiSampling: OFF] [Screen: 255] [Lang: en]
   0.545 Available displays: 1
   0.545  [0]: Color LCD - {[0,0], 1920x1200, SDL_PIXELFORMAT_ARGB8888, 60Hz}
   1.583 Initialised OpenGL:[0] AMD Radeon Pro 450 OpenGL Engine; driver: 4.1 ATI-2.4.10
   1.583   [Extensions] s3tc:yes; KHR_debug:NO; ARB_clear_texture:NO, ARB_copy_image:NO
   1.583   [Version] 4.1
   1.714 Graphics settings preset: very-high
   1.714   Dedicated video memory size 2048 MB (detected from AMD Radeon Pro 450 Compute Engine)
   1.714 Graphics options: [Graphics quality: normal] [Video memory usage: low] [Light scale: 25%] [DXT: low-quality]
   1.714                   [Max load threads: 32] [Max texture size: 2048] [Tex.Stream.: 1] [Low quality rotation: 1]
   1.839 Loading mod core 0.0.0 (data.lua)
   1.928 Loading mod base 0.17.4 (data.lua)
   2.171 Loading mod base 0.17.4 (data-updates.lua)
   2.292 Checksum for core: 3928040144
   2.292 Checksum of base: 60305742
   2.889 Loading sounds...
   2.922 Info PlayerData.cpp:69: Local player-data.json unavailable
   2.922 Info PlayerData.cpp:72: Cloud player-data.json available, timestamp 1551552981
   3.100 Initial atlas bitmap size is 2048
   3.100 Created atlas bitmap 2048x872 [none]
   3.100 Created atlas bitmap 2048x1988 [decal]
   3.100 Created atlas bitmap 2048x2024 [decal]
   3.102 Created atlas bitmap 2048x2048 [low-object]
   3.103 Created atlas bitmap 2048x2048 [low-object]
   3.103 Created atlas bitmap 2048x2048 [low-object]
   3.104 Created atlas bitmap 2048x1776 [low-object]
   3.104 Created atlas bitmap 2048x2048 [mipmap, linear-minification, linear-magnification, linear-mip-level]
   3.104 Created atlas bitmap 2048x1920 [mipmap]
   3.105 Created atlas bitmap 2048x2032 [terrain, mipmap, linear-minification, linear-mip-level, no-crop]
   3.105 Created atlas bitmap 2048x2000 [terrain, mipmap, linear-minification, linear-mip-level, no-crop]
   3.106 Created atlas bitmap 2048x2032 [terrain, mipmap, linear-minification, linear-mip-level, no-crop]
   3.106 Created atlas bitmap 2048x1968 [terrain, mipmap, linear-minification, linear-mip-level, no-crop]
   3.107 Created atlas bitmap 2048x2000 [terrain, mipmap, linear-minification, linear-mip-level, no-crop]
   3.107 Created atlas bitmap 2048x2032 [terrain, mipmap, linear-minification, linear-mip-level, no-crop]
   3.107 Created atlas bitmap 2048x2016 [terrain, mipmap, linear-minification, linear-mip-level, no-crop]
   3.107 Created atlas bitmap 2048x1824 [terrain, mipmap, linear-minification, linear-mip-level, no-crop]
   3.107 Created atlas bitmap 2048x768 [terrain, mipmap, linear-minification, linear-mip-level, no-crop]
   3.107 Created atlas bitmap 2048x2016 [smoke, mipmap, linear-minification, linear-magnification]
   3.107 Created atlas bitmap 2048x1216 [smoke, mipmap, linear-minification, linear-magnification]
   3.108 Created atlas bitmap 2048x2040 [not-compressed, mipmap, linear-minification, linear-magnification, linear-mip-level, no-crop, no-scale]
   3.108 Created atlas bitmap 2048x688 [not-compressed, mipmap, linear-minification, linear-magnification, linear-mip-level, no-crop, no-scale]
   3.108 Created atlas bitmap 2048x1656 [alpha-mask]
   3.110 Created atlas bitmap 2048x2020 [shadow, linear-magnification, alpha-mask]
   3.112 Created atlas bitmap 2048x2028 [shadow, linear-magnification, alpha-mask]
   3.114 Created atlas bitmap 2048x2032 [shadow, linear-magnification, alpha-mask]
   3.116 Created atlas bitmap 2048x2040 [shadow, linear-magnification, alpha-mask]
   3.117 Created atlas bitmap 2048x2048 [shadow, linear-magnification, alpha-mask]
   3.119 Created atlas bitmap 2048x2044 [shadow, linear-magnification, alpha-mask]
   3.120 Created atlas bitmap 2048x2040 [shadow, linear-magnification, alpha-mask]
   3.121 Created atlas bitmap 2048x2040 [shadow, linear-magnification, alpha-mask]
   3.122 Created atlas bitmap 2048x2028 [shadow, linear-magnification, alpha-mask]
   3.123 Created atlas bitmap 2048x2040 [shadow, linear-magnification, alpha-mask]
   3.124 Created atlas bitmap 2048x2028 [shadow, linear-magnification, alpha-mask]
   3.127 Created atlas bitmap 2048x2032 [shadow, linear-magnification, alpha-mask]
   3.127 Created atlas bitmap 2048x1744 [shadow, linear-magnification, alpha-mask]
   3.127 Created atlas bitmap 2048x2048 [shadow, mipmap, linear-magnification, alpha-mask]
   3.127 Created atlas bitmap 2048x576 [shadow, mipmap, linear-magnification, alpha-mask]
   3.401 Created virtual atlas pages 4096x4096x8
   3.538 Texture processor created. GPU accelerated compression Supported: yes, Enabled: yes/yes. Test passed. YCoCgDXT PSNR: 35.83, BC3 PSNR: 33.82
   3.817 Parallel Sprite Loader initialized (threads: 7)
   7.290 Sprites loaded
   7.330 Generated mipmaps for atlas of size 2048x2048
   7.369 Generated mipmaps for atlas of size 2048x2048
   7.407 Generated mipmaps for atlas of size 2048x2048
   7.445 Generated mipmaps for atlas of size 2048x1776
   7.484 Generated mipmaps for atlas of size 2048x2048
   7.523 Generated mipmaps for atlas of size 2048x1920
   7.562 Generated mipmaps for atlas of size 2048x2032
   7.600 Generated mipmaps for atlas of size 2048x2000
   7.638 Generated mipmaps for atlas of size 2048x2032
   7.676 Generated mipmaps for atlas of size 2048x1968
   7.714 Generated mipmaps for atlas of size 2048x2000
   7.751 Generated mipmaps for atlas of size 2048x2032
   7.787 Generated mipmaps for atlas of size 2048x2016
   7.824 Generated mipmaps for atlas of size 2048x1824
   7.856 Generated mipmaps for atlas of size 2048x768
   7.894 Generated mipmaps for atlas of size 2048x2016
   7.930 Generated mipmaps for atlas of size 2048x1216
   7.959 Generated mipmaps for atlas of size 2048x2040
   7.976 Generated mipmaps for atlas of size 2048x688
   7.984 Generated mipmaps for atlas of size 2048x2048
   7.989 Generated mipmaps for atlas of size 2048x576
   8.149 Generated mipmaps for virtual atlas of size 8192x16384
   8.155 Custom inputs active: 0
   8.227 Factorio initialised
  11.368 Info UDPSocket.cpp:27: Opening socket at (IP ADDR:({0.0.0.0:0}))
  23.157 Info UDPSocket.cpp:210: Closing socket
  23.158 Info UDPSocket.cpp:240: Socket closed
  23.158 Info UDPSocket.cpp:210: Closing socket
  23.159 Joining game STEAM ID:({76561197989940852})
  23.159 Info UDPSocket.cpp:33: Opening socket
  23.160 Info ClientMultiplayerManager.cpp:571: UpdateTick(4294967295) changing state from(Ready) to(Connecting)
  23.966 Warning TransmissionControlHelper.cpp:179: Fragment 0000 failed too many times
  28.716 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ConnectionRequestReply) received from STEAM ID:({76561197989940852}): invalid state (WaitingForAccept) for packet type
  28.716 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ConnectionRequestReply) received from STEAM ID:({76561197989940852}): invalid state (WaitingForAccept) for packet type
  28.716 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ConnectionRequestReply) received from STEAM ID:({76561197989940852}): invalid state (WaitingForAccept) for packet type
  28.716 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ConnectionRequestReply) received from STEAM ID:({76561197989940852}): invalid state (WaitingForAccept) for packet type
  28.883 Info ClientSynchronizer.cpp:35: Initialized Synchronizer local peer(1) latency(32).
  28.883 Info ClientMultiplayerManager.cpp:571: UpdateTick(4294967295) changing state from(Connecting) to(ConnectedWaitingForMap)
  28.883 Info ClientRouter.cpp:224: ConnectionAccepted
  29.299 Info ClientMultiplayerManager.cpp:743: Received mapReadyForDownload
  29.299 Downloading file /Users/jonjejan/Library/Application Support/factorio/temp/mp-download.zip (4187510 B, 8326 blocks)
  29.300 Info ClientMultiplayerManager.cpp:571: UpdateTick(4294967295) changing state from(ConnectedWaitingForMap) to(ConnectedDownloadingMap)
  35.154 Finished download (5 s, 4.1 MB, 715 kB/s)
  35.182 Info ClientMultiplayerManager.cpp:723: updateTick(4294967295) map download finished creating scenario
  35.183 Info ClientMultiplayerManager.cpp:571: UpdateTick(4294967295) changing state from(ConnectedDownloadingMap) to(ConnectedLoadingMap)
  35.183 Loading map /Users/j/Library/Application Support/factorio/temp/mp-download.zip: 4187510 bytes.
  35.206 Loading Level.dat: 16680927 bytes.
  35.211 Info Scenario.cpp:147: Map version 0.17.4-1
  36.615 Loading script.dat: 328 bytes.
  36.618 Checksum for script /Users/j/Library/Application Support/factorio/temp/currently-playing/control.lua: 2366710471
  36.633 Info ClientMultiplayerManager.cpp:571: UpdateTick(885153) changing state from(ConnectedLoadingMap) to(TryingToCatchUp)
  37.599 Info ClientMultiplayerManager.cpp:571: UpdateTick(885652) changing state from(TryingToCatchUp) to(WaitingForCommandToStartSendingTickClosures)
  37.849 Info ClientMultiplayerManager.cpp:571: UpdateTick(885652) changing state from(WaitingForCommandToStartSendingTickClosures) to(InGame)
  38.050 Info GameActionHandler.cpp:4041: UpdateTick (885664) processed PlayerJoinGame peerID(27) playerIndex(22) mode(connect) 
 207.229 Info SteamContext.cpp:602: Steam connection lost.
 669.654 Info ClientMultiplayerManager.cpp:881: Disconnect notification for peer (13)
 937.533 Info GameActionHandler.cpp:4041: UpdateTick (939571) processed PlayerJoinGame peerID(28) playerIndex(24) mode(create) 
 944.332 Info ClientMultiplayerManager.cpp:881: Disconnect notification for peer (28)
1160.566 Info GameActionHandler.cpp:4041: UpdateTick (952928) processed PlayerJoinGame peerID(29) playerIndex(12) mode(connect) 
1168.688 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from STEAM ID:({76561197989940852}): heartbeat sequence number (477131) outside latency window [477174, 477774)
1168.688 Info UnparsedNetworkMessage.cpp:102: Message data: 27 12 cb 47 07 00 02 f4 8b 0e 00 08 42 01 6e 4f 3e 3d f3 8b 0e 00 25 02 b0 0e 89 25 06 f5 8b 0e 00 06 42 01 4f 1a 3a d9 f4 8b 0e 00 25 02 25 14 02 0f f4 8b 0e 00 00 00 0f f5 8b 0e 00 00 00
1168.688 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from STEAM ID:({76561197989940852}): heartbeat sequence number (477134) outside latency window [477174, 477774)
1168.688 Info UnparsedNetworkMessage.cpp:102: Message data: 27 12 ce 47 07 00 02 fa 8b 0e 00 0c 42 01 21 5b 50 3c f9 8b 0e 00 25 02 0a 0e 36 00 80 c3 fe ff 80 0d 00 00 04 00 01 00 00 06 03 b0 00 78 fb 8b 0e 00 0c 42 01 83 73 74 14 fa 8b 0e 00 25 02 b0 02 78 36 00 80 6f ff ff 80 e7 ff ff 06 00 01 00 00 b0 0c 89 25 06 01 0f fb 8b 0e 00 00 00
1168.688 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from STEAM ID:({76561197989940852}): heartbeat sequence number (477135) outside latency window [477174, 477774)
1168.688 Info UnparsedNetworkMessage.cpp:102: Message data: 27 12 cf 47 07 00 02 fc 8b 0e 00 08 42 01 0c 3b 0f 06 fb 8b 0e 00 25 02 b0 02 88 25 12 fd 8b 0e 00 06 42 01 d9 58 c2 26 fc 8b 0e 00 25 02 25 14 02 0f fc 8b 0e 00 00 00 0f fd 8b 0e 00 00 00
1168.688 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from STEAM ID:({76561197989940852}): heartbeat sequence number (477136) outside latency window [477174, 477774)
1168.688 Info UnparsedNetworkMessage.cpp:102: Message data: 07 12 d0 47 07 00 02 fe 8b 0e 00 08 b0 01 22 42 00 55 ac ee 13 fd 8b 0e 00 25 02 25 14 ff 8b 0e 00 0a 42 01 6c 4a a7 09 fe 8b 0e 00 25 02 b0 02 78 36 00 80 6e ff ff 80 e7 ff ff 06 00 01 00 00 25 12 02 0f fe 8b 0e 00 00 00 0f ff 8b 0e 00 00 00
1168.688 Info UnparsedNetworkMessage.cpp:91: Invalid packet (type ServerToClientHeartbeat) received from STEAM ID:({76561197989940852}): heartbeat sequence number (477137) outside latency window [477174, 477774)
1168.688 Info UnparsedNetworkMessage.cpp:102: Message data: 07 12 d1 47 07 00 02 00 8c 0e 00 0c 42 01 99 d9 8c 22 ff 8b 0e 00 25 02 b0 02 88 b0 04 78 b3 0b f4 16 02 00 25 03 01 8c 0e 00 0a 42 01 87 0f f0 8b 00 8c 0e 00 25 02 0a 0e 36 00 80 c3 fe ff 80 0e 00 00 04 00 01 00 00 25 06 02 0f 00 8c 0e 00 00 00 0f 01 8c 0e 00 00 00
1400.032 Info GameActionHandler.cpp:4041: UpdateTick (967272) processed PlayerJoinGame peerID(30) playerIndex(25) mode(create) 
1900.915 Info ClientMultiplayerManager.cpp:141: Disconnecting multiplayer connection. Reason: Quit.
1900.915 Info ClientMultiplayerManager.cpp:571: UpdateTick(997299) changing state from(InGame) to(DisconnectScheduled)
1900.933 Info ClientMultiplayerManager.cpp:571: UpdateTick(997299) changing state from(DisconnectScheduled) to(WaitingForDisconnectConfirmation)
1901.149 Info ClientMultiplayerManager.cpp:571: UpdateTick(997299) changing state from(WaitingForDisconnectConfirmation) to(Disconnected)
1901.149 Info ClientMultiplayerManager.cpp:881: Disconnect notification for peer (27)
1901.149 Info ClientSynchronizer.cpp:211: nextHeartbeatSequenceNumber(1586455341) cannot process synchronizer action(SkippedTickClosure)
1901.713 Info UDPSocket.cpp:210: Closing socket
1901.713 Info UDPSocket.cpp:240: Socket closed
1902.598 Info GlobalContext.cpp:678: Waiting for child processes to exit: 
1902.918 Steam API shutdown.
1902.918 Goodbye

Sidan
Manual Inserter
Manual Inserter
Posts: 4
Joined: Thu Feb 28, 2019 5:39 pm
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by Sidan »

Here is a log with V-sync enabled (loaded a save, moved around, clicked on stuff ; input lag happened):
With V-sync
And here is one with V-sync disabled (the delay also arises with v-sync disabled, just a lot more scarcely):
Without V-sync
_italics_
Burner Inserter
Burner Inserter
Posts: 17
Joined: Sat Jan 05, 2019 3:28 pm
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by _italics_ »

I attached a log made with --verbose, where I opened a game, made it lag like hell by zooming in and out, then exited.

I have tried many different settings and compared with my Windows machine. I assume you don't have access to a Mac?

Here's a few clues:

1. When I run it on Windows with V-Sync off and game speed 1000, UPS goes to over 10k, but FPS stays at 60. On the Mac, FPS goes to 80-90.
2. On Mac with VSync On, I can't get FPS to go lower than 60 when I am zooming in on things that would cause FPS to drop in 0.16. It's then delaying all input until the gfx has caught up.
3. The problem is much less pronounced when I use a lower resolution (but still unplayable)
4. The problem disappears completely when I reduce game speed to e.g. 0.75, which makes the FPS become stable at 45 FPS.

Everything points to not dropping frames when it can't draw it in time for 60FPS, imho.
Attachments
factorio-current.log
(8.38 KiB) Downloaded 136 times
Maciej
Burner Inserter
Burner Inserter
Posts: 12
Joined: Wed Dec 13, 2017 4:33 pm
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by Maciej »

Here is log from me.
Played few seconds with Magic Trackpad 2 (when playing with mouse there is no lags at all).

iMac (Retina 5K, 27-inch, 2017)
Attachments
factorio-current.log
(90.24 KiB) Downloaded 144 times
Xorimuth
Filter Inserter
Filter Inserter
Posts: 695
Joined: Sat Mar 02, 2019 9:39 pm
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by Xorimuth »

I get this problem as well (MacBook Air 2015). However, it only happens with the trackpad. The game behaves completely normally with an external mouse. I believe the movement delay people are mentioning only happens while it is trying to catch up with the trackpad movement.

Here is a video recording, where you can see the trackpad delay (can't really prove the zoom delay, but it did happen a while after I actually scrolled up/down). https://imgur.com/a/uiIQ9NR

Log:

Code: Select all

   0.002 2019-03-02 21:30:36; Factorio 0.17.4 (build 43174, mac, steam)
   0.002 Operating system: macOS 10.14.3
   0.002 Program arguments: "/Users/tomburrows/Library/Application Support/Steam/steamapps/common/Factorio/factorio.app/Contents/MacOS/factorio" 
   0.002 Read data path: /Users/tomburrows/Library/Application Support/Steam/steamapps/common/Factorio/factorio.app/Contents/data
   0.002 Write data path: /Users/tomburrows/Library/Application Support/factorio [5552/114892MB]
   0.002 Binaries path: /Users/tomburrows/Library/Application Support/Steam/steamapps/common/Factorio/factorio.app/Contents
   0.020 System info: [CPU: Intel(R) Core(TM) i5-5250U CPU @ 1.60GHz, 4 cores, RAM: 8192 MB]
   0.020 Display options: [FullScreen: 0] [VSync: 1] [UIScale: custom (100.0%)] [MultiSampling: OFF] [Screen: 255] [Lang: en]
   0.278 Available displays: 1
   0.280  [0]: Color LCD - {[0,0], 1440x900, SDL_PIXELFORMAT_ARGB8888, 60Hz}
   0.437 Initialised OpenGL:[0] Intel(R) HD Graphics 6000; driver: 4.1 INTEL-12.4.7
   0.437   [Extensions] s3tc:yes; KHR_debug:NO; ARB_clear_texture:NO, ARB_copy_image:NO
   0.437   [Version] 4.1
   0.684 Graphics settings preset: high
   0.684   Dedicated video memory size 1536 MB (detected from Intel(R) Iris(TM) Graphics 6100)
   0.684 Graphics options: [Graphics quality: high] [Video memory usage: high] [Light scale: 25%] [DXT: high-quality]
   0.684                   [Max load threads: 32] [Max texture size: 0] [Tex.Stream.: 1] [Low quality rotation: 1]
   1.082 Loading mod core 0.0.0 (data.lua)
   1.246 Loading mod base 0.17.4 (data.lua)
   1.721 Loading mod base 0.17.4 (data-updates.lua)
   1.886 Checksum for core: 3928040144
   1.886 Checksum of base: 60305742
   2.700 Loading sounds...
   2.954 Info PlayerData.cpp:69: Local player-data.json unavailable
   2.954 Info PlayerData.cpp:72: Cloud player-data.json available, timestamp 1551475095
   3.134 Initial atlas bitmap size is 16384
   3.158 Created atlas bitmap 16384x16380 [none]
   3.158 Created atlas bitmap 16384x4440 [none]
   3.158 Created atlas bitmap 16384x2116 [decal]
   3.159 Created atlas bitmap 16384x3808 [low-object]
   3.159 Created atlas bitmap 16384x1888 [mipmap, linear-minification, linear-magnification, linear-mip-level]
   3.159 Created atlas bitmap 16384x6592 [terrain, mipmap, linear-minification, linear-mip-level, no-crop]
   3.159 Created atlas bitmap 16384x480 [smoke, mipmap, linear-minification, linear-magnification]
   3.159 Created atlas bitmap 16384x456 [not-compressed, mipmap, linear-minification, linear-magnification, linear-mip-level, no-crop, no-scale]
   3.159 Created atlas bitmap 16384x748 [alpha-mask]
   3.161 Created atlas bitmap 16384x11240 [shadow, linear-magnification, alpha-mask]
   3.161 Created atlas bitmap 16384x1264 [shadow, mipmap, linear-magnification, alpha-mask]
   3.333 Created virtual atlas pages 4096x4096x5
   3.642 Texture processor created. GPU accelerated compression Supported: yes, Enabled: yes/yes. Test passed. YCoCgDXT PSNR: 35.83, BC3 PSNR: 33.82
   8.212 Parallel Sprite Loader initialized (threads: 3)
  43.314 Sprites loaded
  43.969 Generated mipmaps for atlas of size 16384x3808
  44.325 Generated mipmaps for atlas of size 16384x1888
  44.940 Generated mipmaps for atlas of size 16384x6592
  45.109 Generated mipmaps for atlas of size 16384x480
  45.202 Generated mipmaps for atlas of size 16384x456
  45.237 Generated mipmaps for atlas of size 16384x1264
  45.439 Generated mipmaps for virtual atlas of size 8192x12288
  45.450 Custom inputs active: 0
  45.560 Factorio initialised
  65.521 Loading map /Users/tomburrows/Library/Application Support/factorio/saves/tutorial.zip: 2516420 bytes.
  65.589 Loading Level.dat: 5405741 bytes.
  65.593 Info Scenario.cpp:147: Map version 0.17.4-1
  65.879 Loading script.dat: 281337 bytes.
  65.990 Checksum for script /Users/tomburrows/Library/Application Support/factorio/temp/currently-playing/control.lua: 4289712941
 166.989 Info GlobalContext.cpp:678: Waiting for child processes to exit: 
 167.578 Steam API shutdown.
 167.579 Goodbye
My mods
Content: Lunar Landings | Freight Forwarding | Spidertron Patrols | Spidertron Enhancements | Power Overload
QoL: Factory Search | Module Inserter Simplified | Wire Shortcuts X | Ghost Warnings
_italics_
Burner Inserter
Burner Inserter
Posts: 17
Joined: Sat Jan 05, 2019 3:28 pm
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by _italics_ »

Wow, I can also confirm there's no lag using an external mouse.
Sidan
Manual Inserter
Manual Inserter
Posts: 4
Joined: Thu Feb 28, 2019 5:39 pm
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by Sidan »

I also confirm there is no problem at all when using an external mouse. Woah, I can't believe I didn't notice that. That's a nice workaround for the time being
User avatar
ssilk
Global Moderator
Global Moderator
Posts: 12889
Joined: Tue Apr 16, 2013 10:35 pm
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by ssilk »

Yeah, reactivated my mouse. With that the game racts much faster, now playable, but the average load is still extreme, all CPU's are at full load.

The game reaches 56 FPS after waiting for 20 secs. If I move the trackpad the load increases, FPS sinks down to 40 and takes 20 secs to recover.

It takes so much CPU, that all other apps are also influenced by this. I guess: Some threads have too much priority?
Cool suggestion: Eatable MOUSE-pointers.
Have you used the Advanced Search today?
Need help, question? FAQ - Wiki - Forum help
I still like small signatures...
2799
Burner Inserter
Burner Inserter
Posts: 7
Joined: Thu Dec 28, 2017 4:55 am
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by 2799 »

Just to add some more info on this, mouse lag is reproducible on my 2011 MBP in the release notes GUI, when viewing the notes for 0.17 -> 0.17.4.
FPS is between 20 - 40. This is using trackpad; I don't have an external mouse to check with currently.

I also have occasional keyboard lag when actually playing.
2799
Burner Inserter
Burner Inserter
Posts: 7
Joined: Thu Dec 28, 2017 4:55 am
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by 2799 »

I've attached 2 performance test files in case they are of some use.

The first is from opening the release notes GUI and then moving the mouse around. Around line 160 you can see the CPU time spike. I get mouse lag etc where menu items fail to highlight on hover.

The second is from starting a new game and running around a bit, while zooming in and out. It seemed to be related to trees being visible; I get keyboard lag while running around and zooming. FPS was around 40/60 at that time.

I noticed in the file there is a frame drops column but it's always zero.
Attachments
perf2.csv
playtest
(129.88 KiB) Downloaded 136 times
perf1.csv
release notes GUI
(119.67 KiB) Downloaded 129 times
meyercm
Manual Inserter
Manual Inserter
Posts: 1
Joined: Sun Mar 03, 2019 1:26 am
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by meyercm »

Can also confirm trackpad zoom is very laggy on 10.14.2, 2015 era MBP. Keystrokes (WASD movement) also seem to queue behind lagged trackpad zoom

Thanks to whoever discovered the external mouse workaround.
ezekiel
Burner Inserter
Burner Inserter
Posts: 15
Joined: Sun Mar 03, 2019 10:01 pm
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by ezekiel »

Hi All,

I wanted to contribute my report:

I have a Late 2016 MacBook Pro (built-in trackpad and keyboard) and I'm seeing this as well. There's significant input lag, both in the mouse and the keyboard. It comes and goes, too–one minute everything's responding then all the sudden it comes back.

At no point do the animations slow down or does the game tick speed go down.
RChestnut
Manual Inserter
Manual Inserter
Posts: 2
Joined: Wed Jan 02, 2019 6:39 am
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by RChestnut »

I am also having this problem on a Mid 2014 MBP. The input seems to slow down the most when I (attempt to) use the built-in trackpad to zoom in and out. Factorio 0.16 ran beautifully on this machine.
SuPeRTRoNeRD
Manual Inserter
Manual Inserter
Posts: 2
Joined: Wed Feb 20, 2019 3:30 pm
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by SuPeRTRoNeRD »

My mid-2013 11-inch MBA is giving me similar lags when scrolling, zooming in or out, moving the map and dragging across burner mining drills to get coal.

Log file is attached.

It seems to me that the lag is caused by macOS specific trackpad gestures, like zooming with two fingers or dragging with three.
Not sure if using a mouse fixes the problem, because I don't have a mouse at hand.
Attachments
factorio-current.log
(7.33 KiB) Downloaded 126 times
danielbrauer
Long Handed Inserter
Long Handed Inserter
Posts: 94
Joined: Thu May 18, 2017 2:22 pm
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by danielbrauer »

Trackpad as culprit makes a lot of sense. Mac OS trackpad scrolling uses far more events than mouse wheel scrolling. This throws off a lot of software which handles input at a low level. If the events are all going into the same queue, and that queue can't keep up with the frequency of trackpad scrolling events, then it should lag if it's not dropping frames. And it seems that while Factorio can drop frames (or render partial frames) with v-sync off, it insists on drawing every frame while v-sync is on.
posila
Factorio Staff
Factorio Staff
Posts: 5357
Joined: Thu Jun 11, 2015 1:35 pm
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by posila »

Thanks for the reports and your logs.

It is fixed for 0.17.5

EDIT: I was stuck in a mindset that the problem was caused mainly by rendering in Retina resolution so I started to prepare downscaled rendering on Friday, but after seeing all your logs and seeing that lot of you don't even run the game at FullHD, let alone on Retina; and lot of you mentioned trackpad so I switch my focus on our event processing code and found bug in it.

Rendering in Retina resolution is still problem on some configurations but it'll be handled as separate issue.
danielbrauer
Long Handed Inserter
Long Handed Inserter
Posts: 94
Joined: Thu May 18, 2017 2:22 pm
Contact:

Re: [0.17.4] [MacOS] Delays in control

Post by danielbrauer »

posila wrote: Mon Mar 04, 2019 12:43 pm It is fixed for 0.17.5
Nice! Thanks for this.
Rendering in Retina resolution is still problem on some configurations but it'll be handled as separate issue.
Awesome. I'm sure there are a lot of people who will appreciate this too.
Post Reply

Return to “Resolved Problems and Bugs”