Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.09.2021 13:39
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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 15782268 kB
MemAvailable 30802488 kB
Buffers 0 kB
Cached 15958936 kB
SwapCached 2944 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.1
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 2021-09-30 13:37:07
DTS_END                         2021-09-30 13:37:07.2090
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/52020
TRN_ID                          235209
ATT_ID                          648
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: 9183.43 25859 1102012
customer order: creation 164.50 453 19740
customer order: refuse 14.54 323 1745
order to supplier: creation 44.45 1469 5334
order to supplier: removal 3.41 115293 409
invoice (draft): creation 44.55 3870 5346
invoice (draft): removal 54.54 801 6545
invoice accept: apply 39.57 18257 4748
invoice accept: cancel 49.36 759 5923
customer reserve: creation 3682.39 178 441887
customer reserve: removal 4792.27 66 575073
realization accept: apply 80.23 209 9628
realization accept: cancel 89.14 121 10697
payment from customer: creation 57.42 134 6890
payment from customer: removal 5.54 70 665
payment to supplier: creation 47.56 169 5707
payment to supplier: removal 3.14 79 377
service: total inventory turnovers 3.67 3405 440
service: total monetary turnovers 6.29 468 755
service: aggregate perf. data 0.83 17236 99
service: refresh index statistics 0.03 353827 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 30-09-2021 13:40:01 to 30-09-2021 13:40:04.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8144
TEST_TIME 2 4417
TEST_TIME 3 5197
TEST_TIME 4 5986
TEST_TIME 5 5684
TEST_TIME 6 5192
TEST_TIME 7 5159
TEST_TIME 8 5611
TEST_TIME 9 5582
TEST_TIME 10 5696
TEST_TIME 11 5905
TEST_TIME 12 5869
TEST_TIME 13 5998
TEST_TIME 14 6910
TEST_TIME 15 7534
TEST_TIME 16 7742
TEST_TIME 17 7829
TEST_TIME 18 7890
TEST_TIME 19 8375
TEST_TIME 20 8910
TEST_TIME 21 8982
TEST_TIME 22 8811
TEST_TIME 23 8943
TEST_TIME 24 9120
TEST_TIME 25 9444
TEST_TIME 26 9881
TEST_TIME 27 10692
TEST_TIME 28 10525
TEST_TIME 29 10346
TEST_TIME 30 10311
TEST_TIME 31 10521
TEST_TIME 32 10312
TEST_TIME 33 10052
TEST_TIME 34 10123
TEST_TIME 35 9933
TEST_TIME 36 9856
TEST_TIME 37 9701
TEST_TIME 38 9717
TEST_TIME 39 9789
TEST_TIME 40 9789
TEST_TIME 41 9801
TEST_TIME 42 9702
TEST_TIME 43 9624
TEST_TIME 44 9623
TEST_TIME 45 9641
TEST_TIME 46 9614
TEST_TIME 47 9576
TEST_TIME 48 9704
TEST_TIME 49 9722
TEST_TIME 50 9665
TEST_TIME 51 9575
TEST_TIME 52 9687
TEST_TIME 53 9730
TEST_TIME 54 9824
TEST_TIME 55 9923
TEST_TIME 56 9876
TEST_TIME 57 9781
TEST_TIME 58 9703
TEST_TIME 59 9589
TEST_TIME 60 9528
TEST_TIME 61 9474
TEST_TIME 62 9404
TEST_TIME 63 9369
TEST_TIME 64 9389
TEST_TIME 65 9366
TEST_TIME 66 9406
TEST_TIME 67 9375
TEST_TIME 68 9399
TEST_TIME 69 9495
TEST_TIME 70 9502
TEST_TIME 71 9502
TEST_TIME 72 9479
TEST_TIME 73 9508
TEST_TIME 74 9581
TEST_TIME 75 9643
TEST_TIME 76 9565
TEST_TIME 77 9635
TEST_TIME 78 9618
TEST_TIME 79 9515
TEST_TIME 80 9424
TEST_TIME 81 9412
TEST_TIME 82 9375
TEST_TIME 83 9321
TEST_TIME 84 9239
TEST_TIME 85 9222
TEST_TIME 86 9245
TEST_TIME 87 9257
TEST_TIME 88 9269
TEST_TIME 89 9235
TEST_TIME 90 9293
TEST_TIME 91 9330
TEST_TIME 92 9363
TEST_TIME 93 9367
TEST_TIME 94 9357
TEST_TIME 95 9322
TEST_TIME 96 9397
TEST_TIME 97 9448
TEST_TIME 98 9473
TEST_TIME 99 9398
TEST_TIME 100 9376
TEST_TIME 101 9347
TEST_TIME 102 9298
TEST_TIME 103 9246
TEST_TIME 104 9209
TEST_TIME 105 9199
TEST_TIME 106 9211
TEST_TIME 107 9194
TEST_TIME 108 9169
TEST_TIME 109 9147
TEST_TIME 110 9184
TEST_TIME 111 9171
TEST_TIME 112 9137
TEST_TIME 113 9281
TEST_TIME 114 9384
TEST_TIME 115 9327
TEST_TIME 116 9401
TEST_TIME 117 9347
TEST_TIME 118 9280
TEST_TIME 119 9261
TEST_TIME 120 9185

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-09-2021 13:40:05 to 30-09-2021 13:40:08.

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 1511372 1511372 0 0.00 0 20815 7 0 0
doc_list_biud 1511371 1511371 0 0.00 0 75736 31 0 0
sp_add_invoice_to_stock 4898 4748 150 3.06 29 180566 18258 0 150
sp_cancel_adding_invoice 5923 5923 0 0.00 4 48649 760 0 0
sp_cancel_client_order 1745 1745 0 0.00 5 8256 324 0 0
sp_cancel_customer_reserve 575073 575073 0 0.00 1 41556 66 0 0
sp_cancel_pay_from_customer 665 665 0 0.00 3 7504 71 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 3039 80 0 0
sp_cancel_supplier_invoice 6554 6545 9 0.13 2 75766 802 0 9
sp_cancel_supplier_order 409 409 0 0.00 6 933767 115293 0 0
sp_cancel_write_off 10697 10697 0 0.00 1 16215 122 0 0
sp_client_order 19740 19740 0 0.00 4 20701 454 0 0
sp_customer_reserve 441887 441887 0 0.00 0 52101 179 0 0
sp_fill_shopping_cart 30411 30411 0 0.00 1 25359 48 0 0
sp_fill_shopping_cart_clo_res 441884 441884 0 0.00 0 29596 17 0 0
sp_get_clo_for_invoice 4745 4745 0 0.00 0 42691 338 0 0
sp_kill_cost_storno 17662 17662 0 0.00 0 11559 55 0 0
sp_kill_qstorno_ret_qs2qd 1167797 1167797 0 0.00 0 75734 35 0 0
sp_kill_qty_storno 614765 614765 0 0.00 0 75736 75 0 0
sp_lock_selected_doc 19963 19963 0 0.00 0 8120 6 0 0
sp_make_cost_storno 26970 26970 0 0.00 0 20813 68 0 0
sp_multiply_rows_for_pdistr 26970 26970 0 0.00 0 10699 42 0 0
sp_multiply_rows_for_qdistr 19740 19740 0 0.00 0 18111 235 0 0
sp_pay_from_customer 6890 6890 0 0.00 4 26209 134 0 0
sp_pay_to_supplier 5707 5707 0 0.00 4 21353 169 0 0
sp_payment_common 12597 12597 0 0.00 1 14424 28 0 0
sp_qd_handle_on_cancel_clo 1745 1745 0 0.00 0 8249 285 0 0
sp_qd_handle_on_invoice_upd_sts 10668 10668 0 0.00 0 16772 344 0 0
sp_qd_handle_on_reserve_upd_sts 9628 9628 0 0.00 0 15215 59 0 0
sp_reserve_write_off 9628 9628 0 0.00 7 27166 210 0 0
sp_supplier_invoice 5346 5346 0 0.00 7 98980 3870 0 0
sp_supplier_order 5334 5334 0 0.00 6 83638 1470 0 0
srv_aggregate_perf_data 99 99 0 0.00 2737 96292 17236 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1524 132105 44896 0 0
srv_find_qd_qs_mism 1056255 1056255 0 0.00 0 18158 6 0 0
srv_make_invnt_saldo 440 440 0 0.00 44 113032 3406 0 0
srv_make_money_saldo 755 755 0 0.00 1 12703 469 0 0
srv_recalc_idx_stat 4 4 0 0.00 208201 464723 353827 0 0
t$perf-abend:sp_add_invoice_to_stock 150 150 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 4748 4748 0 0.00 0 15308 185 0 0
t$perf-norm:sp_cancel_adding_invoice 387 387 0 0.00 0 2086 77 0 0
t$perf-norm:sp_cancel_client_order 1745 1745 0 0.00 0 1715 13 0 0
t$perf-norm:sp_cancel_customer_reserve 659 659 0 0.00 0 2859 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 665 665 0 0.00 0 4159 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 1465 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 3860 32 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 15828 1489 0 0
t$perf-norm:sp_cancel_write_off 945 945 0 0.00 0 2609 8 0 0
t$perf-norm:sp_client_order 19740 19740 0 0.00 0 9255 22 0 0
t$perf-norm:sp_customer_reserve 966 966 0 0.00 0 3975 11 0 0
t$perf-norm:sp_pay_from_customer 6890 6890 0 0.00 0 4039 12 0 0
t$perf-norm:sp_pay_to_supplier 5707 5707 0 0.00 0 22833 16 0 0
t$perf-norm:sp_reserve_write_off 9628 9628 0 0.00 0 16653 14 0 0
t$perf-norm:sp_supplier_invoice 5346 5346 0 0.00 0 8333 23 0 0
t$perf-norm:sp_supplier_order 5334 5334 0 0.00 0 8703 17 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 150 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 5171 80 0 0
t$perf-norm:srv_make_invnt_saldo 440 440 0 0.00 0 4370 20 0 0
t$perf-norm:srv_make_money_saldo 755 755 0 0.00 0 3223 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 16 6 0 0
v_add_invoice_to_stock 9790 9490 300 3.06 0 6161 17 0 300
v_all_customers 35996 35996 0 0.00 0 52 1 0 0
v_all_suppliers 29718 29718 0 0.00 0 68 1 0 0
v_all_wares 39480 39480 0 0.00 0 72 1 0 0
v_cancel_adding_invoice 774 774 0 0.00 0 534 7 0 0
v_cancel_client_order 3490 3490 0 0.00 0 3937 11 0 0
v_cancel_customer_prepayment 1330 1330 0 0.00 0 4067 7 0 0
v_cancel_customer_reserve 1318 1318 0 0.00 0 4586 9 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 2966 8 0 0
v_cancel_supplier_invoice 824 806 18 2.18 0 3553 23 0 18
v_cancel_supplier_order 818 818 0 0.00 0 1497 7 0 0
v_cancel_write_off 1890 1890 0 0.00 0 1947 15 0 0
v_max_id_clo_ord 5333 5333 0 0.00 0 1994 2 0 0
v_max_id_clo_res 966 966 0 0.00 0 7324 25 0 0
v_max_id_ord_sup 5337 5337 0 0.00 0 8753 7 0 0
v_max_non_paid_invoice 1518 1518 0 0.00 0 619 3 0 0
v_max_non_paid_realizn 3665 3665 0 0.00 0 3871 4 0 0
v_min_id_clo_ord 5333 5333 0 0.00 0 5005 8 0 0
v_min_id_clo_res 966 966 0 0.00 0 4909 13 0 0
v_min_id_ord_sup 5337 5337 0 0.00 0 7211 13 0 0
v_min_non_paid_invoice 5707 5707 0 0.00 0 13236 22 0 0
v_min_non_paid_realizn 6890 6890 0 0.00 0 7068 14 0 0
v_our_firm 9934 9934 0 0.00 0 25 1 0 0
v_reserve_write_off 19256 19256 0 0.00 0 14693 8 0 0
x_lock_depdocs_on_canc_invoice 12065 12065 0 0.00 0 11423 104 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 7881 328 0 0
x_make_qstorno_1000_1200 5334 5334 0 0.00 2 82002 1373 0 0
x_make_qstorno_1200_2000 5346 5346 0 0.00 3 98827 3697 0 0
x_make_qstorno_2100_3300 441887 441887 0 0.00 0 52100 159 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 30-09-2021 13:40:09 to 30-09-2021 13:40:14.

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:11 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:12 39223 8192000000 9725126896 10020851712 791723488 8.812 100 90 180 0 821753808 7745984 22284336
11:09:53 23087 8192000000 9744835472 10046767104 817741136 9.076 100 86 172 0 852375712 13710160 20924416
11:11:23 11081 8192000000 9798537344 10115833856 864432672 9.544 100 78 156 1 904304128 21929072 17942384
11:12:40 12329 8192000000 9873283088 10204327936 912875040 10.026 100 83 166 0 974779712 43996656 17908016
11:13:57 9679 8192000000 9909316064 10252840960 949698688 10.388 100 82 164 1 1020901680 53691136 17511856
11:15:11 5685 8192000000 9968057088 10322767872 988104848 10.763 100 86 169 0 1083565232 78616176 16844208
11:16:19 2693 8192000000 9998844016 10361008128 1012237920 10.997 100 88 170 0 1113441440 84016432 17187088
11:17:23 1489 8192000000 10022865248 10388496384 1035859632 11.225 100 88 174 1 1127685520 73944352 17881536
11:18:26 1206 8192000000 10048703024 10419335168 1069905232 11.551 100 95 191 0 1159809792 70071776 19832784
11:19:28 9939 8192000000 10007149584 10391154688 1079631568 11.644 100 74 152 1 1121137232 24370528 17135136
11:20:38 4500 8192000000 10007979424 10397503488 1087658032 11.720 100 43 83 1 1121095840 24383424 9054384
11:21:46 5954 8192000000 10000613520 10395172864 1095570608 11.796 100 35 70 1 1118304400 13585776 9148016
11:22:52 6480 8192000000 10013125696 10423750656 1101397248 11.851 100 29 58 1 1136626368 29742352 5486768
11:23:59 22411 8192000000 10011143200 10427482112 1101578752 11.853 100 45 86 0 1143532912 32420128 9534032
11:25:25 5870 8192000000 10036121520 10450337792 1096451104 11.804 99 43 84 0 1133556064 27762368 9342592
11:26:34 5884 8192000000 10029404624 10427371520 1097992208 11.819 99 40 80 2 1136293904 30137536 8164160
11:27:44 4222 8192000000 10006360608 10399113216 1097560432 11.814 100 30 59 0 1113969920 8220816 8188672
11:28:53 36475 8192000000 9975213072 10356215808 996727024 10.847 93 55 109 1 1027161232 17709776 12724432
11:30:30 4305 8192000000 9937625760 10315931648 986270768 10.745 97 46 90 1 1017170720 20538848 10361104
11:31:35 5441 8192000000 9935114032 10311249920 1009905216 10.974 99 47 94 3 1037127760 16427280 10795264
11:32:43 8872 8192000000 9934105376 10336079872 1012944048 11.004 98 34 68 0 1050592880 29449632 8199200
11:33:55 37825 8192000000 9977334176 10382077952 1044531152 11.308 100 23 47 1 1069599776 19954368 5114256
11:35:39 4396 8192000000 9995613248 10389954560 1062510640 11.481 100 47 91 4 1121005296 48561168 9933488
11:36:47 10192 8192000000 9983752656 10380152832 1070669296 11.558 100 33 65 2 1097952320 19258880 8024144
11:38:11 5257 8192000000 10037706800 10434441216 1084969264 11.695 100 62 123 0 1137804592 39736720 13098608
11:39:21 89409 8192000000 10050480800 10466234368 1097050416 11.810 100 30 60 0 1159191728 55203776 6937536
11:41:53 23076 8192000000 10087730752 10493308928 1119520096 12.022 100 40 79 0 1189309952 61118896 8670960
11:43:24 30338 8192000000 10117607616 10523869184 1144144144 12.254 100 44 86 1 1217677232 63049856 10483232
11:45:01 19797 8192000000 10160928400 10582204416 1153345600 12.341 100 46 90 2 1278257840 114521504 10390736
11:46:29 17242 8192000000 10219453824 10633043968 1160666000 12.410 100 55 110 0 1320936896 147436240 12834656
11:47:53 10715 8192000000 10249240272 10664738816 1158116320 12.386 99 53 104 2 1346939152 177137552 11685280
11:49:16 6704 8192000000 10254201696 10679066624 1173653232 12.531 100 49 96 0 1358211440 174012640 10545568
11:50:29 3137 8192000000 10307364416 10710024192 1165469456 12.454 100 76 152 1 1401593856 219216640 16907760
11:51:36 1523 8192000000 10320229472 10729877504 1165900544 12.458 100 90 179 1 1412283344 226839040 19543760
11:52:41 7908 8192000000 10283001664 10710704128 1177371392 12.566 100 33 67 0 1394080800 208747424 7961984
11:53:55 10342 8192000000 10312243328 10777923584 1173156288 12.526 100 38 76 1 1422206288 239295056 9754944
11:55:13 3288 8192000000 10344703328 10751676416 1145761216 12.270 99 72 144 0 1436533856 274169664 16602976
11:56:19 1759 8192000000 10310705712 10728886272 1137809072 12.195 100 77 155 1 1420762288 264883328 18069888
11:57:27 12243 8192000000 10307456000 10733019136 1104677632 11.882 96 36 72 1 1373701776 259478144 9546000
11:58:46 27510 8192000000 10328985760 10786058240 1153863504 12.346 100 30 60 0 1435904048 274229168 7811376
12:00:16 16630 8192000000 10314495312 10774720512 1142196816 12.236 99 41 82 0 1433190736 281443088 9550832
12:01:34 34916 8192000000 10313327840 10762973184 1111503072 11.947 97 21 42 3 1398326944 281528288 5295584
12:03:25 22706 8192000000 10225257056 10736828416 1119350272 12.021 100 13 26 0 1337056304 214068464 3637568
12:04:49 93654 8192000000 10115414896 10549542912 1126766976 12.091 99 19 38 2 1199098864 68364336 3967552
12:07:25 34075 8192000000 10135609696 10555817984 1121739488 12.043 99 41 83 1 1223182048 92620736 8821824
12:09:02 92808 8192000000 10076096448 10502369280 1099701792 11.835 97 32 64 1 1169320960 63019024 6600144
12:11:44 70771 8192000000 10066569296 10502991872 1101438032 11.851 97 27 54 1 1133173984 25715200 6020752
12:13:59 56382 8192000000 10166365392 10603380736 1122056112 12.046 99 25 48 1 1223800768 96464736 5279920
12:15:57 41888 8192000000 10151357248 10605916160 1097330464 11.812 97 24 49 0 1231108368 128195200 5582704
12:17:39 31267 8192000000 10101519776 10588532736 1126099744 12.085 100 14 28 1 1210065552 80770816 3194992
12:19:11 36676 8192000000 10067227248 10534465536 1123699472 12.062 100 12 24 1 1174106272 47882864 2523936
12:20:57 72682 8192000000 10083520704 10544807936 1137918560 12.196 100 16 32 0 1218593296 77278160 3396576
12:23:10 19488 8192000000 10108802832 10535796736 1135090592 12.169 99 53 104 1 1177999232 32999408 9909232
12:24:40 52780 8192000000 10159304464 10602917888 1142859792 12.242 99 21 42 0 1230402992 82903040 4640160
12:26:33 40914 8192000000 10082366880 10528878592 1144174000 12.255 99 16 32 0 1176149360 28814272 3161088
12:28:14 6931 8192000000 10090210592 10520772608 1154632384 12.353 100 55 109 2 1185235920 19715344 10888192
12:29:23 81207 8192000000 10139469712 10645590016 1139517616 12.211 98 13 26 1 1293448944 150789056 3142272
12:31:45 75274 8192000000 10109353856 10616340480 1137976832 12.196 99 12 23 0 1206348896 65896000 2476064
12:34:00 132105 8192000000 10067812576 10524381184 1112565408 11.957 97 40 80 0 1161659232 40730944 8362880
12:37:20 37390 8192000000 10112326816 10569138176 1135193440 12.170 99 32 64 1 1189443888 47213808 7036640
12:39:10 45885 8192000000 10124299328 10588364800 1137558256 12.193 99 18 36 0 1221508336 80404064 3546016
12:40:57 51038 8192000000 10119366000 10623188992 1144312688 12.256 100 7 14 0 1223647392 77617920 1716784
12:42:57 69500 8192000000 10069394272 10539843584 1138462960 12.201 99 19 39 1 1208757648 65940400 4354288
12:45:10 64184 8192000000 10105615024 10589126656 1092548464 11.767 96 25 50 0 1177514224 79414576 5551184
12:47:23 68167 8192000000 10058710032 10497912832 1041068288 11.275 93 22 44 0 1100768272 55120288 4579696
12:49:32 35941 8192000000 10065056560 10497220608 1069073904 11.543 96 27 54 1 1133442816 59075760 5293152
12:51:14 47245 8192000000 10073169328 10527219712 1091160656 11.754 98 23 46 0 1180707088 85141568 4404864
12:53:02 57664 8192000000 10070373632 10491289600 1085927232 11.704 97 34 69 1 1135558672 41930944 7700496
12:55:00 107723 8192000000 10030450720 10493521920 1089758512 11.740 98 20 40 0 1138284864 44513824 4012528
12:57:55 17386 8192000000 10054162256 10502397952 1106106624 11.896 100 28 54 3 1142300160 30892464 5301072
12:59:14 53512 8192000000 10079274656 10538061824 1117481296 12.003 100 14 28 0 1191274096 70949696 2843104
13:02:20 46372 8192000000 10143021120 10613329920 1129395024 12.116 99 22 44 2 1251818336 117065008 5358304
13:04:14 55456 8192000000 10096684288 10556084224 1119981728 12.027 99 14 28 1 1229074752 105574336 3518688
13:06:10 58490 8192000000 10135769952 10581688320 1117306624 12.002 99 11 22 3 1189443424 69731120 2405680
13:08:29 69214 8192000000 10078887344 10542972928 1126279824 12.086 99 18 35 1 1190853328 60807328 3766176
13:10:39 70070 8192000000 10079137728 10540552192 1122583552 12.051 99 24 48 0 1170089520 42831760 4674208
13:12:50 37888 8192000000 10161690112 10648707072 1129102848 12.113 99 22 44 0 1242165744 108190800 4872096
13:14:30 76237 8192000000 10072210032 10528022528 1117028256 11.999 98 24 48 0 1194211936 72492976 4690704
13:16:59 96822 8192000000 10061397200 10542379008 1103955776 11.875 97 25 50 0 1148647360 38841552 5850032
13:19:38 38849 8192000000 10042255776 10506637312 1121308496 12.039 99 7 14 0 1153262944 31041088 913360
13:21:19 47965 8192000000 10067514960 10531254272 1132808272 12.148 100 9 19 0 1164305632 29914704 1582656
13:23:08 36413 8192000000 10084472592 10538123264 1135912544 12.177 99 16 32 1 1173298208 34024896 3360768
13:24:53 38164 8192000000 10119309632 10566307840 1152374144 12.332 100 25 50 3 1205258576 46970592 5913840
13:26:34 26981 8192000000 10174127040 10618744832 1162643200 12.428 100 28 56 2 1276762240 107562208 6556832
13:28:04 53223 8192000000 10182700624 10621747200 1132108384 12.141 98 22 44 1 1251742288 114251568 5382336
13:29:58 51253 8192000000 10206842736 10783059968 1140756224 12.223 99 14 28 0 1317072800 172851520 3465056
13:31:51 27079 8192000000 10058314944 10482655232 1071002208 11.562 94 44 86 1 1090877584 11191968 8683408
13:33:19 49176 8192000000 10064153760 10567331840 1086131744 11.706 97 21 42 0 1146095328 55878880 4084704
13:35:12 99427 8192000000 10048969392 10507730944 1086932672 11.713 97 16 30 0 1119452496 29522624 2997200

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 30-09-2021 13:40:15 to 30-09-2021 13:40:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 150 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 30-09-2021 13:40:32 to 30-09-2021 13:40:34.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          235577
MON$OLDEST_ACTIVE               235578
MON$OLDEST_SNAPSHOT             235578
MON$NEXT_TRANSACTION            235578
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                       2065440
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             802
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2619 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2619 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2619 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2619 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 ---
:16: 
:17: mscs-test-161.budzdorov.ru    Thu Sep 30 13:37:46 2021
:18:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:19: 
:20: 
:21: mscs-test-161.budzdorov.ru    Thu Sep 30 13:37:46 2021
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
--- end of diff output ---

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

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