Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 24.09.2021 10: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 15874556 kB
MemAvailable 30818348 kB
Buffers 0 kB
Cached 15853436 kB
SwapCached 1628 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-24 10:37:07
DTS_END                         2021-09-24 10:37:07.6280
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/41742
TRN_ID                          244693
ATT_ID                          675
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: 9190.01 18191 1102801
customer order: creation 183.12 405 21974
customer order: refuse 15.47 319 1856
order to supplier: creation 48.83 1408 5859
order to supplier: removal 3.53 100196 424
invoice (draft): creation 49.02 3820 5882
invoice (draft): removal 50.88 955 6105
invoice accept: apply 43.33 19368 5200
invoice accept: cancel 44.87 889 5384
customer reserve: creation 4021.09 191 482531
customer reserve: removal 4429.32 63 531518
realization accept: apply 88.52 192 10622
realization accept: cancel 77.14 136 9257
payment from customer: creation 62.53 142 7503
payment from customer: removal 6.48 111 777
payment to supplier: creation 50.99 143 6119
payment to supplier: removal 3.48 80 417
service: total inventory turnovers 4.03 3358 483
service: total monetary turnovers 6.48 499 778
service: aggregate perf. data 0.89 15561 107
service: refresh index statistics 0.04 215990 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 24-09-2021 10:40:03 to 24-09-2021 10:40:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8249
TEST_TIME 2 5557
TEST_TIME 3 5581
TEST_TIME 4 6417
TEST_TIME 5 5788
TEST_TIME 6 5835
TEST_TIME 7 6409
TEST_TIME 8 6513
TEST_TIME 9 6515
TEST_TIME 10 6502
TEST_TIME 11 6762
TEST_TIME 12 6901
TEST_TIME 13 6878
TEST_TIME 14 6876
TEST_TIME 15 7394
TEST_TIME 16 7595
TEST_TIME 17 8252
TEST_TIME 18 8312
TEST_TIME 19 8564
TEST_TIME 20 8654
TEST_TIME 21 8796
TEST_TIME 22 8800
TEST_TIME 23 8816
TEST_TIME 24 9540
TEST_TIME 25 10032
TEST_TIME 26 10171
TEST_TIME 27 11044
TEST_TIME 28 10962
TEST_TIME 29 11104
TEST_TIME 30 10840
TEST_TIME 31 10688
TEST_TIME 32 10685
TEST_TIME 33 10719
TEST_TIME 34 10553
TEST_TIME 35 10292
TEST_TIME 36 10174
TEST_TIME 37 9972
TEST_TIME 38 9903
TEST_TIME 39 9775
TEST_TIME 40 9764
TEST_TIME 41 9949
TEST_TIME 42 10016
TEST_TIME 43 9961
TEST_TIME 44 9846
TEST_TIME 45 9744
TEST_TIME 46 9594
TEST_TIME 47 9574
TEST_TIME 48 9459
TEST_TIME 49 9494
TEST_TIME 50 9387
TEST_TIME 51 9437
TEST_TIME 52 9394
TEST_TIME 53 9511
TEST_TIME 54 9608
TEST_TIME 55 9643
TEST_TIME 56 9805
TEST_TIME 57 9807
TEST_TIME 58 9835
TEST_TIME 59 9957
TEST_TIME 60 9902
TEST_TIME 61 9919
TEST_TIME 62 9842
TEST_TIME 63 9747
TEST_TIME 64 9749
TEST_TIME 65 9667
TEST_TIME 66 9619
TEST_TIME 67 9583
TEST_TIME 68 9589
TEST_TIME 69 9540
TEST_TIME 70 9505
TEST_TIME 71 9556
TEST_TIME 72 9547
TEST_TIME 73 9559
TEST_TIME 74 9530
TEST_TIME 75 9542
TEST_TIME 76 9626
TEST_TIME 77 9611
TEST_TIME 78 9612
TEST_TIME 79 9744
TEST_TIME 80 9722
TEST_TIME 81 9660
TEST_TIME 82 9573
TEST_TIME 83 9527
TEST_TIME 84 9497
TEST_TIME 85 9435
TEST_TIME 86 9412
TEST_TIME 87 9373
TEST_TIME 88 9353
TEST_TIME 89 9367
TEST_TIME 90 9371
TEST_TIME 91 9372
TEST_TIME 92 9367
TEST_TIME 93 9378
TEST_TIME 94 9346
TEST_TIME 95 9501
TEST_TIME 96 9480
TEST_TIME 97 9488
TEST_TIME 98 9456
TEST_TIME 99 9516
TEST_TIME 100 9449
TEST_TIME 101 9382
TEST_TIME 102 9354
TEST_TIME 103 9360
TEST_TIME 104 9348
TEST_TIME 105 9324
TEST_TIME 106 9327
TEST_TIME 107 9313
TEST_TIME 108 9336
TEST_TIME 109 9311
TEST_TIME 110 9396
TEST_TIME 111 9383
TEST_TIME 112 9368
TEST_TIME 113 9385
TEST_TIME 114 9398
TEST_TIME 115 9370
TEST_TIME 116 9359
TEST_TIME 117 9351
TEST_TIME 118 9283
TEST_TIME 119 9271
TEST_TIME 120 9194

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 24-09-2021 10:40:07 to 24-09-2021 10:40:10.

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 1548220 1548220 0 0.00 0 22641 7 0 0
doc_list_biud 1548218 1548218 0 0.00 0 27091 28 0 0
sp_add_invoice_to_stock 5300 5200 100 1.88 44 219042 19369 0 100
sp_cancel_adding_invoice 5384 5384 0 0.00 5 75188 890 0 0
sp_cancel_client_order 1856 1856 0 0.00 4 9474 319 0 0
sp_cancel_customer_reserve 531518 531518 0 0.00 1 22960 63 0 0
sp_cancel_pay_from_customer 777 777 0 0.00 3 6630 112 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 7881 80 0 0
sp_cancel_supplier_invoice 6116 6105 11 0.17 2 27094 955 0 11
sp_cancel_supplier_order 424 424 0 0.00 6 1080804 100196 0 0
sp_cancel_write_off 9257 9257 0 0.00 1 25569 137 0 0
sp_client_order 21974 21974 0 0.00 4 27276 406 0 0
sp_customer_reserve 482531 482531 0 0.00 0 45445 191 0 0
sp_fill_shopping_cart 33697 33697 0 0.00 1 27695 43 0 0
sp_fill_shopping_cart_clo_res 482523 482523 0 0.00 0 21438 17 0 0
sp_get_clo_for_invoice 5192 5192 0 0.00 0 23537 378 0 0
sp_kill_cost_storno 15835 15835 0 0.00 0 7847 59 0 0
sp_kill_qstorno_ret_qs2qd 1078822 1078822 0 0.00 0 27089 35 0 0
sp_kill_qty_storno 570358 570358 0 0.00 0 27090 75 0 0
sp_lock_selected_doc 21932 21932 0 0.00 0 10395 7 0 0
sp_make_cost_storno 29436 29436 0 0.00 0 13515 62 0 0
sp_multiply_rows_for_pdistr 29436 29436 0 0.00 0 12349 36 0 0
sp_multiply_rows_for_qdistr 21974 21974 0 0.00 0 13238 219 0 0
sp_pay_from_customer 7503 7503 0 0.00 4 21318 142 0 0
sp_pay_to_supplier 6119 6119 0 0.00 5 20020 143 0 0
sp_payment_common 13622 13622 0 0.00 1 21059 41 0 0
sp_qd_handle_on_cancel_clo 1856 1856 0 0.00 0 7995 283 0 0
sp_qd_handle_on_invoice_upd_sts 10576 10576 0 0.00 0 17945 349 0 0
sp_qd_handle_on_reserve_upd_sts 10622 10622 0 0.00 0 18208 50 0 0
sp_reserve_write_off 10622 10622 0 0.00 7 21934 192 0 0
sp_supplier_invoice 5882 5882 0 0.00 7 102666 3821 0 0
sp_supplier_order 5859 5859 0 0.00 5 40736 1408 0 0
srv_aggregate_perf_data 108 107 1 0.92 3128 117969 15562 0 1
srv_fill_mon_cache_memory 66 66 0 0.00 1268 105102 42524 0 0
srv_find_qd_qs_mism 1051889 1051889 0 0.00 0 22641 7 0 0
srv_make_invnt_saldo 483 483 0 0.00 67 97005 3359 0 0
srv_make_money_saldo 778 778 0 0.00 1 11692 500 0 0
srv_recalc_idx_stat 5 5 0 0.00 118375 269639 215990 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5200 5200 0 0.00 0 23804 147 0 0
t$perf-norm:sp_cancel_adding_invoice 409 409 0 0.00 0 8236 103 0 0
t$perf-norm:sp_cancel_client_order 1856 1856 0 0.00 0 1147 9 0 0
t$perf-norm:sp_cancel_customer_reserve 757 757 0 0.00 0 1104 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 777 777 0 0.00 0 4803 21 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 5319 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 476 476 0 0.00 0 1265 18 0 0
t$perf-norm:sp_cancel_supplier_order 424 424 0 0.00 0 14308 1260 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 2786 6 0 0
t$perf-norm:sp_client_order 21974 21974 0 0.00 0 8319 18 0 0
t$perf-norm:sp_customer_reserve 1040 1040 0 0.00 0 2239 8 0 0
t$perf-norm:sp_pay_from_customer 7503 7503 0 0.00 0 8389 10 0 0
t$perf-norm:sp_pay_to_supplier 6119 6119 0 0.00 0 8174 10 0 0
t$perf-norm:sp_reserve_write_off 10622 10622 0 0.00 0 10110 10 0 0
t$perf-norm:sp_supplier_invoice 5882 5882 0 0.00 0 8510 16 0 0
t$perf-norm:sp_supplier_order 5859 5859 0 0.00 0 4200 13 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 821 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 78 2 0 0
t$perf-norm:srv_make_invnt_saldo 483 483 0 0.00 0 1383 11 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 1054 8 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10580 10380 200 1.89 0 4950 21 0 200
v_all_customers 39734 39734 0 0.00 0 39 1 0 0
v_all_suppliers 32012 32012 0 0.00 0 15 1 0 0
v_all_wares 43946 43946 0 0.00 0 76 1 0 0
v_cancel_adding_invoice 818 818 0 0.00 0 2176 16 0 0
v_cancel_client_order 3712 3712 0 0.00 0 4481 7 0 0
v_cancel_customer_prepayment 1554 1554 0 0.00 0 2158 9 0 0
v_cancel_customer_reserve 1514 1514 0 0.00 0 1791 6 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 4701 14 0 0
v_cancel_supplier_invoice 974 952 22 2.25 0 1640 14 0 22
v_cancel_supplier_order 848 848 0 0.00 0 4851 13 0 0
v_cancel_write_off 2006 2006 0 0.00 0 3603 22 0 0
v_max_id_clo_ord 5855 5855 0 0.00 0 2847 2 0 0
v_max_id_clo_res 1040 1040 0 0.00 0 1591 13 0 0
v_max_id_ord_sup 5868 5868 0 0.00 0 5069 5 0 0
v_max_non_paid_invoice 1836 1836 0 0.00 0 8485 7 0 0
v_max_non_paid_realizn 4179 4179 0 0.00 0 7184 6 0 0
v_min_id_clo_ord 5855 5855 0 0.00 0 1325 4 0 0
v_min_id_clo_res 1040 1040 0 0.00 0 8221 14 0 0
v_min_id_ord_sup 5868 5868 0 0.00 0 7328 9 0 0
v_min_non_paid_invoice 6119 6119 0 0.00 0 8163 26 0 0
v_min_non_paid_realizn 7503 7503 0 0.00 0 11069 33 0 0
v_our_firm 10860 10860 0 0.00 0 17 1 0 0
v_reserve_write_off 21244 21244 0 0.00 0 4791 6 0 0
x_lock_depdocs_on_canc_invoice 11013 11013 0 0.00 0 9656 95 0 0
x_lock_depdocs_on_canc_sup_ord 424 424 0 0.00 0 8276 296 0 0
x_make_qstorno_1000_1200 5859 5859 0 0.00 2 39394 1336 0 0
x_make_qstorno_1200_2000 5882 5882 0 0.00 3 102649 3648 0 0
x_make_qstorno_2100_3300 482531 482531 0 0.00 0 45445 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 24-09-2021 10:40:12 to 24-09-2021 10:40:16.

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
08:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:12 32500 8192000000 9735103504 10035187712 801239392 8.909 100 82 164 0 829485664 8154608 20091664
08:09:49 26847 8192000000 9782422640 10094153728 850683184 9.407 100 80 160 0 883277792 13355456 19239152
08:11:29 16704 8192000000 9820610464 10145632256 883399024 9.733 100 88 176 1 929426320 26096192 19931104
08:12:50 11336 8192000000 9878038448 10215964672 923695760 10.133 100 83 166 2 986129808 44350704 18083344
08:14:08 9161 8192000000 9935965008 10285965312 965343264 10.541 100 77 153 1 1044261856 63377200 15541392
08:15:19 4536 8192000000 9953794112 10310696960 994235376 10.823 100 72 144 0 1070150160 61306896 14607888
08:16:27 2281 8192000000 10013377392 10376585216 1033293024 11.200 100 90 180 1 1125613296 74201104 18119168
08:17:31 1651 8192000000 10019746992 10389696512 1054977136 11.408 100 83 165 2 1125995824 53898032 17120656
08:18:35 22059 8192000000 10016020112 10398781440 1061205200 11.468 100 72 143 2 1137259376 60452656 15601520
08:19:58 4342 8192000000 10012066592 10391719936 1067927472 11.532 100 54 109 2 1117134944 37740624 11466848
08:21:05 4636 8192000000 10006121296 10404204544 1075713840 11.607 100 38 77 4 1108320576 24279072 8327664
08:22:10 10095 8192000000 9992835696 10408112128 1082945008 11.676 100 18 36 0 1115616000 29071424 3599568
08:23:25 5760 8192000000 9999772848 10412965888 1083705792 11.683 100 33 66 2 1112710096 21940032 7064272
08:24:32 5165 8192000000 10016806368 10407956480 1085748496 11.702 100 46 92 1 1128477504 32591840 10137168
08:25:39 54924 8192000000 10018798640 10439217152 1078578688 11.634 99 43 87 0 1099456832 11669440 9208704
08:27:41 4691 8192000000 9993864112 10388791296 1079899520 11.647 100 59 115 3 1108115584 15270912 12945152
08:28:46 6575 8192000000 9939888448 10327461888 996923840 10.849 94 44 87 3 1015309520 8184752 10200928
08:29:54 9400 8192000000 9969926320 10353606656 1031869648 11.186 100 41 80 1 1073433440 33514608 8049184
08:31:07 21514 8192000000 9920896416 10302599168 981204336 10.696 95 17 33 2 1006090560 21429856 3456368
08:32:31 9773 8192000000 9947187520 10333261824 1008945440 10.965 97 38 76 0 1054895376 37361584 8588352
08:33:41 7359 8192000000 9976764608 10374098944 1045837264 11.321 100 47 94 1 1073222032 16761872 10622896
08:34:50 76157 8192000000 9968879408 10377408512 1061886960 11.475 100 34 67 0 1093362368 22359168 9116240
08:37:11 26270 8192000000 10009025744 10406207488 1091506560 11.757 100 42 84 1 1122880992 22211072 9163360
08:38:39 40741 8192000000 10067675520 10469937152 1112775520 11.959 100 50 100 0 1170705456 48144480 9785456
08:40:25 74869 8192000000 10101131168 10523656192 1129864768 12.120 100 38 76 1 1233319328 94730400 8724160
08:42:48 13692 8192000000 10139082352 10549661696 1145414640 12.266 100 49 95 2 1242392224 86037920 10939664
08:44:05 11379 8192000000 10154690608 10568445952 1153350240 12.341 100 56 110 1 1252796384 87567920 11878224
08:45:22 5261 8192000000 10180615664 10584522752 1159143968 12.395 100 69 134 2 1287615328 114373504 14097856
08:46:34 9172 8192000000 10194620608 10610356224 1173416960 12.529 100 67 133 0 1297173280 109698512 14057808
08:47:49 6220 8192000000 10245276128 10658783232 1173965008 12.534 100 68 132 0 1344829936 156704912 14160016
08:49:04 1267 8192000000 10244628608 10661560320 1186269584 12.649 100 72 146 1 1348818240 146291232 16257424
08:50:08 4764 8192000000 10226276304 10635091968 1143720384 12.251 99 63 123 0 1336236944 178269296 14247264
08:51:18 12379 8192000000 10310913520 10731298816 1149788384 12.308 99 56 112 1 1394499936 231520080 13191472
08:52:37 7738 8192000000 10308568128 10726805504 1153060080 12.338 99 59 115 0 1408050272 241389104 13601088
08:53:49 5087 8192000000 10316758608 10723491840 1157089024 12.376 100 68 134 0 1410335088 237743792 15502272
08:54:58 7857 8192000000 10289565344 10722541568 1152160640 12.330 100 40 80 0 1394090928 232185808 9744480
08:56:11 8686 8192000000 10311692368 10743181312 1141675296 12.231 100 38 76 1 1429472288 278051136 9745856
08:57:30 1306 8192000000 10348921264 10745491456 1148810432 12.298 100 86 170 2 1440290400 271787232 19692736
08:58:33 12098 8192000000 10336406704 10760589312 1144109600 12.254 100 39 78 0 1431457792 276660304 10687888
08:59:47 8666 8192000000 10389087616 10822778880 1136794304 12.185 99 43 86 2 1480586416 332489616 11302496
09:01:03 14675 8192000000 10305778448 10832424960 1146863040 12.280 100 34 69 0 1467769776 311519728 9387008
09:02:20 44294 8192000000 10248058832 10730283008 1125527088 12.079 99 21 39 0 1358766736 228114560 5125088
09:04:14 90216 8192000000 10042693792 10509230080 1057006240 11.428 95 13 26 1 1196374784 136274128 3094416
09:06:45 67572 8192000000 10042781312 10479529984 1036151408 11.228 94 14 29 0 1074090160 34628848 3309904
09:08:54 48619 8192000000 10052170192 10491146240 1094056240 11.781 98 24 48 1 1138130800 39232304 4842256
09:10:44 58417 8192000000 10039925728 10471813120 1107664128 11.910 99 19 38 1 1145898208 33044144 5189936
09:12:43 60804 8192000000 10114444256 10548895744 1120742400 12.034 100 45 91 0 1180330080 50250032 9337648
09:14:45 4600 8192000000 10083292624 10514173952 1120619696 12.033 99 32 65 2 1182070576 54331664 7119216
09:15:52 48203 8192000000 10173490928 10621480960 1140501328 12.220 100 36 72 2 1250282016 100851008 8929680
09:17:51 57091 8192000000 10100972128 10584129536 1140601744 12.221 100 15 30 0 1263005904 118434672 3969488
09:19:51 47791 8192000000 10071008832 10534010880 1114594896 11.976 98 23 46 1 1150779856 31045152 5139808
09:21:39 40184 8192000000 10071893008 10528628736 1119678496 12.024 98 18 37 2 1144235344 20206432 4350416
09:24:38 35619 8192000000 10078949248 10545033216 1142054608 12.235 100 11 22 0 1171052992 26686368 2312016
09:26:27 44551 8192000000 10075797872 10545627136 1123815424 12.063 98 8 16 1 1156278752 30038000 2425328
09:28:23 61888 8192000000 10155754192 10607198208 1121050544 12.037 97 16 32 1 1217862352 93265808 3546000
09:30:26 46797 8192000000 10116150848 10591514624 1103608352 11.872 96 16 32 1 1248864304 141052608 4203344
09:32:32 73730 8192000000 10132270720 10600804352 1126516784 12.089 99 19 38 0 1241529104 110371232 4641088
09:34:49 58937 8192000000 10155495408 10621698048 1129865424 12.120 98 18 36 0 1214557568 81124176 3567968
09:36:48 46343 8192000000 10108541184 10544705536 1099448144 11.832 97 45 90 0 1134044768 25296704 9299920
09:38:39 75336 8192000000 10078484416 10537668608 1111677776 11.948 99 41 82 2 1164252544 44632144 7942624
09:41:10 27247 8192000000 10107273120 10550927360 1112469664 11.956 98 44 88 0 1177240992 55399760 9371568
09:43:00 37768 8192000000 10052755056 10546524160 1108665408 11.920 99 3 6 0 1151129088 42188352 275328
09:44:49 35771 8192000000 10056375744 10519449600 1124809872 12.072 100 32 65 1 1167647936 36819520 6018544
09:46:26 19685 8192000000 10052685744 10493853696 1089947520 11.742 97 22 44 0 1132212064 37273728 4990816
09:47:51 20716 8192000000 10091516752 10511892480 1077483568 11.623 98 49 100 0 1176263184 88086240 10693376
09:49:17 52785 8192000000 10044235184 10481872896 1068315328 11.536 97 24 46 1 1112541232 39770544 4455360
09:51:10 32806 8192000000 10094622352 10537320448 1104413008 11.879 100 29 58 0 1179373456 68769536 6190912
09:52:45 85281 8192000000 10029339696 10513563648 1080059328 11.648 97 24 48 0 1159797120 74560768 5177024
09:55:11 72491 8192000000 10122156016 10616406016 1096074576 11.800 98 14 28 0 1200440336 101230944 3134816
09:57:27 93044 8192000000 10046401248 10499506176 1096657568 11.806 99 37 73 0 1126445072 22616304 7171200
10:00:07 59105 8192000000 10033742544 10489610240 1079847072 11.646 97 23 46 0 1121966816 37142544 4977200
10:02:14 71814 8192000000 10029961824 10500026368 1099901616 11.837 99 13 26 0 1143179808 40622544 2655648
10:04:28 40074 8192000000 10042892448 10505388032 1090608416 11.748 99 10 20 0 1113129152 20527152 1993584
10:06:11 50341 8192000000 10058209792 10535591936 1111459536 11.946 100 26 51 0 1157828208 40954400 5414272
10:08:02 26537 8192000000 10081270416 10525196288 1114877616 11.979 99 29 58 0 1177209040 56329024 6002400
10:09:40 59307 8192000000 10114156304 10567983104 1127103616 12.094 99 23 46 1 1190856464 59193888 4558960
10:11:56 87970 8192000000 10075423456 10528694272 1112916320 11.960 97 17 34 0 1173582960 57055536 3611104
10:14:26 28984 8192000000 10074705184 10518859776 1115114768 11.981 98 42 84 0 1157792928 34037888 8640272
10:15:58 105102 8192000000 10089507824 10539384832 1089683792 11.740 95 28 56 0 1129379008 33358976 6336240
10:19:28 55184 8192000000 10124503744 10634043392 1128746944 12.110 99 11 20 1 1232361248 100809840 2804464
10:21:39 21948 8192000000 10068761424 10535276544 1140097504 12.216 100 20 40 1 1205933488 60460864 5375120
10:23:01 62121 8192000000 10110402016 10577162240 1149578912 12.306 100 21 42 1 1202079152 47938592 4561648
10:25:07 65804 8192000000 10106985056 10556379136 1130353312 12.125 98 14 28 0 1214125136 80708512 3063312
10:27:28 81713 8192000000 10075394176 10532700160 1110432032 11.937 97 13 26 0 1170177984 57004656 2741296
10:29:52 63395 8192000000 10059824624 10514837504 1094873136 11.789 96 22 44 0 1152174048 52474464 4826448
10:31:59 46642 8192000000 10082712976 10538004480 1090469296 11.747 97 35 68 1 1142562448 45542112 6551040
10:33:48 38421 8192000000 10065951088 10542120960 1104275280 11.878 98 38 76 0 1187923184 75885392 7762512
10:36:01 81366 8192000000 9545380800 9934180352 655497216 7.408 58 15 30 0 707193712 48823888 2872608

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 24-09-2021 10:40:18 to 24-09-2021 10:40:33.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 100 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 24-09-2021 10:40:34 to 24-09-2021 10:40:36.

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          243789
MON$OLDEST_ACTIVE               244895
MON$OLDEST_SNAPSHOT             244895
MON$NEXT_TRANSACTION            244895
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                       2055776
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             816
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2618 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2618 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2618 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Fri Sep 24 10:38:07 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

24.09.21 10:40:37. 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-24 10:40:39 No severe PSQL-related problems occured
24.09.21 10:40:40. End of report.