Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.12.2022 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 14967016 kB
MemAvailable 30108456 kB
Buffers 0 kB
Cached 16734288 kB
SwapCached 3376 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 2022-12-27 07:37:21
DTS_END                         2022-12-27 07:37:21.9620
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58908
TRN_ID                          223903
ATT_ID                          617
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: 8836.56 24774 1060387
customer order: creation 172.65 461 20718
customer order: refuse 14.76 395 1771
order to supplier: creation 46.43 1307 5572
order to supplier: removal 3.25 159785 390
invoice (draft): creation 46.56 3249 5587
invoice (draft): removal 47.58 999 5710
invoice accept: apply 42.16 26109 5059
invoice accept: cancel 42.94 1295 5153
customer reserve: creation 3899.73 251 467967
customer reserve: removal 4224.36 99 506923
realization accept: apply 84.08 264 10090
realization accept: cancel 79.18 162 9501
payment from customer: creation 60.41 169 7249
payment from customer: removal 5.60 146 672
payment to supplier: creation 50.33 195 6039
payment to supplier: removal 3.66 131 439
service: total inventory turnovers 4.37 3345 524
service: total monetary turnovers 7.52 316 902
service: aggregate perf. data 0.98 15006 117
service: refresh index statistics 0.03 281797 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-12-2022 07:38:47 to 27-12-2022 07:38:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7759
TEST_TIME 2 5408
TEST_TIME 3 5159
TEST_TIME 4 5394
TEST_TIME 5 5772
TEST_TIME 6 5732
TEST_TIME 7 5735
TEST_TIME 8 6171
TEST_TIME 9 6286
TEST_TIME 10 6104
TEST_TIME 11 6408
TEST_TIME 12 6658
TEST_TIME 13 6732
TEST_TIME 14 6899
TEST_TIME 15 6907
TEST_TIME 16 7428
TEST_TIME 17 7548
TEST_TIME 18 7730
TEST_TIME 19 7996
TEST_TIME 20 8057
TEST_TIME 21 8046
TEST_TIME 22 8239
TEST_TIME 23 8466
TEST_TIME 24 8642
TEST_TIME 25 8786
TEST_TIME 26 8967
TEST_TIME 27 9342
TEST_TIME 28 9702
TEST_TIME 29 10100
TEST_TIME 30 10738
TEST_TIME 31 10699
TEST_TIME 32 10620
TEST_TIME 33 10735
TEST_TIME 34 10745
TEST_TIME 35 10543
TEST_TIME 36 10338
TEST_TIME 37 10112
TEST_TIME 38 10002
TEST_TIME 39 10010
TEST_TIME 40 9929
TEST_TIME 41 9884
TEST_TIME 42 9894
TEST_TIME 43 9832
TEST_TIME 44 9729
TEST_TIME 45 9754
TEST_TIME 46 9721
TEST_TIME 47 9613
TEST_TIME 48 9557
TEST_TIME 49 9731
TEST_TIME 50 9644
TEST_TIME 51 9669
TEST_TIME 52 9721
TEST_TIME 53 9669
TEST_TIME 54 9724
TEST_TIME 55 9760
TEST_TIME 56 9798
TEST_TIME 57 9767
TEST_TIME 58 9720
TEST_TIME 59 9754
TEST_TIME 60 9690
TEST_TIME 61 9575
TEST_TIME 62 9506
TEST_TIME 63 9483
TEST_TIME 64 9403
TEST_TIME 65 9352
TEST_TIME 66 9292
TEST_TIME 67 9334
TEST_TIME 68 9296
TEST_TIME 69 9243
TEST_TIME 70 9203
TEST_TIME 71 9262
TEST_TIME 72 9226
TEST_TIME 73 9212
TEST_TIME 74 9176
TEST_TIME 75 9179
TEST_TIME 76 9305
TEST_TIME 77 9440
TEST_TIME 78 9450
TEST_TIME 79 9594
TEST_TIME 80 9537
TEST_TIME 81 9459
TEST_TIME 82 9438
TEST_TIME 83 9412
TEST_TIME 84 9327
TEST_TIME 85 9318
TEST_TIME 86 9244
TEST_TIME 87 9239
TEST_TIME 88 9172
TEST_TIME 89 9128
TEST_TIME 90 9142
TEST_TIME 91 9129
TEST_TIME 92 9052
TEST_TIME 93 9144
TEST_TIME 94 9138
TEST_TIME 95 9136
TEST_TIME 96 9163
TEST_TIME 97 9138
TEST_TIME 98 9101
TEST_TIME 99 9061
TEST_TIME 100 9038
TEST_TIME 101 9192
TEST_TIME 102 9177
TEST_TIME 103 9141
TEST_TIME 104 9230
TEST_TIME 105 9250
TEST_TIME 106 9214
TEST_TIME 107 9149
TEST_TIME 108 9111
TEST_TIME 109 9082
TEST_TIME 110 9072
TEST_TIME 111 9074
TEST_TIME 112 9042
TEST_TIME 113 9005
TEST_TIME 114 8970
TEST_TIME 115 8974
TEST_TIME 116 8961
TEST_TIME 117 8971
TEST_TIME 118 8938
TEST_TIME 119 8914
TEST_TIME 120 8866

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-12-2022 07:38:50 to 27-12-2022 07:38:54.

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 1491366 1491366 0 0.00 0 38735 13 0 0
doc_list_biud 1491355 1491355 0 0.00 0 47146 39 0 0
sp_add_invoice_to_stock 5177 5059 118 2.27 44 254138 26109 0 118
sp_cancel_adding_invoice 5153 5153 0 0.00 6 82706 1295 0 0
sp_cancel_client_order 1771 1771 0 0.00 6 14014 396 0 0
sp_cancel_customer_reserve 506923 506923 0 0.00 1 25697 99 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 4 8199 146 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 3 9608 132 0 0
sp_cancel_supplier_invoice 5724 5710 14 0.24 3 32083 999 0 14
sp_cancel_supplier_order 390 390 0 0.00 11 1342604 159785 0 0
sp_cancel_write_off 9501 9501 0 0.00 2 8702 163 0 0
sp_client_order 20718 20718 0 0.00 6 37766 462 0 0
sp_customer_reserve 467967 467967 0 0.00 0 52868 251 0 0
sp_fill_shopping_cart 31872 31872 0 0.00 1 33567 78 0 0
sp_fill_shopping_cart_clo_res 467933 467933 0 0.00 0 12519 18 0 0
sp_get_clo_for_invoice 5016 5016 0 0.00 0 31583 265 0 0
sp_kill_cost_storno 15765 15765 0 0.00 0 7816 64 0 0
sp_kill_qstorno_ret_qs2qd 1029447 1029447 0 0.00 0 32077 44 0 0
sp_kill_qty_storno 544553 544553 0 0.00 0 47144 100 0 0
sp_lock_selected_doc 20893 20893 0 0.00 0 6143 8 0 0
sp_make_cost_storno 28394 28394 0 0.00 0 38729 82 0 0
sp_multiply_rows_for_pdistr 28394 28394 0 0.00 0 6391 32 0 0
sp_multiply_rows_for_qdistr 20717 20717 0 0.00 0 15057 184 0 0
sp_pay_from_customer 7249 7249 0 0.00 5 15134 170 0 0
sp_pay_to_supplier 6039 6039 0 0.00 6 30751 195 0 0
sp_payment_common 13288 13288 0 0.00 2 7019 41 0 0
sp_qd_handle_on_cancel_clo 1771 1771 0 0.00 1 13939 320 0 0
sp_qd_handle_on_invoice_upd_sts 10169 10169 0 0.00 0 47141 463 0 0
sp_qd_handle_on_reserve_upd_sts 10089 10089 0 0.00 0 13683 61 0 0
sp_reserve_write_off 10090 10090 0 0.00 8 27966 265 0 0
sp_supplier_invoice 5587 5587 0 0.00 10 152930 3250 0 0
sp_supplier_order 5572 5572 0 0.00 7 44965 1307 0 0
srv_aggregate_perf_data 117 117 0 0.00 2292 130000 15006 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 845 137104 34267 0 0
srv_find_qd_qs_mism 1010766 1010766 0 0.00 0 37456 11 0 0
srv_make_invnt_saldo 524 524 0 0.00 47 69487 3345 0 0
srv_make_money_saldo 902 902 0 0.00 1 13707 317 0 0
srv_recalc_idx_stat 4 4 0 0.00 74886 461768 281797 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 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 5065 5065 0 0.00 0 17681 846 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 1 6113 450 0 0
t$perf-norm:sp_cancel_client_order 1771 1771 0 0.00 0 19525 32 0 0
t$perf-norm:sp_cancel_customer_reserve 735 735 0 0.00 0 646 20 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 931 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 8316 31 0 0
t$perf-norm:sp_cancel_supplier_invoice 361 361 0 0.00 0 1085 25 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 72899 5725 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 7699 24 0 0
t$perf-norm:sp_client_order 20718 20718 0 0.00 0 9468 36 0 0
t$perf-norm:sp_customer_reserve 1019 1019 0 0.00 0 752 14 0 0
t$perf-norm:sp_pay_from_customer 7249 7249 0 0.00 0 2321 15 0 0
t$perf-norm:sp_pay_to_supplier 6039 6039 0 0.00 0 7717 18 0 0
t$perf-norm:sp_reserve_write_off 10090 10090 0 0.00 0 13788 21 0 0
t$perf-norm:sp_supplier_invoice 5587 5587 0 0.00 0 22198 33 0 0
t$perf-norm:sp_supplier_order 5572 5572 0 0.00 0 2154 29 0 0
t$perf-norm:srv_aggregate_perf_data 117 117 0 0.00 0 236 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 198 5 0 0
t$perf-norm:srv_make_invnt_saldo 524 524 0 0.00 0 1592 14 0 0
t$perf-norm:srv_make_money_saldo 902 902 0 0.00 0 916 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 8 182 67 0 0
v_add_invoice_to_stock 10266 10030 236 2.29 0 8103 27 0 236
v_all_customers 38294 38294 0 0.00 0 82 3 0 0
v_all_suppliers 30724 30724 0 0.00 0 265 4 0 0
v_all_wares 41434 41434 0 0.00 0 203 4 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 696 13 0 0
v_cancel_client_order 3542 3542 0 0.00 0 4792 13 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 8105 28 0 0
v_cancel_customer_reserve 1470 1470 0 0.00 0 497 9 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 9509 21 0 0
v_cancel_supplier_invoice 750 722 28 3.73 0 2425 26 0 28
v_cancel_supplier_order 780 780 0 0.00 0 586 9 0 0
v_cancel_write_off 1968 1968 0 0.00 0 8082 24 0 0
v_max_id_clo_ord 5571 5571 0 0.00 0 1407 5 0 0
v_max_id_clo_res 1019 1019 0 0.00 0 6477 17 0 0
v_max_id_ord_sup 5583 5583 0 0.00 0 3756 6 0 0
v_max_non_paid_invoice 1831 1831 0 0.00 0 4912 9 0 0
v_max_non_paid_realizn 3605 3605 0 0.00 0 2957 8 0 0
v_min_id_clo_ord 5571 5571 0 0.00 0 1207 7 0 0
v_min_id_clo_res 1019 1019 0 0.00 0 3273 11 0 0
v_min_id_ord_sup 5583 5583 0 0.00 0 8132 20 0 0
v_min_non_paid_invoice 6039 6039 0 0.00 0 7010 12 0 0
v_min_non_paid_realizn 7249 7249 0 0.00 0 3610 14 0 0
v_our_firm 10426 10426 0 0.00 0 43 4 0 0
v_reserve_write_off 20178 20178 0 0.00 0 9349 11 0 0
x_lock_depdocs_on_canc_invoice 10502 10502 0 0.00 0 18353 116 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 13417 348 0 0
x_make_qstorno_1000_1200 5572 5572 0 0.00 2 44947 1161 0 0
x_make_qstorno_1200_2000 5587 5587 0 0.00 5 151495 3025 0 0
x_make_qstorno_2100_3300 467966 467966 0 0.00 0 52865 222 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-12-2022 07:38:55 to 27-12-2022 07:38:59.

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 2 8192000000 8796669344 8807108608 3189136 0.038 1 1 2 0 3286752 61840 35776
05:08:26 32780 8192000000 9739151376 10040401920 811339344 9.011 100 92 184 0 841570736 8364528 21866864
05:10:06 16645 8192000000 9765416784 10076692480 843457920 9.334 100 86 172 1 876242784 12335344 20449520
05:11:37 13011 8192000000 9814724160 10135752704 881272608 9.712 100 89 178 0 926073504 24492384 20308512
05:12:59 8986 8192000000 9857434512 10190991360 911945712 10.017 100 79 158 0 969818480 40502736 17370032
05:14:11 3971 8192000000 9915567632 10258096128 944447712 10.337 100 90 175 1 1026917232 63311872 19157648
05:15:19 1199 8192000000 9950970000 10299731968 983916848 10.722 100 89 174 0 1071410128 69794960 17698320
05:16:22 1318 8192000000 10007807824 10367127552 1014982624 11.024 100 82 159 1 1115649968 85245600 15421744
05:17:27 1075 8192000000 10014937648 10384855040 1047931936 11.341 100 97 186 0 1138982432 72159296 18891200
05:18:30 1017 8192000000 10033687232 10411974656 1062087664 11.476 100 91 175 0 1156971600 76639232 18244704
05:19:32 1208 8192000000 10039816512 10417369088 1066309392 11.517 100 98 192 2 1160671248 75405664 18956192
05:20:34 2270 8192000000 10031658864 10418229248 1074239376 11.593 100 76 135 3 1147693520 60377520 13076624
05:21:37 1003 8192000000 10033034992 10421424128 1081987888 11.666 100 90 174 0 1159818752 59506960 18323904
05:22:39 1327 8192000000 10061747072 10452242432 1085455200 11.699 100 88 173 0 1186933472 83381744 18096528
05:23:41 760 8192000000 10049211024 10440560640 1089060768 11.734 100 81 155 0 1171869808 66372304 16436736
05:24:43 1102 8192000000 10063837344 10452242432 1092750912 11.769 100 94 179 0 1185077440 74523120 17803408
05:25:45 1202 8192000000 10062228928 10449907712 1098084496 11.819 100 97 188 1 1189151728 71609984 19457248
05:26:48 1056 8192000000 10073098384 10463531008 1103057504 11.867 100 95 182 1 1195423856 72782384 19583968
05:27:50 994 8192000000 10049749584 10440953856 1097472512 11.814 100 93 181 1 1171635152 54933808 19228832
05:28:52 1069 8192000000 10049467936 10438381568 1096516176 11.805 100 94 177 2 1167934528 53046544 18371808
05:29:54 1867 8192000000 10026526496 10416455680 1077781200 11.626 99 71 134 2 1147274720 55766528 13726992
05:30:57 1199 8192000000 10012645872 10399494144 1062973248 11.485 99 92 172 0 1136720592 56285024 17462320
05:31:59 1180 8192000000 9995699920 10378756096 1047735568 11.339 100 86 158 1 1119522928 54070176 17717184
05:33:01 1360 8192000000 9969640416 10345242624 1036687008 11.233 100 78 143 3 1090148000 38558160 14902832
05:34:03 1061 8192000000 9998092368 10377596928 1051570800 11.376 100 87 166 1 1120996336 51933696 17491840
05:35:05 820 8192000000 9992723344 10363166720 1035632800 11.223 100 92 179 3 1115579744 60052832 19894112
05:36:07 4087 8192000000 9970382000 10343833600 1022004336 11.091 99 73 142 2 1099039936 62664160 14371440
05:37:11 1028 8192000000 9993134832 10369306624 1047101392 11.333 100 87 154 1 1113649504 52009024 14539088
05:38:17 855 8192000000 10026222928 10404622336 1075724800 11.607 100 93 171 4 1148580464 54505728 18349936
05:39:28 3603 8192000000 10039885712 10420879360 1081155120 11.658 100 80 133 4 1155790128 61553328 13081680
05:40:37 7156 8192000000 10058765568 10453438464 1083620736 11.682 100 67 125 1 1178128624 81484064 13023824
05:41:48 9141 8192000000 10090897440 10492899328 1102259920 11.859 100 63 111 0 1207976880 94528016 11188944
05:43:02 11831 8192000000 10101544240 10511753216 1112142672 11.953 100 73 141 0 1223091888 94524976 16424240
05:44:26 6762 8192000000 10161774016 10558734336 1122211568 12.048 100 77 146 2 1275541280 137847072 15482640
05:45:38 1190 8192000000 10204781760 10605924352 1134814192 12.167 100 92 181 1 1320824288 165986848 20023248
05:46:44 6894 8192000000 10209396624 10616291328 1150987152 12.319 100 75 147 1 1317497760 150688816 15821792
05:48:03 1147 8192000000 10228686320 10642706432 1161958080 12.422 100 93 183 4 1349101088 166350160 20792848
05:49:08 830 8192000000 10300977440 10700824576 1167011024 12.469 100 92 179 0 1412477488 225337568 20128896
05:50:12 1121 8192000000 10307977456 10714296320 1177333552 12.565 100 88 170 2 1421302336 223714768 20254016
05:51:17 1983 8192000000 10359705088 10775642112 1181402192 12.603 100 91 177 3 1470420544 268255232 20763120
05:52:24 1873 8192000000 10382592512 10804080640 1184544944 12.633 100 87 170 1 1490163824 285681744 19937136
05:53:30 1708 8192000000 10353641008 10781892608 1183851952 12.626 100 81 159 2 1466472112 264980544 17639616
05:54:36 1814 8192000000 10369968160 10800746496 1183813488 12.626 100 83 160 0 1478793488 275626960 19353040
05:55:41 1114 8192000000 10402842176 10822238208 1183505408 12.623 100 78 154 0 1511801424 309510496 18785520
05:56:45 873 8192000000 10422100864 10843791360 1190102688 12.684 100 87 168 2 1528678976 318026944 20549344
05:57:50 1757 8192000000 10467936208 10879877120 1195399040 12.734 100 86 165 1 1575095104 360774704 18921360
05:58:54 3828 8192000000 10508370896 10941227008 1201348064 12.789 100 69 138 0 1616938912 398805008 16785840
06:00:01 17041 8192000000 10436713168 10890989568 1176530096 12.558 99 42 83 0 1548612880 360881472 11201312
06:01:19 15198 8192000000 10468104480 10933186560 1168052592 12.479 99 46 91 0 1564281888 383792544 12436752
06:02:36 13216 8192000000 10436867632 10891681792 1148719296 12.297 98 40 79 1 1530020112 371060992 10239824
06:03:53 10917 8192000000 10372776864 10860560384 1131041024 12.131 98 42 82 0 1459294464 317706256 10547184
06:05:05 37455 8192000000 10283620544 10799280128 1125769424 12.081 99 24 48 0 1461368560 329245776 6353360
06:06:47 25576 8192000000 10141943680 10671374336 1139458272 12.210 99 19 35 0 1318490496 174753920 4278304
06:08:15 51231 8192000000 10080642704 10516684800 1088899760 11.732 96 25 48 0 1173972512 79724896 5347856
06:10:16 26714 8192000000 10084025872 10568278016 1122634592 12.052 99 24 48 1 1213376976 85985824 4756560
06:11:46 31791 8192000000 10020612704 10449620992 1087410288 11.718 96 56 107 6 1124766368 24593936 12762144
06:13:22 137104 8192000000 10074826720 10538848256 1125939264 12.083 100 38 76 1 1197412144 62941968 8530912
06:16:49 10188 8192000000 10059447232 10492334080 1108034688 11.914 100 36 69 0 1167836928 52721008 7081232
06:18:02 38495 8192000000 10083870448 10525696000 1114637424 11.976 99 42 84 1 1183706272 59522448 9546400
06:19:54 44340 8192000000 10061612480 10516905984 1100961968 11.847 98 14 28 0 1169282416 65147024 3173424
06:22:15 41602 8192000000 10080817824 10534404096 1115537648 11.985 99 17 32 0 1178666976 59401408 3727920
06:24:12 44315 8192000000 10111921840 10573611008 1135052352 12.169 100 16 32 0 1203374688 64908368 3413968
06:26:00 56712 8192000000 10082484608 10576404480 1103909312 11.875 97 16 32 0 1176168944 68596528 3663104
06:28:00 30028 8192000000 10111992672 10539151360 1126812304 12.091 99 54 103 1 1210138080 72596272 10729504
06:29:38 44977 8192000000 10082797744 10542096384 1133672880 12.156 99 29 56 1 1193662864 54146544 5843440
06:31:25 53062 8192000000 10088467728 10558836736 1117022640 11.999 97 15 30 0 1190327696 69997680 3307376
06:33:21 41702 8192000000 10107396944 10569773056 1137915584 12.196 99 32 61 0 1207112144 62877136 6319424
06:35:11 26729 8192000000 10128984064 10553204736 1146515216 12.277 99 52 102 0 1209934592 52872208 10547168
06:36:39 24516 8192000000 10126702288 10544959488 1150661280 12.316 100 57 104 3 1218263744 58071152 9531312
06:38:07 74150 8192000000 10109544176 10552328192 1141748208 12.232 99 44 87 0 1209394688 58092976 9553504
06:40:22 44089 8192000000 10092395632 10563346432 1138545392 12.202 99 17 34 0 1202521984 60544704 3431888
06:42:06 80209 8192000000 10098892592 10554945536 1114763296 11.977 96 10 18 0 1168227776 51971248 1493232
06:44:28 27449 8192000000 10131444800 10570076160 1142138432 12.236 98 33 64 0 1209508976 59420272 7950272
06:46:06 27841 8192000000 10187032864 10629984256 1168428064 12.482 100 33 65 0 1280602096 104685728 7488304
06:47:38 16229 8192000000 10261630960 10694279168 1173346096 12.528 100 46 90 0 1351091872 167289824 10455952
06:48:57 41164 8192000000 10243272672 10679287808 1137237200 12.190 96 36 70 0 1305885040 160520544 8127296
06:50:44 32510 8192000000 10302458880 10771169280 1157200432 12.377 99 43 84 0 1395252816 228133472 9918912
06:52:18 25526 8192000000 10344516928 10806968320 1144496096 12.258 98 44 88 0 1402245536 247680432 10069008
06:54:05 33674 8192000000 10211599120 10728353792 1129609776 12.118 98 19 38 0 1344346672 210025520 4711376
06:55:40 21790 8192000000 10173453344 10598391808 1111668288 11.948 98 36 69 2 1240417792 120341728 8407776
06:57:02 103181 8192000000 10093591248 10533371904 1126515408 12.089 99 47 92 1 1192764928 56570848 9678672
06:59:46 78963 8192000000 10081548608 10577387520 1125539008 12.079 100 17 32 0 1191870048 63384560 2946480
07:02:05 45539 8192000000 10067362656 10540445696 1113297088 11.964 99 20 40 0 1186943280 69105120 4541072
07:03:53 102479 8192000000 10054143952 10513997824 1130256784 12.124 100 12 24 0 1161998304 28839280 2902240
07:06:36 73064 8192000000 10134943264 10628718592 1131170096 12.132 99 21 42 2 1227614880 91693552 4751232
07:11:03 59299 8192000000 10077383920 10548379648 1127225760 12.095 99 22 42 1 1172283120 40659424 4397936
07:13:05 31344 8192000000 10101755888 10530906112 1104396848 11.879 97 34 67 0 1164928240 53726048 6805344
07:14:36 16015 8192000000 10090445392 10526019584 1125857504 12.082 99 39 77 0 1185644592 51804512 7982576
07:16:06 27917 8192000000 10154169776 10586796032 1129209552 12.114 99 41 82 0 1238849888 100965792 8674544
07:18:07 48552 8192000000 10069989872 10550276096 1134153136 12.160 100 27 54 0 1187413584 47992560 5267888
07:19:56 36574 8192000000 10178745648 10628521984 1138310880 12.200 99 39 78 0 1217778160 71485040 7982240
07:21:35 94320 8192000000 10090897648 10563575808 1088652096 11.730 95 21 39 0 1136537248 44051408 3833744
07:24:09 99957 8192000000 10063164432 10501099520 1097833328 11.817 97 30 60 0 1154975056 50414624 6727104
07:26:51 31366 8192000000 10085158976 10557317120 1140156864 12.217 100 26 48 0 1186558016 41030176 5370976
07:28:34 48939 8192000000 10046376880 10528198656 1114975376 11.979 99 12 24 0 1162973984 44748752 3249856
07:30:27 54372 8192000000 10075002912 10537648128 1131897856 12.139 100 21 42 0 1164742496 28352176 4492464
07:32:27 79985 8192000000 10060372880 10535149568 1100667744 11.844 97 21 42 0 1151910752 46733520 4509488
07:35:03 42611 8192000000 10073140176 10511409152 1106246000 11.897 97 17 32 0 1153172032 42969952 3956080
07:36:47 45297 8192000000 9252407376 9553162240 348044176 4.075 31 12 24 0 419727440 69077728 2605536

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 27-12-2022 07:39:01 to 27-12-2022 07:39:17.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 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 27-12-2022 07:39:18 to 27-12-2022 07:39:20.

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          219364
MON$OLDEST_ACTIVE               224313
MON$OLDEST_SNAPSHOT             224313
MON$NEXT_TRANSACTION            224313
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                       2043808
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             779
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 ---
:578: 
:579: mscs-test-161.budzdorov.ru    Tue Dec 27 07:37:34 2022
:580:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:581: 
--- end of diff output ---

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

27.12.22 07:39:21. 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
2022-12-27 07:39:24 No severe PSQL-related problems occured
27.12.22 07:39:25. End of report.