Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.01.2023 07: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 15118484 kB
MemAvailable 30378916 kB
Buffers 0 kB
Cached 16568552 kB
SwapCached 7736 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-02 07:37:21
DTS_END                         2023-01-02 07:37:21.3620
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/38276
TRN_ID                          217929
ATT_ID                          623
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: 8555.96 19609 1026715
customer order: creation 178.53 455 21423
customer order: refuse 14.79 420 1775
order to supplier: creation 46.83 1309 5619
order to supplier: removal 3.43 150001 412
invoice (draft): creation 46.03 3190 5523
invoice (draft): removal 46.01 954 5521
invoice accept: apply 41.49 26358 4979
invoice accept: cancel 41.19 1321 4943
customer reserve: creation 3828.96 251 459475
customer reserve: removal 4011.53 102 481383
realization accept: apply 87.44 294 10493
realization accept: cancel 75.94 177 9113
payment from customer: creation 60.66 198 7279
payment from customer: removal 5.97 137 716
payment to supplier: creation 50.62 217 6074
payment to supplier: removal 3.62 146 434
service: total inventory turnovers 4.12 3893 494
service: total monetary turnovers 7.83 317 939
service: aggregate perf. data 0.97 15321 116
service: refresh index statistics 0.03 187128 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-01-2023 07:38:41 to 02-01-2023 07:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5869
TEST_TIME 2 7416
TEST_TIME 3 7453
TEST_TIME 4 7235
TEST_TIME 5 6957
TEST_TIME 6 6669
TEST_TIME 7 7435
TEST_TIME 8 7575
TEST_TIME 9 7604
TEST_TIME 10 7717
TEST_TIME 11 7513
TEST_TIME 12 7373
TEST_TIME 13 7468
TEST_TIME 14 7566
TEST_TIME 15 7599
TEST_TIME 16 7823
TEST_TIME 17 7739
TEST_TIME 18 8017
TEST_TIME 19 8295
TEST_TIME 20 8275
TEST_TIME 21 8399
TEST_TIME 22 8333
TEST_TIME 23 8469
TEST_TIME 24 8795
TEST_TIME 25 8757
TEST_TIME 26 8829
TEST_TIME 27 9202
TEST_TIME 28 9319
TEST_TIME 29 9447
TEST_TIME 30 10050
TEST_TIME 31 10109
TEST_TIME 32 10984
TEST_TIME 33 10778
TEST_TIME 34 10634
TEST_TIME 35 10551
TEST_TIME 36 10479
TEST_TIME 37 10436
TEST_TIME 38 10566
TEST_TIME 39 10341
TEST_TIME 40 10195
TEST_TIME 41 10122
TEST_TIME 42 10011
TEST_TIME 43 9943
TEST_TIME 44 9918
TEST_TIME 45 9838
TEST_TIME 46 9696
TEST_TIME 47 9546
TEST_TIME 48 9489
TEST_TIME 49 9428
TEST_TIME 50 9393
TEST_TIME 51 9228
TEST_TIME 52 9139
TEST_TIME 53 9133
TEST_TIME 54 9084
TEST_TIME 55 9108
TEST_TIME 56 9045
TEST_TIME 57 8978
TEST_TIME 58 8968
TEST_TIME 59 9032
TEST_TIME 60 8968
TEST_TIME 61 8971
TEST_TIME 62 8942
TEST_TIME 63 9022
TEST_TIME 64 8999
TEST_TIME 65 8995
TEST_TIME 66 8928
TEST_TIME 67 8996
TEST_TIME 68 9115
TEST_TIME 69 9107
TEST_TIME 70 9068
TEST_TIME 71 9101
TEST_TIME 72 9157
TEST_TIME 73 9161
TEST_TIME 74 9057
TEST_TIME 75 9003
TEST_TIME 76 8946
TEST_TIME 77 8982
TEST_TIME 78 8966
TEST_TIME 79 8910
TEST_TIME 80 8886
TEST_TIME 81 8941
TEST_TIME 82 8885
TEST_TIME 83 8851
TEST_TIME 84 8799
TEST_TIME 85 8744
TEST_TIME 86 8712
TEST_TIME 87 8679
TEST_TIME 88 8697
TEST_TIME 89 8728
TEST_TIME 90 8715
TEST_TIME 91 8739
TEST_TIME 92 8764
TEST_TIME 93 8756
TEST_TIME 94 8850
TEST_TIME 95 8843
TEST_TIME 96 8841
TEST_TIME 97 8824
TEST_TIME 98 8816
TEST_TIME 99 8860
TEST_TIME 100 8891
TEST_TIME 101 8923
TEST_TIME 102 8845
TEST_TIME 103 8790
TEST_TIME 104 8795
TEST_TIME 105 8782
TEST_TIME 106 8767
TEST_TIME 107 8753
TEST_TIME 108 8716
TEST_TIME 109 8667
TEST_TIME 110 8626
TEST_TIME 111 8635
TEST_TIME 112 8621
TEST_TIME 113 8590
TEST_TIME 114 8571
TEST_TIME 115 8571
TEST_TIME 116 8613
TEST_TIME 117 8588
TEST_TIME 118 8589
TEST_TIME 119 8625
TEST_TIME 120 8578

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-01-2023 07:38:45 to 02-01-2023 07:38:48.

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 1448065 1448065 0 0.00 0 20130 14 0 0
doc_list_biud 1448065 1448065 0 0.00 0 35039 40 0 0
sp_add_invoice_to_stock 5114 4979 135 2.63 38 191879 26358 0 135
sp_cancel_adding_invoice 4943 4943 0 0.00 6 131622 1321 0 0
sp_cancel_client_order 1775 1775 0 0.00 5 13584 421 0 0
sp_cancel_customer_reserve 481383 481383 0 0.00 1 27792 102 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 3 6337 138 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 4 10695 146 0 0
sp_cancel_supplier_invoice 5530 5521 9 0.16 3 18795 955 0 9
sp_cancel_supplier_order 412 412 0 0.00 12 1311473 150002 0 0
sp_cancel_write_off 9113 9113 0 0.00 2 15041 177 0 0
sp_client_order 21423 21423 0 0.00 6 17406 455 0 0
sp_customer_reserve 459475 459475 0 0.00 0 79369 251 0 0
sp_fill_shopping_cart 32565 32565 0 0.00 1 25393 93 0 0
sp_fill_shopping_cart_clo_res 459462 459462 0 0.00 0 15201 19 0 0
sp_get_clo_for_invoice 4940 4940 0 0.00 0 28314 373 0 0
sp_kill_cost_storno 15206 15206 0 0.00 0 11385 76 0 0
sp_kill_qstorno_ret_qs2qd 977812 977812 0 0.00 0 25540 44 0 0
sp_kill_qty_storno 518579 518579 0 0.00 0 35035 102 0 0
sp_lock_selected_doc 21346 21346 0 0.00 0 8195 8 0 0
sp_make_cost_storno 28785 28785 0 0.00 0 14225 85 0 0
sp_multiply_rows_for_pdistr 28785 28785 0 0.00 0 14199 41 0 0
sp_multiply_rows_for_qdistr 21423 21423 0 0.00 0 14951 155 0 0
sp_pay_from_customer 7279 7279 0 0.00 6 20512 198 0 0
sp_pay_to_supplier 6074 6074 0 0.00 6 20545 218 0 0
sp_payment_common 13353 13353 0 0.00 2 15099 55 0 0
sp_qd_handle_on_cancel_clo 1775 1775 0 0.00 0 13574 331 0 0
sp_qd_handle_on_invoice_upd_sts 9882 9882 0 0.00 0 35032 538 0 0
sp_qd_handle_on_reserve_upd_sts 10493 10493 0 0.00 0 17307 67 0 0
sp_reserve_write_off 10493 10493 0 0.00 8 21964 294 0 0
sp_supplier_invoice 5523 5523 0 0.00 10 81559 3190 0 0
sp_supplier_order 5619 5619 0 0.00 8 31750 1310 0 0
srv_aggregate_perf_data 116 116 0 0.00 2266 220483 15321 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 724 141508 36876 0 0
srv_find_qd_qs_mism 976077 976077 0 0.00 0 20128 11 0 0
srv_make_invnt_saldo 494 494 0 0.00 55 113632 3893 0 0
srv_make_money_saldo 939 939 0 0.00 1 15999 317 0 0
srv_recalc_idx_stat 4 4 0 0.00 91287 298447 187128 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 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 4980 4980 0 0.00 1 25493 945 0 0
t$perf-norm:sp_cancel_adding_invoice 421 421 0 0.00 1 6453 532 0 0
t$perf-norm:sp_cancel_client_order 1775 1775 0 0.00 0 3040 29 0 0
t$perf-norm:sp_cancel_customer_reserve 701 701 0 0.00 0 1340 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 610 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 3000 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 5244 42 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 0 116015 6018 0 0
t$perf-norm:sp_cancel_write_off 1057 1057 0 0.00 0 1626 22 0 0
t$perf-norm:sp_client_order 21423 21423 0 0.00 0 24184 37 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 295 11 0 0
t$perf-norm:sp_pay_from_customer 7279 7279 0 0.00 0 24191 22 0 0
t$perf-norm:sp_pay_to_supplier 6074 6074 0 0.00 0 9641 19 0 0
t$perf-norm:sp_reserve_write_off 10493 10493 0 0.00 0 9669 23 0 0
t$perf-norm:sp_supplier_invoice 5523 5523 0 0.00 0 1825 28 0 0
t$perf-norm:sp_supplier_order 5619 5619 0 0.00 0 8568 34 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 132 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 1372 23 0 0
t$perf-norm:srv_make_invnt_saldo 494 494 0 0.00 0 2330 25 0 0
t$perf-norm:srv_make_money_saldo 939 939 0 0.00 0 1165 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 17 184 86 0 0
v_add_invoice_to_stock 10148 9878 270 2.66 0 6358 26 0 270
v_all_customers 38984 38984 0 0.00 0 84 4 0 0
v_all_suppliers 31282 31282 0 0.00 0 67 4 0 0
v_all_wares 42846 42846 0 0.00 0 75 4 0 0
v_cancel_adding_invoice 842 842 0 0.00 0 522 13 0 0
v_cancel_client_order 3550 3550 0 0.00 0 2353 12 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 5420 15 0 0
v_cancel_customer_reserve 1402 1402 0 0.00 0 2367 13 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 3641 20 0 0
v_cancel_supplier_invoice 814 796 18 2.21 0 1363 27 0 18
v_cancel_supplier_order 824 824 0 0.00 0 2389 20 0 0
v_cancel_write_off 2114 2114 0 0.00 0 1197 23 0 0
v_max_id_clo_ord 5619 5619 0 0.00 0 1267 6 0 0
v_max_id_clo_res 975 975 0 0.00 0 15144 29 0 0
v_max_id_ord_sup 5523 5523 0 0.00 0 5287 7 0 0
v_max_non_paid_invoice 1575 1575 0 0.00 0 785 9 0 0
v_max_non_paid_realizn 3830 3830 0 0.00 0 1419 8 0 0
v_min_id_clo_ord 5619 5619 0 0.00 0 1670 8 0 0
v_min_id_clo_res 975 975 0 0.00 0 6003 34 0 0
v_min_id_ord_sup 5523 5523 0 0.00 0 4036 9 0 0
v_min_non_paid_invoice 6074 6074 0 0.00 0 14173 25 0 0
v_min_non_paid_realizn 7279 7279 0 0.00 0 11805 24 0 0
v_our_firm 10760 10760 0 0.00 0 53 5 0 0
v_reserve_write_off 20986 20986 0 0.00 0 5439 11 0 0
x_lock_depdocs_on_canc_invoice 10066 10066 0 0.00 0 15123 125 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 4680 340 0 0
x_make_qstorno_1000_1200 5619 5619 0 0.00 2 31711 1130 0 0
x_make_qstorno_1200_2000 5523 5523 0 0.00 5 81191 2922 0 0
x_make_qstorno_2100_3300 459475 459475 0 0.00 0 79365 219 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-01-2023 07:38:49 to 02-01-2023 07:38:53.

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
05:07:25 1 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:25 25249 8192000000 9722293264 10023968768 796572000 8.862 100 88 176 1 825374464 7492944 21309520
05:09:57 7642 8192000000 9760934640 10069020672 838215728 9.282 100 89 179 0 870785584 11790512 20779344
05:11:12 23421 8192000000 9805559600 10124107776 867492320 9.575 100 89 178 0 912350256 24045680 20812256
05:12:55 5748 8192000000 9863684432 10199359488 920363792 10.100 100 77 154 1 983849088 46969952 16515344
05:14:04 6421 8192000000 9924286960 10270134272 961833840 10.507 100 83 166 0 1040297456 61285328 17178288
05:15:15 1832 8192000000 9948182736 10302320640 992453552 10.805 100 81 154 2 1071025984 63585136 14987296
05:16:19 2036 8192000000 10016897984 10381950976 1017741648 11.050 100 95 184 1 1135309808 98163184 19404976
05:17:23 847 8192000000 10005978240 10375806976 1042520448 11.289 100 93 176 1 1123917168 65222336 16174384
05:18:25 1150 8192000000 10030299856 10404552704 1060424976 11.461 100 96 184 1 1149926880 70123152 19378752
05:19:29 7692 8192000000 10028956112 10403454976 1071242944 11.564 100 93 171 3 1149653776 61772848 16637984
05:20:38 1253 8192000000 10038102400 10416349184 1079201456 11.640 100 97 182 1 1161509200 64239536 18068208
05:21:40 11726 8192000000 10050423968 10438430720 1082625712 11.672 100 73 146 1 1171353616 73721520 15006384
05:22:53 1109 8192000000 10049299952 10440892416 1095130880 11.791 100 85 162 1 1173277136 60745280 17400976
05:23:55 1026 8192000000 10036062112 10428571648 1097155040 11.811 100 74 137 1 1157517424 46854112 13508272
05:24:57 1635 8192000000 10047231808 10433990656 1099362752 11.832 100 83 153 1 1169363424 55546016 14454656
05:26:00 1177 8192000000 10082209008 10473881600 1103956912 11.875 100 95 185 2 1201609792 78233136 19419744
05:27:02 1236 8192000000 10090619584 10482106368 1104128304 11.877 100 96 187 3 1209967888 85721792 20117792
05:28:04 1089 8192000000 10077414432 10470436864 1107392176 11.908 100 90 159 0 1195554576 72791056 15371344
05:29:06 1095 8192000000 10057862928 10449956864 1092044032 11.762 99 97 187 0 1182809328 71229664 19535632
05:30:08 1302 8192000000 10018272976 10398924800 1065685632 11.511 100 96 186 1 1138646560 53943312 19017616
05:31:10 1166 8192000000 10036859808 10422218752 1074690544 11.597 100 81 153 1 1156841296 66097664 16053088
05:32:12 1269 8192000000 10006124240 10388717568 1051566400 11.376 100 81 149 0 1125602176 59075216 14960560
05:33:14 1026 8192000000 9990111056 10364604416 1047261904 11.334 100 85 151 1 1111975536 48198048 16515584
05:34:16 1006 8192000000 9994634560 10370392064 1040500240 11.269 100 89 171 2 1119264368 59958240 18805888
05:35:18 3663 8192000000 9975931472 10353942528 1025517472 11.125 100 64 122 2 1098729984 60751632 12460880
05:36:23 1931 8192000000 9975878672 10353270784 1035671552 11.223 100 76 147 0 1091940176 40409744 15858880
05:37:25 21227 8192000000 9994785184 10380128256 1037192864 11.238 100 60 114 1 1118217824 69710784 11314176
05:38:53 887 8192000000 10006438528 10379059200 1057558768 11.433 100 92 172 1 1124335440 50189392 16587280
05:39:57 1648 8192000000 10057995936 10440511488 1081212912 11.659 100 88 169 1 1179901488 79731744 18956832
05:41:01 1755 8192000000 10060734496 10448977920 1091925408 11.761 100 87 161 1 1184030096 75259296 16845392
05:42:08 3884 8192000000 10103436480 10492289024 1098910624 11.827 100 74 143 0 1221345136 107558640 14875872
05:43:15 2105 8192000000 10125086128 10518491136 1126978928 12.093 100 84 158 2 1249206288 104572464 17654896
05:44:20 2468 8192000000 10160409952 10559299584 1129527440 12.117 100 88 162 2 1272211280 125656288 17027552
05:45:26 3165 8192000000 10192737872 10585337856 1146372112 12.275 100 86 166 1 1304207136 138747376 19087648
05:46:32 1156 8192000000 10212496592 10618310656 1152808400 12.336 100 96 187 0 1326801968 153482048 20511520
05:47:38 5207 8192000000 10214637728 10634309632 1147109392 12.282 100 68 136 0 1334045392 171447120 15488880
05:48:52 1210 8192000000 10239697520 10648924160 1159382112 12.397 100 86 160 1 1353563584 176707792 17473680
05:49:59 1511 8192000000 10312764816 10713980928 1173370432 12.528 100 79 150 1 1422508208 232403056 16734720
05:51:04 1282 8192000000 10334585760 10736439296 1174664800 12.540 100 87 173 0 1442886256 248282560 19938896
05:52:08 1337 8192000000 10358091328 10779594752 1185607536 12.642 100 89 173 2 1468887024 263057216 20222272
05:53:14 2543 8192000000 10379931232 10805510144 1184685472 12.634 100 87 172 1 1494143552 288350768 21107312
05:54:19 968 8192000000 10404665504 10814038016 1180325728 12.593 100 91 177 1 1513067056 313165152 19576176
05:55:25 1034 8192000000 10413207408 10852093952 1177457344 12.566 100 91 170 3 1520461760 323439840 19564576
05:56:29 803 8192000000 10399904528 10831196160 1185855904 12.645 100 96 184 1 1505230144 298377952 20996288
05:57:34 883 8192000000 10448147888 10853617664 1183804720 12.626 100 98 190 1 1551241056 345767504 21668832
05:58:37 1405 8192000000 10466291424 10892546048 1189803696 12.682 100 86 170 1 1574932016 363947936 21180384
05:59:41 711 8192000000 10478535856 10919165952 1190595744 12.689 100 96 192 1 1584423376 369454224 24373408
06:00:45 1662 8192000000 10472956928 10927906816 1179520560 12.586 100 97 194 0 1572514272 370126224 22867488
06:01:49 2342 8192000000 10509621296 10967171072 1183229744 12.620 100 73 141 0 1611785184 411047024 17508416
06:02:59 4955 8192000000 10460610080 10878312448 1137469232 12.192 98 60 119 1 1572439280 420524832 14445216
06:04:07 24061 8192000000 10446259488 10921947136 1160656816 12.409 100 39 77 0 1587166496 416481584 10028096
06:05:37 9546 8192000000 10453994144 10920116224 1135808816 12.176 99 39 78 0 1545801024 400139360 9852848
06:06:47 24674 8192000000 10382268272 10886336512 1133818240 12.157 100 37 72 0 1525949520 383552944 8578336
06:08:26 25279 8192000000 10222421936 10729787392 1132935728 12.149 100 29 54 0 1406788480 267062224 6790528
06:09:52 33333 8192000000 10141858160 10564272128 1136894128 12.186 99 46 85 4 1234332848 88300496 9138224
06:11:28 43509 8192000000 10153855472 10585899008 1125207152 12.076 98 41 80 0 1235836432 101778192 8851088
06:13:21 106290 8192000000 10147582800 10682966016 1134354912 12.162 100 15 30 0 1295817984 157629920 3833152
06:16:09 63801 8192000000 10098834640 10543099904 1119126048 12.019 99 24 47 0 1185652128 60970160 5555920
06:18:14 69683 8192000000 10111175008 10554957824 1115947744 11.989 98 31 61 1 1195042800 73250128 5844928
06:20:25 40591 8192000000 10076709984 10557853696 1136041712 12.178 100 21 43 0 1210578512 68941648 5595152
06:22:07 102986 8192000000 10056488000 10517221376 1130289376 12.124 99 14 28 0 1176567168 43426368 2851424
06:24:50 29373 8192000000 10098359104 10551140352 1145708656 12.269 100 54 95 2 1179525488 24555632 9261200
06:26:29 104875 8192000000 10094408576 10587357184 1140807904 12.223 99 15 30 0 1205939696 62230800 2900992
06:29:17 7859 8192000000 10100329440 10551418880 1149075680 12.301 100 37 67 1 1210871504 54645152 7150672
06:30:27 23980 8192000000 10114026496 10572447744 1136692832 12.184 99 37 74 0 1203439712 58908400 7838480
06:31:57 141507 8192000000 10144542416 10606977024 1141085008 12.226 99 24 48 0 1251312416 104682384 5545024
06:35:19 69003 8192000000 10079310736 10646867968 1134880752 12.167 99 6 12 1 1226993152 90643264 1469136
06:37:28 59071 8192000000 10086009584 10527248384 1124467648 12.069 98 23 44 0 1185961024 57139360 4354016
06:39:28 83407 8192000000 10079860000 10539974656 1125926048 12.083 98 14 27 0 1185238544 56800192 2512304
06:41:53 59368 8192000000 10099229488 10559287296 1128415248 12.106 98 26 52 1 1168179360 33873888 5890224
06:43:53 52469 8192000000 10132089376 10628755456 1134527472 12.164 99 31 62 0 1248733088 107525472 6680144
06:45:48 91411 8192000000 10090238288 10514423808 1103787168 11.874 96 28 57 0 1160838944 51121232 5930544
06:48:20 41256 8192000000 10095317232 10578206720 1109212000 11.925 98 36 72 0 1193443216 76702720 7528496
06:50:02 93512 8192000000 10055554704 10497462272 1060886208 11.465 93 21 42 0 1104361024 39187456 4287360
06:52:58 114223 8192000000 10061425056 10544181248 1097772432 11.817 97 30 58 0 1170273360 66349184 6151744
06:56:00 5940 8192000000 10037996320 10490978304 1106849984 11.903 99 32 59 0 1142660240 30148656 5661600
06:57:10 59885 8192000000 10069110848 10538721280 1105147648 11.886 98 21 42 0 1188816480 79309392 4359440
06:59:11 35846 8192000000 10029990208 10483224576 1109452416 11.927 99 9 19 0 1137280640 25963600 1864624
07:00:48 48581 8192000000 10037278704 10505838592 1112211616 11.953 100 15 26 0 1148779296 34105664 2462016
07:02:59 36715 8192000000 10082208464 10530709504 1096985232 11.809 98 29 58 0 1151975984 48836320 6154432
07:04:44 47509 8192000000 10095481392 10552635392 1095849200 11.798 98 17 34 0 1178062320 78724976 3488144
07:06:36 8737 8192000000 10055218848 10482900992 1098961344 11.828 98 50 93 0 1136672128 27368928 10341856
07:07:51 57003 8192000000 10126736224 10590715904 1127779184 12.100 99 28 55 0 1226915808 93555968 5580656
07:10:34 80519 8192000000 10060983168 10526810112 1081010656 11.657 95 26 52 0 1174435744 87885408 5539680
07:12:55 24697 8192000000 10096350592 10517905408 1131111168 12.132 100 47 92 0 1202730256 62400256 9218832
07:14:33 5968 8192000000 10111176768 10523217920 1122907904 12.054 100 71 142 0 1201534384 63937632 14688848
07:15:49 9484 8192000000 10129434384 10547560448 1117652368 12.005 99 57 112 1 1201274080 72836800 10784912
07:17:04 110448 8192000000 10074662064 10553176064 1075855744 11.608 95 14 28 0 1168175056 88980080 3339232
07:19:55 76569 8192000000 10091197728 10537447424 1110359744 11.936 99 24 49 0 1176715024 61406368 4948912
07:22:35 32049 8192000000 10098499184 10547032064 1135105088 12.169 100 46 91 0 1213079680 69021168 8953424
07:24:07 67775 8192000000 10046035344 10504843264 1124318256 12.068 100 6 12 0 1151252144 25707360 1226528
07:26:19 80068 8192000000 10055436256 10500120576 1119583536 12.023 99 22 44 0 1158734624 34932496 4218592
07:28:40 80186 8192000000 10047702304 10515660800 1104884400 11.884 98 4 6 0 1139905840 34505168 516272
07:31:02 42878 8192000000 10105748784 10544242688 1129923488 12.121 99 24 48 0 1186834704 51387968 5523248
07:32:49 54390 8192000000 10142490288 10616512512 1130913232 12.130 98 31 62 0 1255247808 117288000 7046576
07:34:44 26238 8192000000 10176853680 10622484480 1131085168 12.132 99 37 70 1 1265810816 127138976 7586672
07:36:10 45244 8192000000 10153816144 10611142656 1129482800 12.116 99 19 38 0 1255342368 121450400 4409168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 02-01-2023 07:38:55 to 02-01-2023 07:39:11.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 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:02, from 02-01-2023 07:39:12 to 02-01-2023 07:39:14.

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          216427
MON$OLDEST_ACTIVE               226858
MON$OLDEST_SNAPSHOT             226858
MON$NEXT_TRANSACTION            226858
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                       2058720
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             785
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2885 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2885 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2885 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2885 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 ---
:834: 
:835: mscs-test-161.budzdorov.ru    Mon Jan  2 07:37:32 2023
:836:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:837: 
:838: 
:839: mscs-test-161.budzdorov.ru    Mon Jan  2 07:37:32 2023
:840:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:841: 
--- end of diff output ---

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

02.01.23 07:39:15. 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-02 07:39:18 No severe PSQL-related problems occured
02.01.23 07:39:19. End of report.