Small error report for oolite-trunk-1.79.0.5295-131112

[Diziet Sma]

I've just fired up the first of the new 1.79 nightlies for 32-bit Linux, with no added OXPs, and found this in the log.

Code: Select all

Opening log for Oolite development version 1.79.0.5295-131112 (x86-32 test release) under Linux 3.8.0-19-generic at 2013-11-12 10:36:44 -0500.
1 processor detected.
Build options: spoken messages, mass/fuel pricing, JavaScript console support, OXP verifier, localization tools, debug GraphViz support, JavaScript profiling.

Note that the contents of the log file can be adjusted by editing logcontrol.plist.

10:36:46.994 [dataCache.rebuild]: Data cache version (1.77.1) does not match Oolite version (1.79), rebuilding cache.
10:36:48.698 [display.mode.list.native]: X11 native resolution detected: 2560 x 1024
10:36:48.902 [joystick.init]: Number of joysticks detected: 1
10:36:48.904 [rendering.opengl.version]: OpenGL renderer version: 2.1.2 ("2.1.2 NVIDIA 304.88"). Vendor: "NVIDIA Corporation". Renderer: "GeForce 6200/AGP/SSE/3DNOW!".
10:36:48.905 [rendering.opengl.extensions]: OpenGL extensions (190):
GL_ATI_texture_mirror_once, GL_NV_texture_shader2, GL_NV_texture_shader3, GL_ARB_depth_texture, GL_OES_element_index_uint, GL_ARB_texture_env_add, GL_NV_texture_barrier, GL_EXT_pixel_buffer_object, GL_NV_vertex_array_range, GL_NV_fragdepth, GL_EXT_depth_bounds_test, GL_ARB_half_float_vertex, GL_OES_texture_half_float_linear, GL_NV_vertex_program2, GL_EXT_texture3D, GL_NV_vertex_program3, GL_EXT_x11_sync_object, GL_EXT_compiled_vertex_array, GL_NV_vertex_program1_1, GL_EXT_separate_specular_color, GL_ARB_framebuffer_object, GL_EXT_shadow_funcs, GL_EXT_texture_cube_map, GL_EXT_rescale_normal, GL_ATI_draw_buffers, GL_ARB_transpose_matrix, GL_OES_vertex_array_object, GL_EXT_stencil_two_side, GL_ARB_texture_border_clamp, GL_ARB_multisample, GL_ARB_half_float_pixel, GL_NV_fog_distance, GL_ARB_texture_non_power_of_two, GL_EXT_bgra, GL_ARB_sync, GL_EXT_texture_env_dot3, GL_EXT_direct_state_access, GL_NV_light_max_exponent, GL_EXT_provoking_vertex, GL_ARB_sampler_objects, GL_NV_blend_minmax, GL_ARB_vertex_shader, GL_ARB_shader_objects, GL_ARB_map_buffer_range, GL_SGIX_depth_texture, GL_NV_texgen_reflection, GL_EXT_multi_draw_arrays, GL_OES_texture_half_float, GL_NV_complex_primitives, GL_IBM_rasterpos_clip, GL_EXT_texture_object, GL_ARB_vertex_program, GL_NV_framebuffer_multisample_coverage, GL_KTX_buffer_region, GL_ARB_get_program_binary, GL_NV_texture_env_combine4, GL_ARB_texture_float, GL_ARB_imaging, GL_OES_compressed_paletted_texture, GL_OES_get_program_binary, GL_NV_register_combiners, GL_NV_multisample_filter_hint, GL_EXT_stencil_wrap, GL_EXT_texture_swizzle, GL_NV_primitive_restart, GL_EXT_framebuffer_blit, GL_ARB_vertex_array_bgra, GL_NV_packed_depth_stencil, GL_ARB_texture_compression, GL_EXT_blend_color, GL_ARB_vertex_array_object, GL_OES_depth24, GL_EXT_blend_minmax, GL_NV_register_combiners2, GL_ARB_texture_swizzle, GL_SUN_slice_accum, GL_EXT_texture_lod, GL_EXT_texture_compression_s3tc, GL_ARB_occlusion_query2, GL_NV_alpha_test, GL_ARB_timer_query, GL_ARB_point_sprite, GL_ARB_depth_clamp, GL_NV_texture_shader, GL_NV_depth_clamp, GL_EXT_vertex_array, GL_EXT_abgr, GL_NV_blend_square, GL_ARB_vertex_buffer_object, GL_EXT_framebuffer_multisample, GL_NV_texture_rectangle, GL_ARB_multitexture, GL_EXT_vertex_array_bgra, GL_ARB_copy_buffer, GL_EXT_texture_storage, GL_OES_point_sprite, GL_ARB_shadow, GL_EXT_blend_subtract, GL_EXT_separate_shader_objects, GL_OES_packed_depth_stencil, GL_ARB_internalformat_query, GL_ARB_fragment_program_shadow, GL_OES_texture_float_linear, GL_NV_fragment_program_option, GL_ARB_texture_storage, GL_ARB_window_pos, GL_OES_mapbuffer, GL_OES_depth32, GL_NV_fence, GL_IBM_texture_mirrored_repeat, GL_EXT_texture_lod_bias, GL_ARB_texture_env_crossbar, GL_ARB_point_parameters, GL_OES_rgb8_rgba8, GL_NV_fragment_program2, GL_NV_float_buffer, GL_EXT_texture_sRGB_decode, GL_ARB_shading_language_420pack, GL_EXT_timer_query, GL_EXT_texture_edge_clamp, GL_ARB_texture_rg, GL_ARB_robustness, GL_OES_texture_npot, GL_NV_texture_lod_clamp, GL_EXT_texture_sRGB, GL_EXT_texture_compression_dxt1, GL_ARB_texture_env_combine, GL_ARB_fragment_program, GL_ARB_explicit_attrib_location, GL_SGIX_shadow, GL_EXT_texture_filter_anisotropic, GL_NV_vertex_program2_option, GL_NV_vertex_array_range2, GL_EXT_draw_range_elements, GL_OES_depth_texture, GL_ARB_pixel_buffer_object, GL_OES_standard_derivatives, GL_EXT_import_sync_object, GL_NV_pixel_data_range, GL_EXT_packed_depth_stencil, GL_ARB_texture_rectangle, GL_EXT_fog_coord, GL_ARB_separate_shader_objects, GL_NV_half_float, GL_NV_ES1_1_compatibility, GL_SGIS_texture_lod, GL_ARB_ES2_compatibility, GL_EXT_gpu_program_parameters, GL_ARB_fragment_shader, GL_ARB_shading_language_100, GL_EXT_secondary_color, GL_NV_occlusion_query, GL_NV_fbo_color_attachments, GL_EXT_point_parameters, GL_EXT_Cg_shader, GL_NV_vertex_program, GL_EXT_texture_format_BGRA8888, GL_ARB_draw_buffers, GL_SGIS_generate_mipmap, GL_NV_copy_depth_to_color, GL_OES_vertex_half_float, GL_NVX_conditional_render, GL_NV_texture_compression_vtc, GL_OES_read_format, GL_OES_point_size_array, GL_EXT_blend_func_separate, GL_NV_fragment_program, GL_ARB_texture_cube_map, GL_OES_texture_float, GL_S3_s3tc, GL_NV_point_sprite, GL_ARB_occlusion_query, GL_EXT_texture_mirror_clamp, GL_OES_texture_3D, GL_ARB_shading_language_include, GL_EXT_packed_pixels, GL_EXT_framebuffer_object, GL_ARB_compressed_texture_pixel_storage, GL_ARB_texture_mirrored_repeat, GL_ARB_texture_env_dot3, GL_ARB_map_buffer_alignment, GL_OES_fbo_render_mipmap, GL_ARB_provoking_vertex, GL_EXT_blend_equation_separate, GL_NV_texture_expand_normal, GL_ATI_texture_float, GL_ARB_color_buffer_float, GL_EXT_texture_env_add, GL_EXT_texture_env_combine, GL_ARB_conservative_depth
10:36:49.008 [rendering.opengl.shader.support]: Shaders are supported.
10:36:51.040 [dataCache.rebuild]: Data cache version (1.77.1) does not match Oolite version (1.79), rebuilding cache.
10:36:51.041 [searchPaths.dumpAll]: Unrestricted mode - resource paths:
    ~/GNUstep/Applications/Oolite-trunk/oolite.app/Resources
    AddOns
    AddOns/Basic-debug.oxp
