Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.01.2023 04:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15100276 kB
MemAvailable 30391436 kB
Buffers 20 kB
Cached 16556056 kB
SwapCached 4412 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.3
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-01-09 04:37:23
DTS_END                         2023-01-09 04:37:23.9900
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59950
TRN_ID                          221761
ATT_ID                          627
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9139.53 28597 1096744
customer order: creation 168.10 459 20172
customer order: refuse 14.68 362 1761
order to supplier: creation 45.47 1296 5456
order to supplier: removal 3.45 163147 414
invoice (draft): creation 44.66 3017 5359
invoice (draft): removal 50.08 964 6009
invoice accept: apply 41.48 25178 4977
invoice accept: cancel 45.64 1041 5477
customer reserve: creation 3955.14 234 474617
customer reserve: removal 4488.98 98 538677
realization accept: apply 82.94 259 9953
realization accept: cancel 70.18 174 8422
payment from customer: creation 59.43 171 7131
payment from customer: removal 5.77 123 692
payment to supplier: creation 47.64 198 5717
payment to supplier: removal 3.46 101 415
service: total inventory turnovers 4.06 3776 487
service: total monetary turnovers 7.38 337 886
service: aggregate perf. data 0.98 13671 118
service: refresh index statistics 0.03 357329 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-01-2023 04:39:00 to 09-01-2023 04:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4973
TEST_TIME 2 5369
TEST_TIME 3 5356
TEST_TIME 4 4927
TEST_TIME 5 5228
TEST_TIME 6 5021
TEST_TIME 7 5255
TEST_TIME 8 5503
TEST_TIME 9 5619
TEST_TIME 10 5938
TEST_TIME 11 6159
TEST_TIME 12 6366
TEST_TIME 13 6495
TEST_TIME 14 6788
TEST_TIME 15 6855
TEST_TIME 16 7101
TEST_TIME 17 7129
TEST_TIME 18 7300
TEST_TIME 19 7297
TEST_TIME 20 7643
TEST_TIME 21 7799
TEST_TIME 22 7874
TEST_TIME 23 7862
TEST_TIME 24 7977
TEST_TIME 25 8075
TEST_TIME 26 8510
TEST_TIME 27 8603
TEST_TIME 28 8887
TEST_TIME 29 9676
TEST_TIME 30 10388
TEST_TIME 31 10389
TEST_TIME 32 11050
TEST_TIME 33 11014
TEST_TIME 34 10825
TEST_TIME 35 10786
TEST_TIME 36 10805
TEST_TIME 37 10754
TEST_TIME 38 10864
TEST_TIME 39 10858
TEST_TIME 40 10855
TEST_TIME 41 10620
TEST_TIME 42 10449
TEST_TIME 43 10369
TEST_TIME 44 10242
TEST_TIME 45 10134
TEST_TIME 46 10070
TEST_TIME 47 10013
TEST_TIME 48 10047
TEST_TIME 49 10029
TEST_TIME 50 9955
TEST_TIME 51 9818
TEST_TIME 52 9991
TEST_TIME 53 9957
TEST_TIME 54 9889
TEST_TIME 55 9794
TEST_TIME 56 9811
TEST_TIME 57 9759
TEST_TIME 58 9878
TEST_TIME 59 9837
TEST_TIME 60 9829
TEST_TIME 61 9841
TEST_TIME 62 9913
TEST_TIME 63 9888
TEST_TIME 64 9967
TEST_TIME 65 9963
TEST_TIME 66 9932
TEST_TIME 67 9798
TEST_TIME 68 9749
TEST_TIME 69 9760
TEST_TIME 70 9677
TEST_TIME 71 9673
TEST_TIME 72 9611
TEST_TIME 73 9576
TEST_TIME 74 9565
TEST_TIME 75 9531
TEST_TIME 76 9522
TEST_TIME 77 9514
TEST_TIME 78 9544
TEST_TIME 79 9513
TEST_TIME 80 9478
TEST_TIME 81 9564
TEST_TIME 82 9545
TEST_TIME 83 9609
TEST_TIME 84 9614
TEST_TIME 85 9685
TEST_TIME 86 9608
TEST_TIME 87 9588
TEST_TIME 88 9532
TEST_TIME 89 9528
TEST_TIME 90 9485
TEST_TIME 91 9426
TEST_TIME 92 9377
TEST_TIME 93 9369
TEST_TIME 94 9355
TEST_TIME 95 9354
TEST_TIME 96 9337
TEST_TIME 97 9326
TEST_TIME 98 9320
TEST_TIME 99 9310
TEST_TIME 100 9378
TEST_TIME 101 9396
TEST_TIME 102 9417
TEST_TIME 103 9484
TEST_TIME 104 9524
TEST_TIME 105 9504
TEST_TIME 106 9584
TEST_TIME 107 9535
TEST_TIME 108 9498
TEST_TIME 109 9441
TEST_TIME 110 9441
TEST_TIME 111 9425
TEST_TIME 112 9388
TEST_TIME 113 9347
TEST_TIME 114 9323
TEST_TIME 115 9271
TEST_TIME 116 9277
TEST_TIME 117 9242
TEST_TIME 118 9217
TEST_TIME 119 9191
TEST_TIME 120 9158

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 09-01-2023 04:39:03 to 09-01-2023 04:39:07.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1532792 1532792 0 0.00 0 17973 13 0 0
doc_list_biud 1532788 1532788 0 0.00 0 22227 39 0 0
sp_add_invoice_to_stock 5113 4977 136 2.65 34 172726 25179 0 136
sp_cancel_adding_invoice 5477 5477 0 0.00 6 75383 1041 0 0
sp_cancel_client_order 1761 1761 0 0.00 6 12251 363 0 0
sp_cancel_customer_reserve 538677 538677 0 0.00 1 22233 99 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 4 9617 123 0 0
sp_cancel_pay_to_supplier 415 415 0 0.00 3 1609 101 0 0
sp_cancel_supplier_invoice 6018 6009 9 0.14 2 13946 964 0 9
sp_cancel_supplier_order 414 414 0 0.00 9 1440516 163148 0 0
sp_cancel_write_off 8422 8422 0 0.00 2 11909 174 0 0
sp_client_order 20172 20172 0 0.00 6 25130 460 0 0
sp_customer_reserve 474617 474617 0 0.00 0 86418 234 0 0
sp_fill_shopping_cart 30977 30977 0 0.00 1 14633 77 0 0
sp_fill_shopping_cart_clo_res 474605 474605 0 0.00 0 17881 18 0 0
sp_get_clo_for_invoice 4940 4940 0 0.00 0 25539 304 0 0
sp_kill_cost_storno 15006 15006 0 0.00 0 11092 73 0 0
sp_kill_qstorno_ret_qs2qd 1092199 1092199 0 0.00 0 22187 43 0 0
sp_kill_qty_storno 575651 575651 0 0.00 0 22225 97 0 0
sp_lock_selected_doc 20696 20696 0 0.00 0 10234 8 0 0
sp_make_cost_storno 27739 27739 0 0.00 0 13957 80 0 0
sp_multiply_rows_for_pdistr 27739 27739 0 0.00 0 8579 40 0 0
sp_multiply_rows_for_qdistr 20172 20172 0 0.00 0 16861 186 0 0
sp_pay_from_customer 7131 7131 0 0.00 5 17956 171 0 0
sp_pay_to_supplier 5717 5717 0 0.00 6 14162 199 0 0
sp_payment_common 12848 12848 0 0.00 2 14086 51 0 0
sp_qd_handle_on_cancel_clo 1761 1761 0 0.00 1 12242 304 0 0
sp_qd_handle_on_invoice_upd_sts 10415 10415 0 0.00 0 12416 463 0 0
sp_qd_handle_on_reserve_upd_sts 9953 9953 0 0.00 0 12001 53 0 0
sp_reserve_write_off 9953 9953 0 0.00 8 18277 260 0 0
sp_supplier_invoice 5359 5359 0 0.00 8 87329 3017 0 0
sp_supplier_order 5456 5456 0 0.00 8 36925 1297 0 0
srv_aggregate_perf_data 118 118 0 0.00 1903 129705 13672 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 711 155535 34993 0 0
srv_find_qd_qs_mism 1047253 1047253 0 0.00 0 15241 9 0 0
srv_make_invnt_saldo 487 487 0 0.00 78 71670 3776 0 0
srv_make_money_saldo 886 886 0 0.00 1 13645 337 0 0
srv_recalc_idx_stat 4 4 0 0.00 317817 413054 357329 0 0
t$perf-abend:sp_add_invoice_to_stock 136 136 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4978 4978 0 0.00 0 14164 842 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 8706 385 0 0
t$perf-norm:sp_cancel_client_order 1761 1761 0 0.00 0 5490 31 0 0
t$perf-norm:sp_cancel_customer_reserve 687 687 0 0.00 0 806 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 6106 35 0 0
t$perf-norm:sp_cancel_pay_to_supplier 415 415 0 0.00 0 1731 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 548 24 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 81963 6526 0 0
t$perf-norm:sp_cancel_write_off 1008 1008 0 0.00 0 2417 19 0 0
t$perf-norm:sp_client_order 20172 20172 0 0.00 0 8435 35 0 0
t$perf-norm:sp_customer_reserve 1008 1008 0 0.00 0 791 15 0 0
t$perf-norm:sp_pay_from_customer 7131 7131 0 0.00 0 5842 19 0 0
t$perf-norm:sp_pay_to_supplier 5717 5717 0 0.00 0 8425 24 0 0
t$perf-norm:sp_reserve_write_off 9953 9953 0 0.00 0 8183 24 0 0
t$perf-norm:sp_supplier_invoice 5359 5359 0 0.00 0 8659 33 0 0
t$perf-norm:sp_supplier_order 5456 5456 0 0.00 0 7266 36 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 197 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 2622 43 0 0
t$perf-norm:srv_make_invnt_saldo 487 487 0 0.00 0 3082 27 0 0
t$perf-norm:srv_make_money_saldo 886 886 0 0.00 0 2659 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 6 191 75 0 0
v_add_invoice_to_stock 10148 9876 272 2.68 0 6527 24 0 272
v_all_customers 37446 37446 0 0.00 0 60 3 0 0
v_all_suppliers 30282 30282 0 0.00 0 136 4 0 0
v_all_wares 40340 40340 0 0.00 0 86 3 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 856 13 0 0
v_cancel_client_order 3522 3522 0 0.00 0 562 8 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 3249 13 0 0
v_cancel_customer_reserve 1374 1374 0 0.00 0 143 9 0 0
v_cancel_payment_to_supplier 830 830 0 0.00 0 844 11 0 0
v_cancel_supplier_invoice 854 836 18 2.10 0 3810 24 0 18
v_cancel_supplier_order 828 828 0 0.00 0 128 9 0 0
v_cancel_write_off 2016 2016 0 0.00 0 7463 25 0 0
v_max_id_clo_ord 5456 5456 0 0.00 0 3648 5 0 0
v_max_id_clo_res 1008 1008 0 0.00 0 2160 12 0 0
v_max_id_ord_sup 5351 5351 0 0.00 0 3966 7 0 0
v_max_non_paid_invoice 1383 1383 0 0.00 0 1933 9 0 0
v_max_non_paid_realizn 3584 3584 0 0.00 0 1668 7 0 0
v_min_id_clo_ord 5456 5456 0 0.00 0 1777 7 0 0
v_min_id_clo_res 1008 1008 0 0.00 0 8199 18 0 0
v_min_id_ord_sup 5351 5351 0 0.00 0 7243 10 0 0
v_min_non_paid_invoice 5717 5717 0 0.00 0 14062 24 0 0
v_min_non_paid_realizn 7131 7131 0 0.00 0 13453 27 0 0
v_our_firm 9988 9988 0 0.00 0 73 4 0 0
v_reserve_write_off 19906 19906 0 0.00 0 10348 10 0 0
x_lock_depdocs_on_canc_invoice 11068 11068 0 0.00 0 8182 109 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 8905 344 0 0
x_make_qstorno_1000_1200 5456 5456 0 0.00 2 36900 1145 0 0
x_make_qstorno_1200_2000 5359 5359 0 0.00 4 86107 2796 0 0
x_make_qstorno_2100_3300 474617 474617 0 0.00 0 86404 205 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-01-2023 04:39:08 to 09-01-2023 04:39:12.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:27 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
02:08:28 30202 8192000000 9730390896 10030989312 800407648 8.900 100 83 166 0 828168384 7306640 20454096
02:10:05 12302 8192000000 9775824336 10091384832 855757360 9.458 100 86 173 0 888301504 12440160 20103984
02:11:26 13279 8192000000 9824111504 10147983360 889181776 9.791 100 82 164 0 929011072 21478144 18351152
02:12:45 10645 8192000000 9872569408 10206928896 920753024 10.104 100 90 175 1 986084832 45880560 19451248
02:13:59 4737 8192000000 9922983248 10268307456 957758032 10.467 100 87 165 2 1038886960 63713616 17415312
02:15:07 3162 8192000000 9956352944 10312646656 986224320 10.745 100 76 149 0 1077046400 75845136 14976944
02:16:12 1940 8192000000 9977235104 10336710656 1017248464 11.045 100 88 166 1 1090150464 56125264 16776736
02:17:17 1159 8192000000 10021077792 10391171072 1034060240 11.208 100 87 169 0 1142165792 91383904 16721648
02:18:19 750 8192000000 10046318000 10419257344 1052104864 11.381 100 98 192 1 1167028368 96307072 18616432
02:19:21 1566 8192000000 10026601456 10399977472 1064205056 11.497 100 90 170 0 1148399056 67229952 16964048
02:20:25 1222 8192000000 10038331920 10419904512 1069445248 11.547 100 96 191 4 1165093296 75732720 19915328
02:21:27 1335 8192000000 10064788720 10450706432 1077905168 11.628 100 99 191 3 1190737488 93942240 18890080
02:22:30 1120 8192000000 10054629888 10442866688 1081893488 11.666 100 94 179 2 1180314112 79391312 19029312
02:23:37 1106 8192000000 10045244752 10427715584 1084967872 11.695 100 98 185 1 1166587568 62511392 19108304
02:24:40 1289 8192000000 10067774992 10454335488 1093363440 11.775 100 97 188 1 1188346672 75412608 19570624
02:25:42 1089 8192000000 10071794624 10464366592 1092508576 11.767 100 96 180 0 1193880336 82379040 18992720
02:26:44 1229 8192000000 10075018464 10465828864 1101200016 11.849 100 96 189 0 1201283904 81660864 18423024
02:27:46 908 8192000000 10070595088 10468306944 1099716640 11.835 100 75 142 2 1195235600 80063424 15455536
02:28:49 1254 8192000000 10059176480 10452770816 1088674480 11.730 100 98 183 1 1181282064 74025888 18581696
02:29:50 1114 8192000000 10044428576 10429468672 1076479952 11.614 100 96 175 3 1165553984 69869664 19204368
02:30:52 1462 8192000000 10023635056 10407985152 1074521904 11.595 99 82 155 4 1145804288 54663312 16619072
02:31:55 1015 8192000000 10031852592 10407845888 1070606576 11.558 100 98 192 0 1153470000 63342336 19521088
02:32:57 876 8192000000 10004413136 10379726848 1041165696 11.276 100 89 172 0 1130437024 71269920 18001408
02:33:59 5303 8192000000 10006259056 10395947008 1056967248 11.427 100 57 111 1 1133019584 63905056 12147280
02:35:05 11707 8192000000 9959851024 10331111424 980448752 10.689 95 54 104 2 1059145568 68529392 10167424
02:36:18 3168 8192000000 9991252560 10379956224 1033796256 11.205 100 63 123 1 1111655808 65586624 12272928
02:37:23 6081 8192000000 9955666816 10333429760 1019991040 11.072 99 69 132 3 1071589568 39541360 12057168
02:38:38 1506 8192000000 9995719648 10371981312 1046347296 11.326 100 77 141 1 1116064992 53877072 15840624
02:39:43 13686 8192000000 10032193360 10419441664 1065581264 11.510 100 63 115 3 1159960368 82079872 12299232
02:41:04 18573 8192000000 10035052416 10419838976 1075178576 11.602 100 71 135 0 1155028864 64945360 14904928
02:42:32 1923 8192000000 10085052800 10474549248 1100516864 11.843 100 84 159 1 1206277776 87484896 18276016
02:43:38 1875 8192000000 10126141184 10515025920 1111846064 11.950 100 80 149 1 1246235616 118215984 16173568
02:44:44 4815 8192000000 10183494640 10584895488 1116545808 11.994 100 82 156 1 1294914112 160625936 17742368
02:45:56 4462 8192000000 10186979632 10591621120 1127002432 12.093 100 77 147 2 1300297824 157260960 16034432
02:47:06 2363 8192000000 10204968256 10616180736 1142334000 12.237 100 78 152 1 1321487904 162359136 16794768
02:48:19 1834 8192000000 10267945968 10675363840 1165464352 12.454 100 80 160 0 1382364720 199037632 17862736
02:49:27 708 8192000000 10287776976 10687762432 1166970672 12.469 100 93 177 0 1398627200 212088720 19567808
02:50:32 1289 8192000000 10323828048 10735538176 1181808832 12.607 100 96 182 3 1431638640 229477120 20352688
02:51:35 1220 8192000000 10365869280 10773315584 1183902704 12.627 100 94 179 3 1472951136 268077456 20970976
02:52:39 814 8192000000 10386002032 10800754688 1194436112 12.725 100 96 185 0 1498522976 282538752 21548112
02:53:42 779 8192000000 10430943824 10840817664 1199557120 12.772 100 96 191 1 1538192912 316376944 22258848
02:54:44 2332 8192000000 10469153520 10899968000 1207353600 12.845 100 91 180 1 1577680352 348216496 22110256
02:55:50 1015 8192000000 10506710128 10922004480 1209055872 12.860 100 95 185 4 1609295840 378674112 21565856
02:56:56 859 8192000000 10525352192 10974892032 1216591312 12.930 100 99 196 0 1632295840 391273456 24431072
02:57:59 1262 8192000000 10564109504 10986606592 1220043104 12.962 100 86 168 2 1672860448 432622800 20194544
02:59:03 3095 8192000000 10549548896 10997911552 1222841440 12.988 100 61 120 0 1660593344 422612592 15139312
03:00:11 2329 8192000000 10584882672 11008733184 1213476000 12.901 100 89 162 2 1698678912 465719280 19483632
03:01:15 5074 8192000000 10652285840 11084333056 1214147104 12.908 100 68 137 2 1757477696 525819328 17511264
03:02:24 988 8192000000 10629864320 11051651072 1195284944 12.733 100 73 144 0 1731126560 518513664 17327952
03:03:33 3492 8192000000 10609796848 11103961088 1177496656 12.567 100 50 100 0 1724512816 533270304 13745856
03:05:34 8978 8192000000 10541736656 11005927424 1168092496 12.479 100 41 82 0 1645246016 466080928 11072592
03:06:44 23059 8192000000 10333992816 10818887680 1143090624 12.245 99 33 63 1 1478478752 326826416 8561712
03:08:16 39435 8192000000 10282916288 10774949888 1128323888 12.106 98 17 34 0 1357238128 224961984 3952256
03:10:13 26899 8192000000 10060889408 10495344640 1126962160 12.093 99 28 50 2 1159273008 27527008 4783840
03:11:46 12556 8192000000 10104572912 10536140800 1136315344 12.181 100 48 93 0 1193528256 47115600 10097312
03:13:06 87121 8192000000 10102028576 10545328128 1109449776 11.927 97 18 34 0 1170679712 57859136 3370800
03:15:41 155503 8192000000 10062013248 10524372992 1062598512 11.481 93 15 30 0 1122035488 56598816 2838160
03:19:18 57458 8192000000 10079278768 10503274496 1103771312 11.873 97 54 106 2 1137293024 23249040 10272672
03:21:17 67976 8192000000 10119290064 10554961920 1139271424 12.209 99 39 73 1 1204375056 57413056 7690576
03:23:27 46670 8192000000 10175207520 10626523136 1147507456 12.286 100 27 54 0 1262308640 108315344 6485840
03:25:19 35085 8192000000 10208562848 10683076608 1155126080 12.358 100 40 74 1 1319457712 155245360 9086272
03:27:09 74927 8192000000 10178091776 10655133696 1154256896 12.349 100 16 32 0 1279594400 121804448 3533056
03:29:28 34029 8192000000 10113306000 10669191168 1136435280 12.182 99 11 22 0 1247761584 108738960 2587344
03:31:19 52673 8192000000 10107143472 10605187072 1138096496 12.198 99 7 14 0 1189462752 49635376 1730880
03:33:54 56149 8192000000 10138272704 10587668480 1140431728 12.220 100 43 82 0 1223898240 75008544 8457968
03:35:55 43637 8192000000 10112817552 10558550016 1129081168 12.113 98 29 54 3 1189537760 54673456 5783136
03:37:39 56086 8192000000 10114514448 10569195520 1101926208 11.856 96 18 34 0 1169404080 63762672 3715200
03:39:55 40102 8192000000 10118537872 10597060608 1138375008 12.200 100 21 42 0 1205104864 62372496 4357360
03:41:51 96743 8192000000 10087994704 10553159680 1106390000 11.898 97 14 27 0 1146765280 37624944 2750336
03:44:33 60507 8192000000 10113521648 10555170816 1130267312 12.124 99 55 108 1 1206537104 64372544 11897248
03:46:35 31286 8192000000 10075063392 10563063808 1125908256 12.083 99 22 44 0 1170244304 40295120 4040928
03:48:08 24532 8192000000 10093785776 10540867584 1118143376 12.009 98 46 92 0 1181114144 54017504 8953264
03:50:47 35697 8192000000 10058941120 10556456960 1127299072 12.096 100 4 7 0 1159046128 31294320 452736
03:52:22 37961 8192000000 10080250448 10514534400 1092954592 11.771 96 34 66 0 1133634720 33769936 6910192
03:54:01 41255 8192000000 10111852880 10571554816 1105694608 11.892 96 23 46 0 1173447312 62698784 5053920
03:55:43 30461 8192000000 10108470432 10552909824 1124343504 12.068 99 18 36 0 1192726528 64459744 3923280
03:57:14 13893 8192000000 10164612208 10602258432 1135248992 12.171 100 46 86 0 1256289376 111144544 9895840
03:58:40 44185 8192000000 10128987040 10567979008 1125213984 12.076 99 30 54 1 1222093440 90741120 6138336
04:00:34 9636 8192000000 10126616272 10572976128 1138068704 12.197 100 55 110 1 1208880336 60031328 10780304
04:01:45 11152 8192000000 10126187968 10552905728 1120036304 12.027 99 48 94 1 1199621024 69963776 9620944
04:02:59 84801 8192000000 10099983424 10548953088 1119031488 12.018 99 31 62 0 1190075168 64212192 6831488
04:05:29 38360 8192000000 10073765680 10547113984 1105754272 11.892 98 20 40 0 1153997488 44408352 3834864
04:07:09 70336 8192000000 10053395120 10521882624 1109686624 11.929 98 10 20 0 1148704784 37204960 1813200
04:09:20 50409 8192000000 10085740560 10545491968 1126124400 12.085 99 9 18 0 1172116416 44085728 1906288
04:11:15 42986 8192000000 10075655696 10550452224 1120318096 12.030 98 9 18 0 1166395920 44125888 1951936
04:13:00 28899 8192000000 10080720784 10539970560 1145606880 12.268 100 22 41 1 1207112272 56617184 4888208
04:14:37 45327 8192000000 10121138640 10582700032 1119317088 12.021 98 38 76 0 1195781696 68528288 7936320
04:16:25 57895 8192000000 10111496768 10585604096 1099382704 11.832 96 18 36 0 1201330832 97760544 4187584
04:18:29 45926 8192000000 10127965536 10572210176 1135635776 12.174 99 18 36 0 1215248112 75864736 3747600
04:20:16 67956 8192000000 10111319856 10596024320 1107519120 11.909 97 18 36 0 1153268560 42423648 3325792
04:22:24 89592 8192000000 10099248336 10599555072 1131607520 12.137 99 11 22 0 1205932912 72494416 1830976
04:24:59 100442 8192000000 10074338736 10532167680 1123921424 12.064 99 27 54 0 1179663888 50095168 5647296
04:27:48 64848 8192000000 10062945584 10541953024 1112902032 11.960 98 15 30 0 1158641776 43082208 2657536
04:30:09 55545 8192000000 10075514320 10530926592 1135153888 12.170 100 18 36 0 1163046752 23817680 4075184
04:32:05 92362 8192000000 10062776784 10557620224 1120537184 12.032 98 23 44 0 1164828400 39232192 5059024
04:34:37 58473 8192000000 10079093312 10541486080 1111338080 11.945 98 17 34 0 1154179824 39512448 3329296
04:36:40 52002 8192000000 9793221296 10224427008 830506224 9.204 73 31 62 0 919958416 83046992 6405200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 09-01-2023 04:39:14 to 09-01-2023 04:39:30.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 136 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:03, from 09-01-2023 04:39:31 to 09-01-2023 04:39:34.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          217611
MON$OLDEST_ACTIVE               221944
MON$OLDEST_SNAPSHOT             221944
MON$NEXT_TRANSACTION            221944
MON$PAGE_BUFFERS                1000000
MON$SQL_DIALECT                 3
MON$SHUTDOWN_MODE               0
MON$SWEEP_INTERVAL              0
MON$READ_ONLY                   0
MON$FORCED_WRITES               1
MON$RESERVE_SPACE               1
MON$CREATION_DATE               2020-04-30 07:30:08.6030 GMT
MON$PAGES                       2053536
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             777
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2887 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2887 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2887 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:61: 
:62: mscs-test-161.budzdorov.ru    Mon Jan  9 04:37:41 2023
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
:65: 
:66: mscs-test-161.budzdorov.ru    Mon Jan  9 04:37:45 2023
:67:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:68: 
:69: 
:70: mscs-test-161.budzdorov.ru    Mon Jan  9 04:37:45 2023
:71:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:72: 
:73: 
:74: mscs-test-161.budzdorov.ru    Mon Jan  9 04:37:45 2023
:75:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:76: 
:77: 
:78: mscs-test-161.budzdorov.ru    Mon Jan  9 04:37:45 2023
:79:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:80: 
--- end of diff output ---

Final processing ISQL logs in /var/tmp/logs-oltp40 according to config parameter 'remove_isql_logs'

09.01.23 04:39:35. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2023-01-09 04:39:37 No severe PSQL-related problems occured
09.01.23 04:39:39. End of report.