Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.11.2021 07:37
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 16548284 kB
MemAvailable 31611156 kB
Buffers 0 kB
Cached 15113524 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-11-14 07:37:05
DTS_END                         2021-11-14 07:37:05.7440
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39622
TRN_ID                          228355
ATT_ID                          649
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: 9373.03 27524 1124763
customer order: creation 158.32 477 18998
customer order: refuse 14.00 348 1680
order to supplier: creation 42.55 1498 5106
order to supplier: removal 3.55 128247 426
invoice (draft): creation 41.95 3568 5034
invoice (draft): removal 56.93 896 6832
invoice accept: apply 38.17 18441 4581
invoice accept: cancel 51.46 766 6175
customer reserve: creation 3597.17 177 431660
customer reserve: removal 5089.93 70 610791
realization accept: apply 75.73 182 9088
realization accept: cancel 86.57 133 10388
payment from customer: creation 54.48 107 6537
payment from customer: removal 5.51 79 661
payment to supplier: creation 43.51 134 5221
payment to supplier: removal 3.18 80 381
service: total inventory turnovers 3.39 3967 407
service: total monetary turnovers 5.76 507 691
service: aggregate perf. data 0.85 14583 102
service: refresh index statistics 0.03 376213 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-11-2021 07:38:09 to 14-11-2021 07:38:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4002
TEST_TIME 2 4079
TEST_TIME 3 3391
TEST_TIME 4 3487
TEST_TIME 5 3465
TEST_TIME 6 4611
TEST_TIME 7 4945
TEST_TIME 8 5002
TEST_TIME 9 5006
TEST_TIME 10 5183
TEST_TIME 11 6061
TEST_TIME 12 6162
TEST_TIME 13 6585
TEST_TIME 14 6586
TEST_TIME 15 6657
TEST_TIME 16 6990
TEST_TIME 17 7079
TEST_TIME 18 7749
TEST_TIME 19 7979
TEST_TIME 20 7915
TEST_TIME 21 8554
TEST_TIME 22 9101
TEST_TIME 23 9537
TEST_TIME 24 9684
TEST_TIME 25 9545
TEST_TIME 26 10012
TEST_TIME 27 11774
TEST_TIME 28 12080
TEST_TIME 29 11937
TEST_TIME 30 11894
TEST_TIME 31 11778
TEST_TIME 32 11576
TEST_TIME 33 11532
TEST_TIME 34 11366
TEST_TIME 35 11055
TEST_TIME 36 10992
TEST_TIME 37 10841
TEST_TIME 38 10708
TEST_TIME 39 10680
TEST_TIME 40 10635
TEST_TIME 41 10556
TEST_TIME 42 10512
TEST_TIME 43 10466
TEST_TIME 44 10450
TEST_TIME 45 10325
TEST_TIME 46 10270
TEST_TIME 47 10178
TEST_TIME 48 10103
TEST_TIME 49 10135
TEST_TIME 50 10009
TEST_TIME 51 10096
TEST_TIME 52 10177
TEST_TIME 53 10046
TEST_TIME 54 10107
TEST_TIME 55 10094
TEST_TIME 56 10139
TEST_TIME 57 10066
TEST_TIME 58 10105
TEST_TIME 59 10098
TEST_TIME 60 10151
TEST_TIME 61 9999
TEST_TIME 62 9943
TEST_TIME 63 9896
TEST_TIME 64 9843
TEST_TIME 65 9758
TEST_TIME 66 9655
TEST_TIME 67 9605
TEST_TIME 68 9620
TEST_TIME 69 9655
TEST_TIME 70 9792
TEST_TIME 71 9741
TEST_TIME 72 9690
TEST_TIME 73 9730
TEST_TIME 74 9698
TEST_TIME 75 9729
TEST_TIME 76 9772
TEST_TIME 77 9875
TEST_TIME 78 9836
TEST_TIME 79 9831
TEST_TIME 80 9800
TEST_TIME 81 9760
TEST_TIME 82 9730
TEST_TIME 83 9659
TEST_TIME 84 9578
TEST_TIME 85 9542
TEST_TIME 86 9467
TEST_TIME 87 9447
TEST_TIME 88 9512
TEST_TIME 89 9489
TEST_TIME 90 9456
TEST_TIME 91 9460
TEST_TIME 92 9582
TEST_TIME 93 9601
TEST_TIME 94 9543
TEST_TIME 95 9651
TEST_TIME 96 9620
TEST_TIME 97 9590
TEST_TIME 98 9618
TEST_TIME 99 9665
TEST_TIME 100 9624
TEST_TIME 101 9531
TEST_TIME 102 9513
TEST_TIME 103 9495
TEST_TIME 104 9474
TEST_TIME 105 9433
TEST_TIME 106 9434
TEST_TIME 107 9379
TEST_TIME 108 9376
TEST_TIME 109 9345
TEST_TIME 110 9314
TEST_TIME 111 9277
TEST_TIME 112 9305
TEST_TIME 113 9370
TEST_TIME 114 9339
TEST_TIME 115 9390
TEST_TIME 116 9433
TEST_TIME 117 9427
TEST_TIME 118 9403
TEST_TIME 119 9421
TEST_TIME 120 9377

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 14-11-2021 07:38:12 to 14-11-2021 07:38:16.

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 1525621 1525621 0 0.00 0 15561 6 0 0
doc_list_biud 1525621 1525621 0 0.00 0 45836 35 0 0
sp_add_invoice_to_stock 4700 4581 119 2.53 37 180569 18442 0 119
sp_cancel_adding_invoice 6175 6175 0 0.00 5 50384 767 0 0
sp_cancel_client_order 1680 1680 0 0.00 5 16335 348 0 0
sp_cancel_customer_reserve 610791 610791 0 0.00 1 41071 71 0 0
sp_cancel_pay_from_customer 661 661 0 0.00 3 5857 79 0 0
sp_cancel_pay_to_supplier 381 381 0 0.00 3 7674 80 0 0
sp_cancel_supplier_invoice 6842 6832 10 0.14 2 32750 897 0 10
sp_cancel_supplier_order 426 426 0 0.00 6 1241702 128248 0 0
sp_cancel_write_off 10388 10388 0 0.00 1 13319 133 0 0
sp_client_order 18998 18998 0 0.00 4 35638 478 0 0
sp_customer_reserve 431660 431660 0 0.00 0 70910 178 0 0
sp_fill_shopping_cart 29136 29136 0 0.00 1 20756 45 0 0
sp_fill_shopping_cart_clo_res 431652 431652 0 0.00 0 17516 17 0 0
sp_get_clo_for_invoice 4573 4573 0 0.00 0 35500 402 0 0
sp_kill_cost_storno 17605 17605 0 0.00 0 12538 67 0 0
sp_kill_qstorno_ret_qs2qd 1239228 1239228 0 0.00 0 40901 38 0 0
sp_kill_qty_storno 649953 649953 0 0.00 0 45836 80 0 0
sp_lock_selected_doc 19108 19108 0 0.00 0 10985 7 0 0
sp_make_cost_storno 25419 25419 0 0.00 0 9417 54 0 0
sp_multiply_rows_for_pdistr 25419 25419 0 0.00 0 8000 29 0 0
sp_multiply_rows_for_qdistr 18998 18998 0 0.00 0 22247 246 0 0
sp_pay_from_customer 6537 6537 0 0.00 4 9474 107 0 0
sp_pay_to_supplier 5221 5221 0 0.00 5 10536 135 0 0
sp_payment_common 11758 11758 0 0.00 1 10502 28 0 0
sp_qd_handle_on_cancel_clo 1680 1680 0 0.00 0 16328 302 0 0
sp_qd_handle_on_invoice_upd_sts 10748 10748 0 0.00 0 45834 356 0 0
sp_qd_handle_on_reserve_upd_sts 9088 9088 0 0.00 0 16248 59 0 0
sp_reserve_write_off 9088 9088 0 0.00 7 16259 183 0 0
sp_supplier_invoice 5034 5034 0 0.00 6 144838 3569 0 0
sp_supplier_order 5106 5106 0 0.00 5 66384 1499 0 0
srv_aggregate_perf_data 102 102 0 0.00 2783 155870 14583 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 833 131614 46234 0 0
srv_find_qd_qs_mism 1081392 1081392 0 0.00 0 15561 5 0 0
srv_make_invnt_saldo 407 407 0 0.00 52 100311 3968 0 0
srv_make_money_saldo 691 691 0 0.00 1 21147 507 0 0
srv_recalc_idx_stat 4 4 0 0.00 334142 456341 376213 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4581 4581 0 0.00 0 10885 160 0 0
t$perf-norm:sp_cancel_adding_invoice 357 357 0 0.00 0 8592 96 0 0
t$perf-norm:sp_cancel_client_order 1680 1680 0 0.00 0 3096 12 0 0
t$perf-norm:sp_cancel_customer_reserve 636 636 0 0.00 0 1104 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 661 661 0 0.00 0 715 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 381 381 0 0.00 0 1619 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 379 379 0 0.00 0 1166 13 0 0
t$perf-norm:sp_cancel_supplier_order 426 426 0 0.00 0 21793 1491 0 0
t$perf-norm:sp_cancel_write_off 929 929 0 0.00 0 4707 17 0 0
t$perf-norm:sp_client_order 18998 18998 0 0.00 0 7243 16 0 0
t$perf-norm:sp_customer_reserve 936 936 0 0.00 0 11353 18 0 0
t$perf-norm:sp_pay_from_customer 6537 6537 0 0.00 0 4599 10 0 0
t$perf-norm:sp_pay_to_supplier 5221 5221 0 0.00 0 6060 10 0 0
t$perf-norm:sp_reserve_write_off 9088 9088 0 0.00 0 7911 9 0 0
t$perf-norm:sp_supplier_invoice 5034 5034 0 0.00 0 7466 20 0 0
t$perf-norm:sp_supplier_order 5106 5106 0 0.00 0 3862 17 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 202 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 407 407 0 0.00 0 990 12 0 0
t$perf-norm:srv_make_money_saldo 691 691 0 0.00 0 4426 24 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 9382 9144 238 2.53 0 6490 21 0 238
v_all_customers 34856 34856 0 0.00 0 132 1 0 0
v_all_suppliers 28148 28148 0 0.00 0 97 1 0 0
v_all_wares 37996 37996 0 0.00 0 74 1 0 0
v_cancel_adding_invoice 714 714 0 0.00 0 81 5 0 0
v_cancel_client_order 3360 3360 0 0.00 0 3952 7 0 0
v_cancel_customer_prepayment 1322 1322 0 0.00 0 1921 7 0 0
v_cancel_customer_reserve 1272 1272 0 0.00 0 1746 7 0 0
v_cancel_payment_to_supplier 762 762 0 0.00 0 2917 7 0 0
v_cancel_supplier_invoice 778 758 20 2.57 0 5351 25 0 20
v_cancel_supplier_order 852 852 0 0.00 0 3945 12 0 0
v_cancel_write_off 1858 1858 0 0.00 0 3713 27 0 0
v_max_id_clo_ord 5105 5105 0 0.00 0 709 1 0 0
v_max_id_clo_res 936 936 0 0.00 0 6367 20 0 0
v_max_id_ord_sup 5033 5033 0 0.00 0 5496 5 0 0
v_max_non_paid_invoice 1285 1285 0 0.00 0 1111 3 0 0
v_max_non_paid_realizn 3414 3414 0 0.00 0 1856 3 0 0
v_min_id_clo_ord 5105 5105 0 0.00 0 4364 7 0 0
v_min_id_clo_res 936 936 0 0.00 0 6019 23 0 0
v_min_id_ord_sup 5033 5033 0 0.00 0 3787 9 0 0
v_min_non_paid_invoice 5221 5221 0 0.00 0 10494 20 0 0
v_min_non_paid_realizn 6537 6537 0 0.00 0 9320 21 0 0
v_our_firm 9386 9386 0 0.00 0 15 1 0 0
v_reserve_write_off 18174 18174 0 0.00 0 4759 6 0 0
x_lock_depdocs_on_canc_invoice 12628 12628 0 0.00 0 14515 109 0 0
x_lock_depdocs_on_canc_sup_ord 426 426 0 0.00 0 20244 415 0 0
x_make_qstorno_1000_1200 5106 5106 0 0.00 2 66365 1419 0 0
x_make_qstorno_1200_2000 5034 5034 0 0.00 3 126478 3401 0 0
x_make_qstorno_2100_3300 431660 431660 0 0.00 0 70908 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-11-2021 07:38:17 to 14-11-2021 07:38:21.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:09 2 8192000000 8800787536 8812945408 6437744 0.078 2 2 3 0 6589712 90592 61376
05:08:11 9511 8192000000 9715791648 10013683712 798773120 8.884 100 85 170 0 827304224 7444800 21086304
05:09:28 26424 8192000000 9761333808 10068848640 832333408 9.223 100 89 179 0 866014208 12025632 21655168
05:11:00 22352 8192000000 9813877968 10133229568 871610816 9.616 100 84 168 0 914838192 23681680 19545696
05:12:29 13382 8192000000 9862947776 10199756800 916636960 10.063 100 74 146 3 972601504 39715872 16248672
05:13:47 8806 8192000000 9926066944 10272309248 961291584 10.502 100 77 154 0 1035805728 57681328 16832816
05:15:00 4748 8192000000 9954879968 10309128192 992567776 10.806 100 78 154 0 1069335488 61611360 15156352
05:16:06 3815 8192000000 9991415376 10354667520 1017001152 11.043 100 85 165 2 1106471984 72421840 17048992
05:17:12 1804 8192000000 10027233616 10395889664 1050275552 11.363 100 92 184 1 1143800432 74224176 19300704
05:18:15 6513 8192000000 10014538560 10406481920 1059086544 11.448 100 48 96 1 1134009776 65343264 9579968
05:19:23 5388 8192000000 10007440016 10396737536 1070214256 11.554 100 37 74 0 1120198048 42674464 7309328
05:20:30 67374 8192000000 10004370768 10397048832 1075762496 11.607 100 36 71 0 1102225744 19040784 7422464
05:22:38 2865 8192000000 10012641808 10402402304 1085338864 11.698 100 59 118 0 1119407664 21293632 12775168
05:23:41 8235 8192000000 10006654576 10413481984 1088210240 11.726 100 39 76 2 1107428752 10817040 8401472
05:24:51 17756 8192000000 10037080576 10439364608 1092793472 11.769 100 66 132 0 1140630032 33913648 13922912
05:26:14 4624 8192000000 10029284928 10435637248 1070175632 11.554 99 30 60 1 1132984960 57028016 5781312
05:27:20 5404 8192000000 10004451792 10397274112 1080083968 11.648 100 42 82 2 1110498912 21869024 8545920
05:28:26 5594 8192000000 9963094608 10345136128 1026677616 11.136 98 42 84 2 1066208704 30659968 8871120
05:29:33 18892 8192000000 9920315056 10307846144 933884608 10.233 90 20 40 2 963676016 25668480 4122928
05:30:52 6037 8192000000 9936931600 10317955072 1006530880 10.942 98 60 119 1 1049676016 29719168 13425968
05:32:00 7417 8192000000 9950087888 10338435072 1026291392 11.133 99 29 58 0 1059650256 26897152 6461712
05:33:10 57481 8192000000 9949285264 10357825536 1037266096 11.238 99 30 60 1 1065833664 22125984 6441584
05:35:08 5386 8192000000 9981425856 10376241152 1062139808 11.477 100 40 80 0 1094836480 23567328 9129344
05:36:15 7490 8192000000 9983031520 10385829888 1073733744 11.588 100 40 78 2 1112999424 29286080 9979600
05:37:27 21600 8192000000 10021559072 10411663360 1090684112 11.749 100 52 105 0 1122664384 20667824 11312448
05:38:56 131614 8192000000 10088764528 10502770688 1113302048 11.964 100 38 74 1 1197036464 74733584 9000832
05:42:51 3091 8192000000 10165205552 10568114176 1123400096 12.059 99 57 113 1 1266520016 130765920 12354000
05:44:08 7148 8192000000 10183692672 10591043584 1148638480 12.297 100 48 96 0 1285759056 127079328 10041248
05:45:17 7348 8192000000 10197628624 10609078272 1156890896 12.374 100 48 95 2 1312927552 145761856 10274800
05:46:36 5031 8192000000 10265069696 10673274880 1164190992 12.443 100 55 111 1 1360962560 184523504 12248064
05:47:43 9701 8192000000 10292709968 10717462528 1171723296 12.513 100 53 106 2 1402740416 217796208 13220912
05:49:05 9108 8192000000 10309397296 10711281664 1166874544 12.468 99 80 160 0 1400693376 216207648 17611184
05:50:18 3667 8192000000 10339437856 10749960192 1179998496 12.590 100 79 158 0 1460368240 262497008 17872736
05:51:26 3547 8192000000 10355832736 10762113024 1180420960 12.594 100 73 145 3 1455685008 259096208 16167840
05:52:37 831 8192000000 10434803216 10830950400 1182327216 12.612 100 93 184 0 1533315616 328653808 22334592
05:53:40 5647 8192000000 10443897856 10854014976 1162856992 12.430 99 56 112 0 1538648704 361861024 13930688
05:54:51 1036 8192000000 10536802400 10952736768 1173310896 12.528 100 59 118 0 1641363840 452891184 15161760
05:56:01 6669 8192000000 10476228976 10910490624 1162902512 12.430 99 60 120 0 1571948064 393239472 15806080
05:57:13 2383 8192000000 10532247584 10937769984 1182429776 12.613 100 74 148 0 1625889904 423885248 19574880
05:58:23 4592 8192000000 10578447952 10996690944 1185382640 12.640 100 58 116 2 1678129520 477760016 14986864
05:59:48 12823 8192000000 10486628064 10970071040 1177346448 12.565 100 38 76 1 1620555664 433442688 9766528
06:01:21 16402 8192000000 10449752720 10915971072 1176733728 12.560 100 29 58 0 1539671392 355491952 7445712
06:02:42 25837 8192000000 10566382480 11022180352 1151560496 12.324 100 30 60 0 1654321536 494455888 8305152
06:04:10 22053 8192000000 10312963904 10957017088 1149721872 12.307 100 24 48 1 1486905392 330641920 6541600
06:05:48 55483 8192000000 10078697328 10520047616 1103339696 11.869 97 13 26 1 1135167888 29068352 2759840
06:07:49 66421 8192000000 10080491744 10515296256 1088763680 11.731 95 20 41 0 1121946112 29664832 3517600
06:10:06 101536 8192000000 10071158528 10531700736 1095427408 11.794 96 19 38 0 1127056048 28012752 3615888
06:12:56 38537 8192000000 10039951744 10474647552 1092564688 11.767 97 48 94 2 1144487920 42105376 9817856
06:14:35 32270 8192000000 10082654288 10521833472 1128774768 12.110 100 37 73 1 1175233936 39037024 7422144
06:16:08 46185 8192000000 10127559040 10601181184 1149948048 12.309 100 36 73 1 1227149632 69432992 7768592
06:18:06 119123 8192000000 10120830432 10599276544 1107352976 11.907 96 19 38 0 1186320016 74768288 4198752
06:21:05 17493 8192000000 10133725536 10572435456 1151433872 12.323 100 34 69 0 1232456880 73557264 7465744
06:22:28 29344 8192000000 10095830672 10569056256 1148708304 12.297 100 9 16 1 1199980032 49960560 1311168
06:24:04 66968 8192000000 10094700160 10580348928 1150814448 12.317 100 11 22 0 1184766144 31875024 2076672
06:26:18 54582 8192000000 10068684416 10529468416 1143219920 12.246 99 12 24 1 1189334352 43551728 2562704
06:28:13 63085 8192000000 10089558672 10574110720 1114095088 11.971 97 16 31 1 1193371136 75852512 3423536
06:30:26 47371 8192000000 10097280960 10547785728 1132558368 12.145 99 34 68 2 1190248848 50638496 7051984
06:32:19 77102 8192000000 10130270832 10577043456 1149935872 12.309 100 28 54 1 1231367216 75779360 5651984
06:34:37 80512 8192000000 10088211552 10537279488 1107257568 11.906 96 23 46 0 1157820080 45663360 4899152
06:37:02 47401 8192000000 10104910688 10595110912 1137257952 12.190 99 18 36 0 1195036080 54510992 3267136
06:40:06 67347 8192000000 10108620384 10547691520 1152732256 12.335 100 37 74 0 1221139408 60685472 7721680
06:42:13 86013 8192000000 10163567760 10607681536 1120245360 12.029 97 21 42 0 1209024992 83761872 5017760
06:44:39 94132 8192000000 10166106720 10701770752 1127443168 12.097 98 13 26 0 1298257728 167539744 3274816
06:47:15 55804 8192000000 10098059616 10591174656 1134735728 12.166 99 17 33 1 1231042240 92397600 3908912
06:49:11 42651 8192000000 10136690112 10585313280 1117055408 11.999 98 26 51 0 1192302112 69683216 5563488
06:50:56 45650 8192000000 10141498960 10596188160 1135636752 12.174 99 24 48 2 1235117680 94604624 4876304
06:52:42 41730 8192000000 10172091120 10613547008 1121448624 12.041 98 24 48 1 1225843648 99513616 4881408
06:54:27 45372 8192000000 10079641504 10492661760 1078104304 11.629 96 45 90 1 1132188192 44857168 9226720
06:56:13 88246 8192000000 10045634608 10508242944 1097109056 11.810 98 25 50 0 1175388992 72977584 5302352
06:58:49 62424 8192000000 10021181904 10474541056 1083516864 11.681 97 17 34 0 1125735504 38909328 3309312
07:00:59 57454 8192000000 10043944912 10510843904 1108023760 11.914 99 9 19 0 1144478064 34699392 1754912
07:02:56 58957 8192000000 10087839504 10526085120 1114363904 11.974 99 11 22 1 1163900032 46971696 2564432
07:05:14 64062 8192000000 10073554608 10549374976 1123220096 12.057 100 16 32 0 1197486960 71041888 3224976
07:07:26 67410 8192000000 10173408464 10662359040 1150138704 12.311 100 26 52 0 1254738272 99260624 5338944
07:10:24 62417 8192000000 10087805408 10537832448 1099340720 11.831 96 15 30 1 1151786256 49788560 2656976
07:12:27 68917 8192000000 10058195120 10501197824 1114547824 11.975 98 7 14 0 1143700896 27466496 1686576
07:14:40 45736 8192000000 10100485856 10567823360 1099913088 11.837 97 34 68 1 1160043728 53310976 6819664
07:17:02 131010 8192000000 10098747344 10538295296 1130562080 12.127 99 33 66 1 1182814208 44828928 7423200
07:20:15 51789 8192000000 10107598400 10605662208 1123264736 12.058 98 14 27 2 1192026160 66035136 2726288
07:23:00 72223 8192000000 10066403744 10539741184 1122039904 12.046 99 10 20 0 1162657152 38753760 1863488
07:25:55 51829 8192000000 10043038992 10514386944 1102901776 11.865 97 10 20 0 1138712272 33911200 1899296
07:28:13 37973 8192000000 10122127280 10572496896 1129466528 12.116 99 40 80 1 1195275488 57468016 8340944
07:29:52 74698 8192000000 10082602368 10572746752 1090656256 11.749 96 14 28 1 1165281184 71142016 3482912
07:32:08 64675 8192000000 10103343920 10628870144 1136553216 12.183 100 16 32 0 1232923664 93262160 3108288
07:35:05 54719 8192000000 10113887248 10560688128 1112105296 11.952 98 21 42 1 1153107728 36159632 4842800
07:37:02 29671 8192000000 8938919264 9141153792 72358704 0.875 7 3 5 0 107961280 34923856 678720

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-11-2021 07:38:22 to 14-11-2021 07:38:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-11-2021 07:38:38 to 14-11-2021 07:38:40.

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          229137
MON$OLDEST_ACTIVE               229138
MON$OLDEST_SNAPSHOT             229138
MON$NEXT_TRANSACTION            229138
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                       2064608
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2660 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2660 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2660 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2660 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 ---
--- end of diff output ---

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

14.11.21 07:38:41. 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-11-14 07:38:43 No severe PSQL-related problems occured
14.11.21 07:38:44. End of report.