10:36:51.470 [shipData.load.begin]: Loading ship data.
10:36:52.434 [script.javascript.init]: JavaScript reset successful.
10:36:52.664 [script.load.world.listAll]: Loaded 13 world scripts:
    oolite-cloaking-device 1.79
    oolite-constrictor-hunt 1.79
    oolite-contracts-cargo 1.79
    oolite-contracts-helpers 1.79
    oolite-contracts-parcels 1.79
    oolite-contracts-passengers 1.79
    oolite-libPriorityAI 1.79
    oolite-nova 1.79
    oolite-populator 1.79
    oolite-primable-equipment-register 1.79
    oolite-registership 1.79
    oolite-thargoid-plans 1.79
    oolite-trumbles 1.79
10:36:55.011 [debugTCP.disconnect]: No connection to debug console: "Connection to debug console failed: '' (outStream status: 7, inStream status: 7)."
10:36:55.014 [debugTCP.send.warning]: Error sending packet header, retrying.
10:36:55.038 [debugTCP.send.error]: The following packet could not be sent: {"Oolite version" = 1.79; "packet type" = "Request Connection"; "protocol version" = 65792; }
10:36:55.193 [debugTCP.disconnect]: No connection to debug console: "Connection to debug console failed: 'bad stream.' (outStream status: 0, inStream status: 0)."
10:36:55.194 [debugTCP.connect.failed]: Failed to connect to debug console at address 127.0.0.1:8563.
10:37:05.178 [startup.complete]: ========== Loading complete in 17.10 seconds. ==========
10:45:10.902 [script.javaScript.exception.notDefined]: ***** JavaScript exception (Oolite Assassin AI 1.79): ReferenceError: ai is not defined
10:45:10.990 [script.javaScript.exception.notDefined]: ***** JavaScript exception (Oolite Assassin AI 1.79): ReferenceError: ai is not defined
10:45:12.422 [script.javaScript.exception.notDefined]: ***** JavaScript exception (Oolite Assassin AI 1.79): ReferenceError: ai is not defined
10:45:25.901 [script.javaScript.exception.notDefined]: ***** JavaScript exception (Oolite Assassin AI 1.79): ReferenceError: ai is not defined
10:45:26.001 [script.javaScript.exception.notDefined]: ***** JavaScript exception (Oolite Assassin AI 1.79): ReferenceError: ai is not defined
10:45:27.434 [script.javaScript.exception.notDefined]: ***** JavaScript exception (Oolite Assassin AI 1.79): ReferenceError: ai is not defined
10:45:33.149 [script.javaScript.exception.notDefined]: ***** JavaScript exception (Oolite Assassin AI 1.79): ReferenceError: ai is not defined
10:45:35.782 [script.javaScript.exception.notDefined]: ***** JavaScript exception (Oolite Assassin AI 1.79): ReferenceError: ai is not defined
10:45:36.715 [script.javaScript.exception.notDefined]: ***** JavaScript exception (Oolite Assassin AI 1.79): ReferenceError: ai is not defined
10:45:48.162 [script.javaScript.exception.notDefined]: ***** JavaScript exception (Oolite Assassin AI 1.79): ReferenceError: ai is not defined
10:45:50.794 [script.javaScript.exception.notDefined]: ***** JavaScript exception (Oolite Assassin AI 1.79): ReferenceError: ai is not defined
10:45:51.727 [script.javaScript.exception.notDefined]: ***** JavaScript exception (Oolite Assassin AI 1.79): ReferenceError: ai is not defined
10:46:03.174 [script.javaScript.exception.notDefined]: ***** JavaScript exception (Oolite Assassin AI 1.79): ReferenceError: ai is not defined
10:50:04.238 [exit.context]: Exiting: Exit Game selected on options screen.
10:50:04.251 [gameController.exitApp]: .GNUstepDefaults synchronized.

