Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.01.2023 13: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 14837256 kB
MemAvailable 30395356 kB
Buffers 0 kB
Cached 16889892 kB
SwapCached 4428 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-08 13:37:23
DTS_END                         2023-01-08 13:37:23.4320
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/50072
TRN_ID                          222869
ATT_ID                          610
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: 9218.72 31003 1106246
customer order: creation 169.45 458 20334
customer order: refuse 14.82 400 1778
order to supplier: creation 45.56 1310 5467
order to supplier: removal 3.54 162993 425
invoice (draft): creation 45.95 3021 5514
invoice (draft): removal 52.98 921 6357
invoice accept: apply 41.62 25192 4994
invoice accept: cancel 47.94 1148 5753
customer reserve: creation 3832.95 241 459954
customer reserve: removal 4666.42 97 559970
realization accept: apply 83.28 269 9993
realization accept: cancel 85.74 163 10289
payment from customer: creation 58.41 178 7009
payment from customer: removal 5.53 131 664
payment to supplier: creation 48.69 199 5843
payment to supplier: removal 3.54 141 425
service: total inventory turnovers 3.90 3953 468
service: total monetary turnovers 7.41 353 889
service: aggregate perf. data 0.97 17920 116
service: refresh index statistics 0.03 400976 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-01-2023 13:38:58 to 08-01-2023 13:39:00.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5024
TEST_TIME 2 5161
TEST_TIME 3 5542
TEST_TIME 4 5879
TEST_TIME 5 5724
TEST_TIME 6 5709
TEST_TIME 7 6118
TEST_TIME 8 6625
TEST_TIME 9 6923
TEST_TIME 10 6752
TEST_TIME 11 6789
TEST_TIME 12 6874
TEST_TIME 13 7007
TEST_TIME 14 7014
TEST_TIME 15 7129
TEST_TIME 16 7159
TEST_TIME 17 7258
TEST_TIME 18 7250
TEST_TIME 19 7566
TEST_TIME 20 7694
TEST_TIME 21 7789
TEST_TIME 22 7859
TEST_TIME 23 8052
TEST_TIME 24 8480
TEST_TIME 25 8493
TEST_TIME 26 8733
TEST_TIME 27 8875
TEST_TIME 28 9257
TEST_TIME 29 9346
TEST_TIME 30 9310
TEST_TIME 31 9820
TEST_TIME 32 10143
TEST_TIME 33 10559
TEST_TIME 34 10364
TEST_TIME 35 10986
TEST_TIME 36 11361
TEST_TIME 37 11380
TEST_TIME 38 11180
TEST_TIME 39 11085
TEST_TIME 40 11011
TEST_TIME 41 10830
TEST_TIME 42 10973
TEST_TIME 43 11004
TEST_TIME 44 11036
TEST_TIME 45 10872
TEST_TIME 46 10752
TEST_TIME 47 10554
TEST_TIME 48 10504
TEST_TIME 49 10320
TEST_TIME 50 10303
TEST_TIME 51 10225
TEST_TIME 52 10137
TEST_TIME 53 10007
TEST_TIME 54 9971
TEST_TIME 55 9879
TEST_TIME 56 9985
TEST_TIME 57 9882
TEST_TIME 58 10118
TEST_TIME 59 9991
TEST_TIME 60 9984
TEST_TIME 61 9981
TEST_TIME 62 9926
TEST_TIME 63 9967
TEST_TIME 64 9946
TEST_TIME 65 9871
TEST_TIME 66 9893
TEST_TIME 67 9927
TEST_TIME 68 9908
TEST_TIME 69 9857
TEST_TIME 70 9922
TEST_TIME 71 9981
TEST_TIME 72 9939
TEST_TIME 73 9845
TEST_TIME 74 9789
TEST_TIME 75 9739
TEST_TIME 76 9733
TEST_TIME 77 9670
TEST_TIME 78 9613
TEST_TIME 79 9567
TEST_TIME 80 9566
TEST_TIME 81 9489
TEST_TIME 82 9424
TEST_TIME 83 9401
TEST_TIME 84 9402
TEST_TIME 85 9442
TEST_TIME 86 9399
TEST_TIME 87 9365
TEST_TIME 88 9379
TEST_TIME 89 9381
TEST_TIME 90 9459
TEST_TIME 91 9512
TEST_TIME 92 9499
TEST_TIME 93 9480
TEST_TIME 94 9565
TEST_TIME 95 9551
TEST_TIME 96 9626
TEST_TIME 97 9537
TEST_TIME 98 9477
TEST_TIME 99 9437
TEST_TIME 100 9392
TEST_TIME 101 9390
TEST_TIME 102 9324
TEST_TIME 103 9322
TEST_TIME 104 9316
TEST_TIME 105 9282
TEST_TIME 106 9293
TEST_TIME 107 9291
TEST_TIME 108 9284
TEST_TIME 109 9296
TEST_TIME 110 9268
TEST_TIME 111 9237
TEST_TIME 112 9265
TEST_TIME 113 9327
TEST_TIME 114 9314
TEST_TIME 115 9354
TEST_TIME 116 9371
TEST_TIME 117 9346
TEST_TIME 118 9288
TEST_TIME 119 9296
TEST_TIME 120 9237

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-01-2023 13:39:01 to 08-01-2023 13:39:05.

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 1530163 1530163 0 0.00 0 28565 13 0 0
doc_list_biud 1530161 1530161 0 0.00 0 35594 41 0 0
sp_add_invoice_to_stock 5161 4994 167 3.23 36 196120 25193 0 167
sp_cancel_adding_invoice 5753 5753 0 0.00 5 65069 1149 0 0
sp_cancel_client_order 1778 1778 0 0.00 6 8153 400 0 0
sp_cancel_customer_reserve 559970 559970 0 0.00 1 35597 98 0 0
sp_cancel_pay_from_customer 664 664 0 0.00 3 3165 131 0 0
sp_cancel_pay_to_supplier 425 425 0 0.00 4 6493 141 0 0
sp_cancel_supplier_invoice 6371 6357 14 0.21 2 22108 921 0 14
sp_cancel_supplier_order 425 425 0 0.00 7 1485350 162993 0 0
sp_cancel_write_off 10289 10289 0 0.00 2 17402 163 0 0
sp_client_order 20334 20334 0 0.00 5 37075 459 0 0
sp_customer_reserve 459954 459954 0 0.00 0 73465 242 0 0
sp_fill_shopping_cart 31312 31312 0 0.00 1 11422 82 0 0
sp_fill_shopping_cart_clo_res 459941 459941 0 0.00 0 17510 18 0 0
sp_get_clo_for_invoice 4967 4967 0 0.00 0 25951 284 0 0
sp_kill_cost_storno 17131 17131 0 0.00 0 11192 68 0 0
sp_kill_qstorno_ret_qs2qd 1137011 1137011 0 0.00 0 35587 42 0 0
sp_kill_qty_storno 599531 599531 0 0.00 0 35593 97 0 0
sp_lock_selected_doc 20756 20756 0 0.00 0 9400 8 0 0
sp_make_cost_storno 27811 27811 0 0.00 0 24125 82 0 0
sp_multiply_rows_for_pdistr 27811 27811 0 0.00 0 10779 38 0 0
sp_multiply_rows_for_qdistr 20334 20334 0 0.00 0 18302 182 0 0
sp_pay_from_customer 7009 7009 0 0.00 5 13551 178 0 0
sp_pay_to_supplier 5843 5843 0 0.00 6 17394 199 0 0
sp_payment_common 12852 12852 0 0.00 2 10889 47 0 0
sp_qd_handle_on_cancel_clo 1778 1778 0 0.00 0 8121 323 0 0
sp_qd_handle_on_invoice_upd_sts 10719 10719 0 0.00 0 34445 513 0 0
sp_qd_handle_on_reserve_upd_sts 9993 9993 0 0.00 0 7840 55 0 0
sp_reserve_write_off 9993 9993 0 0.00 8 24715 269 0 0
sp_supplier_invoice 5514 5514 0 0.00 10 91559 3022 0 0
sp_supplier_order 5467 5467 0 0.00 7 43606 1311 0 0
srv_aggregate_perf_data 116 116 0 0.00 2681 198123 17920 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 688 222193 36779 0 0
srv_find_qd_qs_mism 1057880 1057880 0 0.00 0 28564 10 0 0
srv_make_invnt_saldo 468 468 0 0.00 49 65878 3953 0 0
srv_make_money_saldo 889 889 0 0.00 1 8936 354 0 0
srv_recalc_idx_stat 4 4 0 0.00 318000 473349 400976 0 0
t$perf-abend:sp_add_invoice_to_stock 167 167 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 14 14 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4995 4995 0 0.00 0 15840 915 0 0
t$perf-norm:sp_cancel_adding_invoice 427 427 0 0.00 0 6767 477 0 0
t$perf-norm:sp_cancel_client_order 1778 1778 0 0.00 0 4366 28 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 2205 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 664 664 0 0.00 0 2783 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 425 425 0 0.00 0 3879 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 393 393 0 0.00 0 958 34 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 90129 6756 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 2150 22 0 0
t$perf-norm:sp_client_order 20334 20334 0 0.00 0 15678 35 0 0
t$perf-norm:sp_customer_reserve 973 973 0 0.00 0 3713 18 0 0
t$perf-norm:sp_pay_from_customer 7009 7009 0 0.00 0 5998 18 0 0
t$perf-norm:sp_pay_to_supplier 5843 5843 0 0.00 0 5874 20 0 0
t$perf-norm:sp_reserve_write_off 9993 9993 0 0.00 0 12732 22 0 0
t$perf-norm:sp_supplier_invoice 5514 5514 0 0.00 0 5064 27 0 0
t$perf-norm:sp_supplier_order 5467 5467 0 0.00 0 2200 29 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 1830 24 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 877 22 0 0
t$perf-norm:srv_make_invnt_saldo 468 468 0 0.00 0 1382 18 0 0
t$perf-norm:srv_make_money_saldo 889 889 0 0.00 0 2921 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 209 74 0 0
v_add_invoice_to_stock 10266 9932 334 3.25 0 5315 26 0 334
v_all_customers 37315 37315 0 0.00 0 61 4 0 0
v_all_suppliers 30636 30636 0 0.00 0 74 4 0 0
v_all_wares 40668 40668 0 0.00 0 136 4 0 0
v_cancel_adding_invoice 854 854 0 0.00 0 3117 17 0 0
v_cancel_client_order 3556 3556 0 0.00 0 4156 14 0 0
v_cancel_customer_prepayment 1328 1328 0 0.00 0 3052 19 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 489 10 0 0
v_cancel_payment_to_supplier 850 850 0 0.00 0 591 11 0 0
v_cancel_supplier_invoice 814 786 28 3.43 0 4304 30 0 28
v_cancel_supplier_order 850 850 0 0.00 0 732 12 0 0
v_cancel_write_off 1974 1974 0 0.00 0 3088 26 0 0
v_max_id_clo_ord 5466 5466 0 0.00 0 3645 6 0 0
v_max_id_clo_res 973 973 0 0.00 0 2986 19 0 0
v_max_id_ord_sup 5512 5512 0 0.00 0 7259 10 0 0
v_max_non_paid_invoice 1503 1503 0 0.00 0 633 9 0 0
v_max_non_paid_realizn 3612 3612 0 0.00 0 6665 10 0 0
v_min_id_clo_ord 5466 5466 0 0.00 0 4360 9 0 0
v_min_id_clo_res 973 973 0 0.00 0 1543 10 0 0
v_min_id_ord_sup 5512 5512 0 0.00 0 6876 10 0 0
v_min_non_paid_invoice 5843 5843 0 0.00 0 5557 17 0 0
v_min_non_paid_realizn 7008 7008 0 0.00 0 10882 21 0 0
v_our_firm 10144 10144 0 0.00 0 53 4 0 0
v_reserve_write_off 19986 19986 0 0.00 0 4184 11 0 0
x_lock_depdocs_on_canc_invoice 11717 11717 0 0.00 0 13079 113 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 10406 409 0 0
x_make_qstorno_1000_1200 5467 5467 0 0.00 2 43271 1147 0 0
x_make_qstorno_1200_2000 5514 5514 0 0.00 6 91506 2798 0 0
x_make_qstorno_2100_3300 459954 459954 0 0.00 0 73464 211 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-01-2023 13:39:06 to 08-01-2023 13:39:10.

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
11:07:27 7 8192000000 8809329088 8824545280 12944144 0.157 6 2 3 0 13090384 84880 61360
11:08:28 14442 8192000000 9715018448 10011688960 799085168 8.887 100 87 174 0 827888208 7841296 20961744
11:09:46 17073 8192000000 9756569552 10063216640 828189632 9.181 100 90 180 0 861505392 11814128 21501632
11:11:06 10952 8192000000 9782413856 10098180096 856488656 9.465 100 86 172 0 894974704 18469312 20016736
11:12:24 11891 8192000000 9845994272 10171469824 893664064 9.835 100 75 149 0 950666624 40664960 16337600
11:13:41 3564 8192000000 9897120816 10233679872 937995808 10.273 100 88 168 1 1013439840 57594768 17849264
11:14:51 2600 8192000000 9955755904 10307379200 982467008 10.708 100 92 180 2 1070493904 69790032 18236864
11:15:55 1351 8192000000 9990899120 10350899200 1013906432 11.013 100 93 173 1 1110983360 79600816 17476112
11:16:58 982 8192000000 10025139360 10392027136 1036218400 11.228 100 93 184 1 1150542336 95903920 18420016
11:18:01 1052 8192000000 10034353936 10402754560 1050258080 11.363 100 95 185 2 1157263168 89379008 17626080
11:19:04 983 8192000000 10025184816 10400354304 1064126224 11.496 100 93 176 0 1151834080 70338640 17369216
11:20:08 1180 8192000000 10029863088 10411585536 1074608160 11.596 100 97 186 3 1150234176 56979968 18646048
11:21:11 1133 8192000000 10035951616 10416582656 1080632944 11.654 100 95 183 0 1159547744 61630720 17284080
11:22:13 824 8192000000 10050204128 10434039808 1089031472 11.733 100 96 191 4 1176322384 68937104 18353808
11:23:17 5601 8192000000 10046568160 10434842624 1085612224 11.701 100 72 136 1 1168463504 69001216 13850064
11:24:24 9402 8192000000 10037088528 10435055616 1089494816 11.738 100 65 120 0 1168624496 67017312 12112368
11:25:35 2304 8192000000 10066770080 10450030592 1082510160 11.671 99 86 160 0 1183909136 86437856 14961120
11:26:38 3848 8192000000 10062474816 10446446592 1092088208 11.763 100 91 172 2 1180398640 72226432 16084000
11:27:43 1279 8192000000 10069642272 10459906048 1097705888 11.816 100 98 193 3 1197864880 80922544 19236448
11:28:46 1144 8192000000 10035693408 10420080640 1092529520 11.767 100 98 178 2 1155843936 43810048 19504368
11:29:48 1356 8192000000 10076737232 10461560832 1089136496 11.734 100 97 189 0 1198742608 90648336 18957776
11:30:50 1027 8192000000 10025190352 10409816064 1072094144 11.572 99 88 161 1 1148460160 58828336 17537680
11:31:52 999 8192000000 9979619808 10351665152 1034635136 11.213 99 94 178 0 1102691840 50751376 17305328
11:32:55 1653 8192000000 9977623776 10353778688 1028938464 11.158 99 91 170 3 1099636000 53370128 17327408
11:33:58 1137 8192000000 9991548624 10365935616 1030890112 11.177 100 82 151 1 1108261824 61280320 16091392
11:35:01 1324 8192000000 9980432864 10358325248 1040505984 11.270 100 81 152 3 1107634672 51445328 15683360
11:36:03 1373 8192000000 9990749504 10360520704 1028186816 11.151 99 96 183 2 1116101856 68805504 19109536
11:37:05 1538 8192000000 9995780192 10374483968 1043987728 11.303 100 86 156 3 1116878192 55190368 17700096
11:38:13 12884 8192000000 9965898416 10342424576 1045350544 11.316 99 58 106 1 1087694288 31862080 10481664
11:39:41 1161 8192000000 10052982384 10433703936 1077640096 11.625 100 97 186 2 1177697648 79078864 20978688
11:40:46 2498 8192000000 10074425712 10461368320 1082734784 11.674 100 79 148 2 1195983744 97628448 15620512
11:41:50 1228 8192000000 10085756592 10468069376 1087533872 11.719 100 92 167 0 1201962848 97847792 16581184
11:42:54 2056 8192000000 10100908352 10483757056 1101580352 11.853 100 94 179 0 1220950544 100222992 19147200
11:44:00 2478 8192000000 10125091440 10516783104 1110497104 11.937 100 77 138 0 1241193792 115983840 14712848
11:45:04 1065 8192000000 10160895904 10567098368 1127766784 12.100 100 97 182 2 1281991312 133212640 21011888
11:46:10 1967 8192000000 10184079872 10590482432 1142121648 12.235 100 81 149 0 1295731616 137131680 16478288
11:47:15 1137 8192000000 10220742640 10613850112 1164338640 12.444 100 93 181 3 1331438512 147224064 19875808
11:48:18 1463 8192000000 10225960768 10639454208 1171449056 12.510 100 92 167 0 1343787824 152399696 19939072
11:49:22 682 8192000000 10274436784 10670600192 1177968944 12.571 100 97 185 2 1384539888 186189312 20381632
11:50:26 1185 8192000000 10319037392 10724331520 1182303536 12.612 100 95 182 2 1430124032 227598064 20222432
11:51:30 7819 8192000000 10325229760 10740387840 1191315472 12.696 100 63 116 0 1434947008 229897280 13734256
11:52:45 2801 8192000000 10374439696 10799144960 1192751792 12.709 100 78 155 1 1483580224 272966928 17861504
11:53:50 1220 8192000000 10431629120 10843336704 1205994560 12.832 100 93 174 2 1541522256 315332752 20194944
11:54:54 1213 8192000000 10453426160 10883600384 1202533168 12.800 100 96 186 0 1563234576 338821392 21880016
11:55:57 887 8192000000 10485632064 10921246720 1202171936 12.796 100 87 174 0 1592005472 369599184 20234352
11:57:02 803 8192000000 10489699296 10911936512 1203320976 12.807 100 96 184 1 1597390896 372995120 21074800
11:58:05 1459 8192000000 10503208752 10927865856 1196276000 12.742 100 95 185 1 1607518544 389621152 21621392
11:59:11 835 8192000000 10537521776 10957459456 1188102384 12.666 100 95 187 0 1644332224 434404480 21825360
12:00:13 1187 8192000000 10533895184 10968543232 1201148192 12.787 100 96 189 0 1641059632 416999568 22911872
12:01:17 1422 8192000000 10517852960 10990862336 1192020784 12.702 100 93 185 1 1622884720 408147536 22716400
12:02:21 1962 8192000000 10522371504 10939473920 1185368240 12.640 99 70 136 2 1625786528 424323648 16094640
12:03:32 7785 8192000000 10508583904 10948141056 1175726752 12.550 100 60 118 0 1618555216 427951584 14876880
12:04:45 4476 8192000000 10525983200 10956492800 1177026976 12.562 100 62 122 0 1630549488 438022480 15500032
12:05:51 30756 8192000000 10459709968 10942521344 1150571712 12.315 98 34 66 0 1569605024 410243264 8790048
12:07:23 23309 8192000000 10475299984 10963427328 1161665936 12.419 100 31 63 0 1598182224 428065536 8450752
12:08:55 17543 8192000000 10376930432 10858303488 1148123152 12.292 100 37 74 1 1489588400 332219664 9245584
12:10:13 21666 8192000000 10266191168 10731945984 1143909888 12.252 99 36 72 0 1382427072 229952400 8564784
12:11:35 28323 8192000000 10256984000 10731413504 1121061104 12.037 98 34 66 1 1359034080 230127152 7845824
12:13:10 54512 8192000000 10105080800 10680242176 1132225664 12.142 100 15 28 1 1288267264 152504112 3537488
12:15:05 47710 8192000000 10053277504 10479165440 1129149728 12.113 100 47 80 2 1150072496 11607056 9315712
12:16:53 95182 8192000000 10153539632 10613092352 1127839040 12.101 99 18 34 0 1173472448 42566160 3067248
12:19:33 222193 8192000000 10101508272 10555854848 1073054240 11.581 93 11 22 1 1126605008 51044992 2505776
12:24:21 64433 8192000000 10076288704 10532732928 1124182656 12.066 98 32 60 1 1150684720 20097664 6404400
12:26:27 96717 8192000000 10112211056 10558263296 1129400240 12.116 98 54 104 0 1190591168 51181360 10009568
12:29:06 47156 8192000000 10146371280 10595270656 1127085504 12.094 98 22 41 0 1219739696 88193312 4460880
12:31:05 10214 8192000000 10222024256 10648547328 1145389856 12.266 99 64 122 0 1316863680 158023920 13449904
12:32:17 82239 8192000000 10192858736 10718064640 1132856576 12.148 99 24 49 0 1312289104 173694192 5738336
12:35:58 67269 8192000000 10105207968 10551996416 1127442384 12.097 99 15 25 0 1166320512 36094544 2783584
12:39:17 19326 8192000000 10081729680 10529947648 1141887872 12.233 100 39 76 0 1178024128 28635632 7500624
12:40:39 59046 8192000000 10078392736 10543513600 1116623408 11.995 98 16 32 0 1172644144 52907152 3113584
12:42:51 34495 8192000000 10094308880 10580029440 1140077584 12.216 100 23 46 0 1228433616 83881968 4474064
12:44:26 31402 8192000000 10124767152 10572427264 1138927712 12.205 100 27 54 0 1200376416 56203760 5244944
12:46:05 47075 8192000000 10170232816 10629033984 1114080960 11.971 97 26 52 0 1229047584 109428352 5538272
12:48:01 72213 8192000000 10090177328 10534899712 1102280464 11.859 97 32 60 1 1177143168 69325280 5537424
12:50:13 117540 8192000000 10074483168 10525171712 1095303344 11.793 97 29 56 0 1168301472 67350576 5647552
12:53:41 48674 8192000000 10060406304 10521079808 1131108032 12.132 100 11 22 0 1164775712 31624304 2043376
12:55:37 42479 8192000000 10085658320 10536812544 1134109920 12.160 99 26 49 0 1176470560 37422608 4938032
12:57:41 67314 8192000000 10072900512 10538631168 1116068816 11.990 98 12 18 0 1157872576 39653664 2150096
12:59:50 58121 8192000000 10078006896 10565189632 1129920144 12.121 100 12 24 0 1178856864 46538496 2398224
13:01:49 77384 8192000000 10066889808 10589130752 1124292384 12.068 99 15 30 0 1187128576 59373904 3462288
13:04:07 10522 8192000000 10118797680 10561540096 1133739936 12.157 99 41 78 1 1203758720 61774656 8244128
13:05:19 64534 8192000000 10150582688 10597064704 1143034592 12.244 100 19 38 0 1242591280 95392560 4164128
13:07:49 47099 8192000000 10118651888 10587033600 1146581600 12.277 100 25 50 0 1232184816 79976944 5626272
13:09:38 17234 8192000000 10079447008 10509139968 1116729264 11.996 98 43 84 1 1157684176 32498896 8456016
13:11:08 69116 8192000000 10109500736 10624139264 1106132544 11.896 97 16 32 0 1202044768 92506144 3406080
13:13:20 109461 8192000000 10041201968 10484649984 1100511856 11.842 97 16 32 0 1146207376 42461200 3234320
13:16:31 43807 8192000000 10085041168 10532597760 1102692944 11.863 98 35 70 0 1176763184 66942608 7127632
13:18:16 74391 8192000000 10094620352 10561196032 1140667344 12.222 100 22 44 1 1225068560 79789760 4611456
13:20:34 64820 8192000000 10070729264 10559905792 1116240112 11.991 99 12 24 0 1170801616 51947216 2614288
13:22:39 49735 8192000000 10070540512 10522673152 1062679536 11.482 94 27 54 0 1125310336 57142352 5488448
13:24:36 32417 8192000000 10078447856 10542399488 1117025472 11.999 99 29 58 0 1180659872 57187376 6447024
13:26:09 28153 8192000000 10101840224 10553417728 1110805968 11.940 98 30 60 0 1190362176 72925680 6630528
13:27:38 70857 8192000000 10180492048 10620338176 1124368992 12.068 98 26 52 1 1228308016 97810000 6129024
13:29:49 47897 8192000000 10156764736 10610888704 1139983712 12.215 100 32 65 0 1277375904 130450368 6941824
13:31:56 31707 8192000000 10158133680 10593751040 1114942672 11.979 98 40 74 0 1234930160 112262832 7724656
13:33:30 33776 8192000000 10092149856 10538893312 1121729376 12.043 99 47 91 2 1177631376 47146048 8755952
13:35:06 93968 8192000000 10049984768 10541252608 1122172176 12.048 100 21 42 0 1208897920 82017216 4708528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 08-01-2023 13:39:12 to 08-01-2023 13:39:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 167 v_add_invoice_to_stock 335544517
except 14 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 08-01-2023 13:39:29 to 08-01-2023 13:39:31.

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          222709
MON$OLDEST_ACTIVE               223678
MON$OLDEST_SNAPSHOT             223678
MON$NEXT_TRANSACTION            223678
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                       2086368
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             782
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 ---
:32: 
:33: mscs-test-161.budzdorov.ru    Sun Jan  8 13:37:29 2023
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Sun Jan  8 13:37:29 2023
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Sun Jan  8 13:37:29 2023
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Sun Jan  8 13:37:29 2023
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Sun Jan  8 13:37:29 2023
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Sun Jan  8 13:37:29 2023
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:55: 
--- end of diff output ---

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

08.01.23 13:39:32. 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-08 13:39:35 No severe PSQL-related problems occured
08.01.23 13:39:36. End of report.