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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16470916 kB
MemAvailable 31442472 kB
Buffers 20 kB
Cached 15135280 kB
SwapCached 0 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-12-07 04:37:09
DTS_END                         2021-12-07 04:37:09.8430
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/59560
TRN_ID                          225158
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: 8531.76 22954 1023811
customer order: creation 160.38 424 19246
customer order: refuse 13.44 425 1613
order to supplier: creation 42.78 1637 5134
order to supplier: removal 3.08 125423 370
invoice (draft): creation 42.68 4260 5122
invoice (draft): removal 49.49 977 5939
invoice accept: apply 38.22 21592 4586
invoice accept: cancel 43.93 992 5272
customer reserve: creation 3527.03 212 423243
customer reserve: removal 4336.88 69 520426
realization accept: apply 78.29 221 9395
realization accept: cancel 76.73 138 9208
payment from customer: creation 54.44 130 6533
payment from customer: removal 5.51 132 661
payment to supplier: creation 46.02 143 5522
payment to supplier: removal 3.06 105 367
service: total inventory turnovers 3.43 4115 411
service: total monetary turnovers 5.55 790 666
service: aggregate perf. data 0.78 19964 93
service: refresh index statistics 0.03 277324 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-12-2021 04:38:30 to 07-12-2021 04:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5650
TEST_TIME 2 5068
TEST_TIME 3 4620
TEST_TIME 4 4656
TEST_TIME 5 4734
TEST_TIME 6 4952
TEST_TIME 7 5290
TEST_TIME 8 5364
TEST_TIME 9 5648
TEST_TIME 10 5807
TEST_TIME 11 6081
TEST_TIME 12 6266
TEST_TIME 13 6373
TEST_TIME 14 6675
TEST_TIME 15 6989
TEST_TIME 16 7035
TEST_TIME 17 7284
TEST_TIME 18 7674
TEST_TIME 19 8294
TEST_TIME 20 8341
TEST_TIME 21 8229
TEST_TIME 22 8482
TEST_TIME 23 8436
TEST_TIME 24 9571
TEST_TIME 25 9607
TEST_TIME 26 10103
TEST_TIME 27 9963
TEST_TIME 28 10151
TEST_TIME 29 10776
TEST_TIME 30 10517
TEST_TIME 31 10456
TEST_TIME 32 10417
TEST_TIME 33 10212
TEST_TIME 34 10064
TEST_TIME 35 9933
TEST_TIME 36 9888
TEST_TIME 37 9936
TEST_TIME 38 9798
TEST_TIME 39 9768
TEST_TIME 40 9585
TEST_TIME 41 9558
TEST_TIME 42 9537
TEST_TIME 43 9630
TEST_TIME 44 9610
TEST_TIME 45 9484
TEST_TIME 46 9411
TEST_TIME 47 9360
TEST_TIME 48 9314
TEST_TIME 49 9228
TEST_TIME 50 9149
TEST_TIME 51 9153
TEST_TIME 52 9074
TEST_TIME 53 8939
TEST_TIME 54 8841
TEST_TIME 55 8773
TEST_TIME 56 8707
TEST_TIME 57 8727
TEST_TIME 58 8724
TEST_TIME 59 8723
TEST_TIME 60 8745
TEST_TIME 61 8819
TEST_TIME 62 8869
TEST_TIME 63 9045
TEST_TIME 64 9010
TEST_TIME 65 9040
TEST_TIME 66 9023
TEST_TIME 67 8974
TEST_TIME 68 9128
TEST_TIME 69 9121
TEST_TIME 70 9170
TEST_TIME 71 9118
TEST_TIME 72 9010
TEST_TIME 73 8960
TEST_TIME 74 8890
TEST_TIME 75 8916
TEST_TIME 76 8850
TEST_TIME 77 8793
TEST_TIME 78 8743
TEST_TIME 79 8654
TEST_TIME 80 8574
TEST_TIME 81 8583
TEST_TIME 82 8637
TEST_TIME 83 8647
TEST_TIME 84 8684
TEST_TIME 85 8696
TEST_TIME 86 8672
TEST_TIME 87 8755
TEST_TIME 88 8720
TEST_TIME 89 8725
TEST_TIME 90 8752
TEST_TIME 91 8773
TEST_TIME 92 8778
TEST_TIME 93 8787
TEST_TIME 94 8706
TEST_TIME 95 8693
TEST_TIME 96 8656
TEST_TIME 97 8609
TEST_TIME 98 8568
TEST_TIME 99 8594
TEST_TIME 100 8584
TEST_TIME 101 8578
TEST_TIME 102 8545
TEST_TIME 103 8559
TEST_TIME 104 8560
TEST_TIME 105 8608
TEST_TIME 106 8657
TEST_TIME 107 8703
TEST_TIME 108 8705
TEST_TIME 109 8719
TEST_TIME 110 8716
TEST_TIME 111 8674
TEST_TIME 112 8641
TEST_TIME 113 8634
TEST_TIME 114 8638
TEST_TIME 115 8639
TEST_TIME 116 8592
TEST_TIME 117 8531
TEST_TIME 118 8607
TEST_TIME 119 8574
TEST_TIME 120 8535

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-12-2021 04:38:34 to 07-12-2021 04:38:37.

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 1416356 1416356 0 0.00 0 20838 8 0 0
doc_list_biud 1416355 1416355 0 0.00 0 51016 33 0 0
sp_add_invoice_to_stock 4673 4586 87 1.86 52 165649 21593 0 87
sp_cancel_adding_invoice 5272 5272 0 0.00 5 58122 993 0 0
sp_cancel_client_order 1613 1613 0 0.00 5 11101 426 0 0
sp_cancel_customer_reserve 520426 520426 0 0.00 1 26953 69 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 3 12398 133 0 0
sp_cancel_pay_to_supplier 367 367 0 0.00 3 6146 105 0 0
sp_cancel_supplier_invoice 5946 5939 7 0.11 2 19114 977 0 7
sp_cancel_supplier_order 370 370 0 0.00 6 1329478 125424 0 0
sp_cancel_write_off 9208 9208 0 0.00 1 16446 138 0 0
sp_client_order 19246 19246 0 0.00 4 24423 425 0 0
sp_customer_reserve 423243 423243 0 0.00 0 91209 212 0 0
sp_fill_shopping_cart 29496 29496 0 0.00 1 28852 52 0 0
sp_fill_shopping_cart_clo_res 423232 423232 0 0.00 0 16420 18 0 0
sp_get_clo_for_invoice 4575 4575 0 0.00 0 32666 427 0 0
sp_kill_cost_storno 15508 15508 0 0.00 0 15109 68 0 0
sp_kill_qstorno_ret_qs2qd 1056369 1056369 0 0.00 0 26891 38 0 0
sp_kill_qty_storno 556798 556798 0 0.00 0 51015 83 0 0
sp_lock_selected_doc 19223 19223 0 0.00 0 10093 6 0 0
sp_make_cost_storno 26024 26024 0 0.00 0 15336 63 0 0
sp_multiply_rows_for_pdistr 26024 26024 0 0.00 0 10191 42 0 0
sp_multiply_rows_for_qdistr 19246 19246 0 0.00 0 23955 214 0 0
sp_pay_from_customer 6533 6533 0 0.00 4 9803 130 0 0
sp_pay_to_supplier 5522 5522 0 0.00 5 10683 144 0 0
sp_payment_common 12054 12054 0 0.00 1 9445 26 0 0
sp_qd_handle_on_cancel_clo 1613 1613 0 0.00 0 11093 393 0 0
sp_qd_handle_on_invoice_upd_sts 9847 9847 0 0.00 0 51015 449 0 0
sp_qd_handle_on_reserve_upd_sts 9395 9395 0 0.00 0 25904 70 0 0
sp_reserve_write_off 9395 9395 0 0.00 6 28859 222 0 0
sp_supplier_invoice 5122 5122 0 0.00 8 85085 4261 0 0
sp_supplier_order 5134 5134 0 0.00 6 66697 1637 0 0
srv_aggregate_perf_data 93 93 0 0.00 2778 349966 19965 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1298 153024 45010 0 0
srv_find_qd_qs_mism 980042 980042 0 0.00 0 16197 7 0 0
srv_make_invnt_saldo 411 411 0 0.00 54 108074 4116 0 0
srv_make_money_saldo 666 666 0 0.00 1 15866 791 0 0
srv_recalc_idx_stat 4 4 0 0.00 192485 442801 277324 0 0
t$perf-abend:sp_add_invoice_to_stock 87 87 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4586 4586 0 0.00 0 13909 207 0 0
t$perf-norm:sp_cancel_adding_invoice 383 383 0 0.00 1 16485 147 0 0
t$perf-norm:sp_cancel_client_order 1613 1613 0 0.00 0 3685 18 0 0
t$perf-norm:sp_cancel_customer_reserve 619 619 0 0.00 0 4588 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 1503 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 367 367 0 0.00 0 1537 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 704 20 0 0
t$perf-norm:sp_cancel_supplier_order 370 370 0 0.00 0 30710 1722 0 0
t$perf-norm:sp_cancel_write_off 839 839 0 0.00 0 2075 6 0 0
t$perf-norm:sp_client_order 19246 19246 0 0.00 0 13956 25 0 0
t$perf-norm:sp_customer_reserve 954 954 0 0.00 0 2325 14 0 0
t$perf-norm:sp_pay_from_customer 6533 6533 0 0.00 0 8381 17 0 0
t$perf-norm:sp_pay_to_supplier 5522 5522 0 0.00 0 16821 16 0 0
t$perf-norm:sp_reserve_write_off 9395 9395 0 0.00 0 8604 11 0 0
t$perf-norm:sp_supplier_invoice 5122 5122 0 0.00 0 5156 19 0 0
t$perf-norm:sp_supplier_order 5134 5134 0 0.00 0 9471 22 0 0
t$perf-norm:srv_aggregate_perf_data 93 93 0 0.00 0 362 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 191 5 0 0
t$perf-norm:srv_make_invnt_saldo 411 411 0 0.00 0 3677 20 0 0
t$perf-norm:srv_make_money_saldo 666 666 0 0.00 0 600 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9324 9150 174 1.86 0 3851 19 0 174
v_all_customers 35300 35300 0 0.00 0 19 1 0 0
v_all_suppliers 28514 28514 0 0.00 0 23 1 0 0
v_all_wares 38490 38490 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 766 766 0 0.00 0 564 10 0 0
v_cancel_client_order 3226 3226 0 0.00 0 1872 6 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 8929 20 0 0
v_cancel_customer_reserve 1238 1238 0 0.00 0 3037 10 0 0
v_cancel_payment_to_supplier 734 734 0 0.00 0 1087 7 0 0
v_cancel_supplier_invoice 816 802 14 1.71 0 3139 20 0 14
v_cancel_supplier_order 740 740 0 0.00 0 1585 8 0 0
v_cancel_write_off 1678 1678 0 0.00 0 6872 23 0 0
v_max_id_clo_ord 5132 5132 0 0.00 0 788 1 0 0
v_max_id_clo_res 954 954 0 0.00 0 4243 18 0 0
v_max_id_ord_sup 5119 5119 0 0.00 0 5367 6 0 0
v_max_non_paid_invoice 1515 1515 0 0.00 0 819 3 0 0
v_max_non_paid_realizn 3427 3427 0 0.00 0 917 3 0 0
v_min_id_clo_ord 5132 5132 0 0.00 0 2676 6 0 0
v_min_id_clo_res 954 954 0 0.00 0 22072 38 0 0
v_min_id_ord_sup 5119 5119 0 0.00 0 2831 4 0 0
v_min_non_paid_invoice 5522 5522 0 0.00 0 9441 22 0 0
v_min_non_paid_realizn 6532 6532 0 0.00 0 7541 18 0 0
v_our_firm 9400 9400 0 0.00 0 17 1 0 0
v_reserve_write_off 18790 18790 0 0.00 0 3636 6 0 0
x_lock_depdocs_on_canc_invoice 10810 10810 0 0.00 0 23921 134 0 0
x_lock_depdocs_on_canc_sup_ord 370 370 0 0.00 0 19268 463 0 0
x_make_qstorno_1000_1200 5134 5134 0 0.00 2 66680 1543 0 0
x_make_qstorno_1200_2000 5122 5122 0 0.00 3 82090 4056 0 0
x_make_qstorno_2100_3300 423243 423243 0 0.00 0 91208 192 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-12-2021 04:38:38 to 07-12-2021 04:38:42.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
02:07:13 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:14 29819 8192000000 9729766432 10029948928 803686128 8.934 100 88 176 0 832995728 7860864 21448736
02:09:48 34172 8192000000 9771366288 10078228480 837684064 9.277 100 86 172 0 870418144 12516032 20218048
02:11:25 15471 8192000000 9805387264 10127110144 872884560 9.629 100 84 168 0 912630448 20553104 19192784
02:12:48 12529 8192000000 9882354800 10217025536 921295152 10.109 100 83 166 1 990965744 51745472 17925120
02:14:04 13630 8192000000 9923736016 10266472448 960806336 10.497 100 79 158 1 1033943920 56634736 16502848
02:15:22 3474 8192000000 9954461328 10309013504 987645200 10.759 100 82 162 0 1068421680 64501696 16274784
02:16:27 2828 8192000000 9991733280 10358411264 1020218224 11.074 100 77 154 1 1105184800 68848480 16118096
02:17:32 4824 8192000000 9998494496 10363179008 1038307376 11.248 100 79 158 1 1114851408 61206144 15337888
02:18:39 941 8192000000 10050150464 10420305920 1060207024 11.458 100 95 190 0 1169339680 90050544 19082112
02:19:40 17997 8192000000 9971786720 10357751808 1067745824 11.531 100 40 80 1 1097017792 20529536 8742432
02:21:00 6030 8192000000 9996298640 10390491136 1081720528 11.664 100 41 82 1 1118711824 28227008 8764288
02:22:08 6096 8192000000 9995512416 10389229568 1086098064 11.706 100 25 51 0 1109759904 18056224 5605616
02:23:17 66609 8192000000 10036455104 10421997568 1102116400 11.858 100 50 101 1 1130162224 17499472 10546352
02:25:24 4167 8192000000 10057655296 10453852160 1106020800 11.895 100 52 101 2 1168831840 52520400 10290640
02:26:31 9005 8192000000 10021361200 10438750208 1104778384 11.883 100 32 63 3 1133545824 21505408 7262032
02:27:41 7035 8192000000 10002350848 10406547456 1084113024 11.687 100 41 82 1 1111910528 18633648 9163856
02:28:49 33060 8192000000 9947560160 10349105152 972437984 10.610 89 20 40 0 996232960 19955440 3839536
02:30:22 5971 8192000000 9953337808 10334760960 1007466624 10.951 97 46 90 0 1034567680 16541712 10559344
02:31:30 7513 8192000000 9933521680 10312540160 988433152 10.766 96 26 51 2 1032163360 38205424 5524784
02:32:38 72897 8192000000 9961017296 10361339904 1025093920 11.121 99 33 65 1 1061814208 28867232 7853056
02:34:52 4643 8192000000 9974115824 10366832640 1050167168 11.362 100 55 111 1 1098242624 36042864 12032592
02:35:59 7961 8192000000 9976546896 10370936832 1048833296 11.349 99 35 70 1 1073522032 16873792 7814944
02:37:08 6594 8192000000 9994613200 10381660160 1079860352 11.646 100 49 97 2 1105913248 14397360 11655536
02:38:20 39106 8192000000 10024626400 10424299520 1092175376 11.763 100 53 106 2 1134058288 31118960 10763952
02:40:04 11774 8192000000 10059622864 10452389888 1112166592 11.953 100 55 110 2 1168785680 44148736 12470352
02:41:19 27719 8192000000 10095637088 10501746688 1124089968 12.066 100 52 104 0 1201949424 67329536 10529920
02:42:56 13589 8192000000 10171283792 10570039296 1134528272 12.164 100 60 120 3 1274536336 127323680 12684384
02:44:13 45623 8192000000 10153906880 10580111360 1146449472 12.276 100 46 90 3 1276217232 119576096 10191664
02:46:12 12622 8192000000 10196095536 10614394880 1155590672 12.362 100 53 105 0 1323044464 156312432 11141360
02:47:30 4366 8192000000 10226872960 10635476992 1167276000 12.471 100 58 116 0 1328608576 148479296 12853280
02:48:39 2169 8192000000 10277772352 10675904512 1165923984 12.459 99 79 158 2 1374435616 190924464 17587168
02:49:45 1684 8192000000 10301559872 10704306176 1162860784 12.430 99 75 150 1 1393926416 214292480 16773152
02:50:55 2211 8192000000 10360764400 10766139392 1183393888 12.622 100 81 162 1 1463654144 261713264 18546992
02:52:03 2815 8192000000 10380308304 10787692544 1187180032 12.657 100 80 159 2 1476625744 271523888 17921824
02:53:12 2671 8192000000 10387785968 10790498304 1163718768 12.438 100 84 167 2 1483584464 300512128 19353568
02:54:19 2992 8192000000 10389383216 10795323392 1157442560 12.379 100 78 153 1 1498094608 323230560 17421488
02:55:28 1296 8192000000 10374216320 10775515136 1141240368 12.227 100 87 176 0 1474780080 312861072 20678640
02:56:37 18434 8192000000 10291594032 10725621760 1111775152 11.949 98 37 74 0 1382901744 261489968 9636624
02:57:59 11612 8192000000 10364709344 10785947648 1148826912 12.298 100 45 90 1 1461441248 301743584 10870752
02:59:15 3988 8192000000 10422162064 10818191360 1129380096 12.116 100 90 180 1 1512731488 362004064 21347328
03:00:26 5760 8192000000 10379326640 10798268416 1133568896 12.155 100 59 117 0 1481625296 333414512 14641888
03:01:36 17338 8192000000 10250127952 10743762944 1117098880 12.000 99 23 46 0 1341206112 217826208 6281024
03:02:55 16006 8192000000 10226076544 10682048512 1112068992 11.952 99 26 52 1 1342102672 223489904 6543776
03:04:13 20252 8192000000 10208141264 10654261248 1120206112 12.029 100 13 26 0 1293699552 170206336 3287104
03:05:41 16667 8192000000 10201574752 10660429824 1120174752 12.029 100 29 54 4 1290533936 163278096 7081088
03:06:59 71788 8192000000 10105255232 10533974016 1130495472 12.126 99 29 58 2 1193178832 57345888 5337472
03:09:18 101052 8192000000 10085414144 10508865536 1123820192 12.063 98 41 82 0 1197199344 65195856 8183296
03:12:02 28352 8192000000 10168333424 10612011008 1151660288 12.325 100 37 73 0 1256668384 97340304 7667792
03:13:45 22145 8192000000 10145074096 10593165312 1145807776 12.270 100 18 36 0 1256620272 106924368 3888128
03:15:20 71377 8192000000 10172841344 10623291392 1152993888 12.338 100 12 24 0 1243957728 88138240 2825600
03:17:33 26757 8192000000 10198963840 10674909184 1158415776 12.388 100 19 38 1 1291781520 128773152 4592592
03:19:03 74842 8192000000 10217648144 10701996032 1161157408 12.414 100 15 30 0 1334546224 169391408 3997408
03:21:20 96246 8192000000 10108170784 10543226880 1156363440 12.369 100 18 36 0 1208970800 48833520 3773840
03:24:05 58571 8192000000 10101729216 10548490240 1146464752 12.276 99 16 32 1 1231805344 81405680 3934912
03:26:10 48180 8192000000 10082259248 10540625920 1158930496 12.393 100 35 70 0 1200724448 34329328 7464624
03:28:08 83564 8192000000 10067871088 10534350848 1149633184 12.306 100 9 18 2 1200731984 49301184 1797616
03:30:39 76670 8192000000 10047812944 10513977344 1123530256 12.060 98 10 20 0 1154831744 28723680 2577808
03:33:25 63994 8192000000 10137931680 10616823808 1158528304 12.389 100 13 26 1 1231309392 69905760 2875328
03:35:30 44519 8192000000 10096605664 10582028288 1121114832 12.038 97 21 42 0 1195698304 70172960 4410512
03:37:22 60248 8192000000 10103454736 10576117760 1089610544 11.739 95 15 30 0 1167058512 74211728 3236240
03:39:23 65081 8192000000 10113991648 10606518272 1132620800 12.146 99 18 36 0 1224980464 88954928 3404736
03:41:33 68375 8192000000 10069348464 10518937600 1088232032 11.726 95 35 70 0 1157166544 62101376 6833136
03:43:45 8406 8192000000 10072208912 10500087808 1120863040 12.035 99 51 101 2 1152095216 20035008 11197168
03:44:56 44365 8192000000 10177690928 10632994816 1138459840 12.201 100 31 62 0 1233918912 89308336 6150736
03:46:42 75285 8192000000 10079709264 10557689856 1076879904 11.618 95 20 40 0 1148174464 67043584 4250976
03:48:58 81582 8192000000 10048959024 10483466240 1089628592 11.739 97 25 50 1 1127645008 32013248 6003168
03:51:20 13185 8192000000 10068917408 10494062592 1107065088 11.905 100 54 108 0 1160008704 41980800 10962816
03:52:33 51173 8192000000 10027154224 10487889920 1092132944 11.763 98 19 38 0 1138856096 42679552 4043600
03:54:27 61862 8192000000 10021966688 10484465664 1044022352 11.303 94 9 18 0 1079880768 34074928 1783488
03:56:39 33716 8192000000 10062413648 10499883008 1100827648 11.845 99 24 48 0 1171048976 64662208 5559120
03:58:15 70506 8192000000 10075056832 10552836096 1099266352 11.831 99 14 27 1 1188507152 85940576 3300224
04:00:33 93126 8192000000 10036510912 10521624576 1064169184 11.496 96 24 48 0 1137045120 67373168 5502768
04:03:07 55849 8192000000 10071176512 10525974528 1085268864 11.698 97 31 61 2 1137542032 45562592 6710576
04:05:08 46915 8192000000 10066665728 10495610880 1103260864 11.869 99 19 38 0 1132732192 25519728 3951600
04:06:56 38727 8192000000 10104788816 10541129728 1107519264 11.909 99 39 78 0 1194405856 79149792 7736800
04:08:35 38521 8192000000 10087084736 10518519808 1109295488 11.926 98 32 64 0 1166146672 50641024 6210160
04:10:19 127575 8192000000 10071882288 10551349248 1115854832 11.988 99 14 28 0 1173951296 54994608 3101856
04:13:41 83698 8192000000 10087967520 10572709888 1124294016 12.068 99 12 22 0 1184743360 57966720 2482624
04:16:04 100947 8192000000 10078135472 10535583744 1144576352 12.259 100 20 38 2 1174418720 25771840 4070528
04:18:49 27497 8192000000 10079645280 10515890176 1129987984 12.121 98 45 89 0 1178945632 38895728 10061920
04:20:17 78479 8192000000 10108023536 10576207872 1108440496 11.918 97 18 36 2 1168454032 55966448 4047088
04:22:56 153024 8192000000 10071814656 10556080128 1096215216 11.802 96 12 24 0 1144408448 45703952 2489280
04:26:31 46667 8192000000 10050573744 10493640704 1106916064 11.903 98 19 37 1 1160656720 49505808 4234848
04:28:19 137666 8192000000 10109475488 10556018688 1127226128 12.095 99 35 70 1 1236083536 101443872 7413536
04:31:43 34330 8192000000 10077670352 10561179648 1129968240 12.121 99 13 26 1 1188145328 55172496 3004592
04:33:18 35341 8192000000 10073293312 10568232960 1137664048 12.194 100 5 10 0 1186943712 48170000 1109664
04:34:55 59488 8192000000 10070692544 10604085248 1113708496 11.968 98 15 30 1 1219691904 102705680 3277728
04:36:59 23610 8192000000 9196202976 9517600768 342549216 4.013 31 2 4 0 373412272 30551040 312016

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-12-2021 04:38:44 to 07-12-2021 04:38:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 87 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-12-2021 04:38:59 to 07-12-2021 04:39:01.

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          225535
MON$OLDEST_ACTIVE               225536
MON$OLDEST_SNAPSHOT             225536
MON$NEXT_TRANSACTION            225536
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                       2052640
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             780
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2675 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2675 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2675 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2675 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 ---
:137: 
:138: mscs-test-161.budzdorov.ru    Tue Dec  7 04:37:24 2021
:139:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:140: 
--- end of diff output ---

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

07.12.21 04:39:02. 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-12-07 04:39:05 No severe PSQL-related problems occured
07.12.21 04:39:06. End of report.