Closing log at 2013-11-12 10:50:04 -0500.

Also, in the run prior to this one, with all my usual OXPs installed except the Griff Replace set, the game appeared to load fine, I got a black screen with the Development version 1.79.0.5295-131112 text displaying, but before anything else appeared, the game crashed to desktop.

In part the log shows this error:

Code: Select all

10:21:34.154 [script.missionDescription.noMissionText]: ***** ERROR: in <anonymous actions>, no mission text set for key 'display_reputation_passenger_1.2' [UNIVERSE missiontext] is:

which was followed by a complete printout of ALL 3300+ lines of the UNIVERSE missiontext.

After that came:

Code: Select all

10:24:00.387 [script.javaScript.timeLimit]: ***** ERROR: Script "Cabal_Common_OXPStrength" ran for 5.74685 seconds and has been terminated.
10:24:00.480 [script.javaScript.stackTrace]:  0 (Cabal_Common_OXPStrength.js:22) <anonymous function>
10:24:00.503 [script.javaScript.stackTrace]:     this: [Script "Cabal_Common_OXPStrength" version 1.7]
10:24:00.537 [script.javaScript.stackTrace]:     temp: "function (ship, whomposition) {\n    \"use strict\";\n    if (!ship || !ship.isValid || !whomposition || !whomposition.x) {\n        return \"\";\n    }\n    var v = ship.position.subtract(whomposition);\n    v..."
10:24:00.537 [script.javaScript.stackTrace]:     prop: "$Telescope_From"
10:24:00.537 [script.javaScript.stackTrace]:     w: "telescope"
10:24:18.396 [setup.ship.badEntry.subentities]: Failed to set up entity griff_normalmapped_cobra_mkIII_front_gun
10:24:19.975 [setup.ship.badEntry.subentities]: Failed to set up entity griff_normalmapped_python_gun_NPC
10:24:20.365 [setup.ship.badEntry.subentities]: Failed to set up entity griff_normalmapped_gecko_gun_NPC
10:24:20.528 [setup.ship.badEntry.subentities]: Failed to set up entity griff_normalmapped_boa-mk2_eng
10:24:20.933 [setup.ship.badEntry.subentities]: Failed to set up entity griff_normalmapped_cobra_mkIII_front_gun
10:24:23.433 [setup.ship.badEntry.subentities]: Failed to set up entity griff_normalmapped_asp_mk2_gun_NPC
10:24:23.772 [setup.ship.badEntry.subentities]: Failed to set up entity griff_normalmapped_cobra_mkIII_front_gun
10:24:24.811 [setup.ship.badEntry.subentities]: Failed to set up entity griff_normalmapped_cobra_mkIII_front_gun
10:24:25.407 [setup.ship.badEntry.subentities]: Failed to set up entity griff_normalmapped_asp_mk2_gun_NPC
10:24:25.479 [setup.ship.badEntry.subentities]: Failed to set up entity griff_normalmapped_gecko_gun_NPC
10:24:33.139 [startup.complete]: ========== Loading complete in 212.65 seconds. ==========

And then the game CTD'd with no useful information in the log.

I presume the massive long list of missiontext was due to Display Reputation causing 1.79 to have a hissy-fit, but hopefully something in the final section of the log might prove useful in tracking down the CTD. I can provide a full OXP list if you wish.

[Tricky]

Code: Select all

10:21:34.154 [script.missionDescription.noMissionText]: ***** ERROR: in <anonymous actions>, no mission text set for key 'display_reputation_passenger_1.2' [UNIVERSE missiontext] is:

which was followed by a complete printout of ALL 3300+ lines of the UNIVERSE missiontext.

That's due to the fact that the reputation values in v1.79 have a fractional part in the number.  Display Reputation OXP was coded for whole reputation numbers only. It is an easy* fix involving Math.floor().

Reported to the relevant OXP thread.

--
* Easy for OXP script coders.

[cim]

10:45:10.902 [script.javaScript.exception.notDefined]: ***** JavaScript exception (Oolite Assassin AI 1.79): ReferenceError: ai is not defined

It definitely is defined. I'm not sure what that's about yet.

10:24:33.139 [startup.complete]: ========== Loading complete in 212.65 seconds. ==========

Is that a typical time for an uncached startup with your OXPs in 1.77.1?

A full OXP list probably isn't going to be that useful, but if you could try the usual "take out half" search to see if the crash is caused by a particular OXP or just general volume of them, then we can probably do a bit more.

That's due to the fact that the reputation values in v1.79 have a fractional part in the number.

Ah. It might not be the only OXP expecting that, either. Possibly I need to put that back to being an integer and introduce a passengerReputationPrecise property (and similarly for the other two).

[Diziet Sma]

10:24:33.139 [startup.complete]: ========== Loading complete in 212.65 seconds. ==========

Is that a typical time for an uncached startup with your OXPs in 1.77.1?

Yes.. 200-odd OXPs takes a while to load on this old antique 'puter..

A full OXP list probably isn't going to be that useful, but if you could try the usual "take out half" search to see if the crash is caused by a particular OXP or just general volume of them, then we can probably do a bit more.

Will do.

[cim]

Code: Select all

10:21:34.154 [script.missionDescription.noMissionText]: ***** ERROR: in <anonymous actions>, no mission text set for key 'display_reputation_passenger_1.2' [UNIVERSE missiontext] is:

which was followed by a complete printout of ALL 3300+ lines of the UNIVERSE missiontext.

That's due to the fact that the reputation values in v1.79 have a fractional part in the number.  Display Reputation OXP was coded for whole reputation numbers only. It is an easy* fix involving Math.floor().

Okay, from tonight, the contractReputation parameter will be back to being an integer. contractReputationPrecise is available for the full reputation. Same for parcels and passengers. That should fix that one up, and possibly some other OXPs too.

Thanks for the report, and thanks Tricky for the quick diagnosis.

[Tricky]

Heh! I noticed it a couple of days before and forgot to report it.