Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 15.12.2021 10: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 16295832 kB
MemAvailable 31481508 kB
Buffers 0 kB
Cached 15412400 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-15 10:37:08
DTS_END                         2021-12-15 10:37:08.0890
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/46652
TRN_ID                          232945
ATT_ID                          645
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: 9567.28 25966 1148074
customer order: creation 161.20 454 19344
customer order: refuse 14.10 309 1692
order to supplier: creation 44.85 1386 5382
order to supplier: removal 3.52 120800 422
invoice (draft): creation 43.75 3651 5250
invoice (draft): removal 56.95 886 6834
invoice accept: apply 40.02 18378 4802
invoice accept: cancel 51.55 789 6186
customer reserve: creation 3757.48 177 450898
customer reserve: removal 5097.08 66 611650
realization accept: apply 79.63 190 9556
realization accept: cancel 94.33 121 11319
payment from customer: creation 55.81 146 6697
payment from customer: removal 5.30 87 636
payment to supplier: creation 48.16 149 5779
payment to supplier: removal 3.18 77 382
service: total inventory turnovers 3.68 3405 442
service: total monetary turnovers 5.88 511 705
service: aggregate perf. data 0.78 18158 94
service: refresh index statistics 0.03 349571 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 15-12-2021 10:39:08 to 15-12-2021 10:39:10.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5453
TEST_TIME 2 5569
TEST_TIME 3 5169
TEST_TIME 4 4913
TEST_TIME 5 4819
TEST_TIME 6 4560
TEST_TIME 7 4718
TEST_TIME 8 5525
TEST_TIME 9 5382
TEST_TIME 10 5443
TEST_TIME 11 5833
TEST_TIME 12 6135
TEST_TIME 13 6395
TEST_TIME 14 6703
TEST_TIME 15 6683
TEST_TIME 16 6844
TEST_TIME 17 7256
TEST_TIME 18 7755
TEST_TIME 19 8429
TEST_TIME 20 8367
TEST_TIME 21 8497
TEST_TIME 22 8432
TEST_TIME 23 8564
TEST_TIME 24 8843
TEST_TIME 25 9189
TEST_TIME 26 9557
TEST_TIME 27 9732
TEST_TIME 28 11025
TEST_TIME 29 11775
TEST_TIME 30 11606
TEST_TIME 31 11510
TEST_TIME 32 11430
TEST_TIME 33 11270
TEST_TIME 34 11115
TEST_TIME 35 11002
TEST_TIME 36 10716
TEST_TIME 37 10784
TEST_TIME 38 10680
TEST_TIME 39 10538
TEST_TIME 40 10563
TEST_TIME 41 10617
TEST_TIME 42 10592
TEST_TIME 43 10409
TEST_TIME 44 10430
TEST_TIME 45 10408
TEST_TIME 46 10295
TEST_TIME 47 10247
TEST_TIME 48 10178
TEST_TIME 49 10170
TEST_TIME 50 10454
TEST_TIME 51 10326
TEST_TIME 52 10395
TEST_TIME 53 10321
TEST_TIME 54 10444
TEST_TIME 55 10432
TEST_TIME 56 10449
TEST_TIME 57 10357
TEST_TIME 58 10232
TEST_TIME 59 10188
TEST_TIME 60 10111
TEST_TIME 61 10059
TEST_TIME 62 9998
TEST_TIME 63 9918
TEST_TIME 64 9826
TEST_TIME 65 9767
TEST_TIME 66 9955
TEST_TIME 67 9930
TEST_TIME 68 9852
TEST_TIME 69 9880
TEST_TIME 70 9895
TEST_TIME 71 9926
TEST_TIME 72 10089
TEST_TIME 73 10058
TEST_TIME 74 10063
TEST_TIME 75 10225
TEST_TIME 76 10169
TEST_TIME 77 10143
TEST_TIME 78 10033
TEST_TIME 79 9953
TEST_TIME 80 9953
TEST_TIME 81 9901
TEST_TIME 82 9856
TEST_TIME 83 9792
TEST_TIME 84 9714
TEST_TIME 85 9730
TEST_TIME 86 9731
TEST_TIME 87 9723
TEST_TIME 88 9744
TEST_TIME 89 9741
TEST_TIME 90 9756
TEST_TIME 91 9751
TEST_TIME 92 9767
TEST_TIME 93 9789
TEST_TIME 94 9838
TEST_TIME 95 9876
TEST_TIME 96 9863
TEST_TIME 97 9795
TEST_TIME 98 9769
TEST_TIME 99 9719
TEST_TIME 100 9656
TEST_TIME 101 9682
TEST_TIME 102 9654
TEST_TIME 103 9679
TEST_TIME 104 9688
TEST_TIME 105 9649
TEST_TIME 106 9635
TEST_TIME 107 9629
TEST_TIME 108 9706
TEST_TIME 109 9713
TEST_TIME 110 9695
TEST_TIME 111 9792
TEST_TIME 112 9758
TEST_TIME 113 9739
TEST_TIME 114 9801
TEST_TIME 115 9763
TEST_TIME 116 9694
TEST_TIME 117 9678
TEST_TIME 118 9643
TEST_TIME 119 9638
TEST_TIME 120 9572

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 15-12-2021 10:39:12 to 15-12-2021 10:39:15.

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 1567189 1567189 0 0.00 0 36201 6 0 0
doc_list_biud 1567189 1567189 0 0.00 0 88094 32 0 0
sp_add_invoice_to_stock 4905 4802 103 2.09 34 193205 18378 0 103
sp_cancel_adding_invoice 6186 6186 0 0.00 4 83279 789 0 0
sp_cancel_client_order 1692 1692 0 0.00 4 9139 309 0 0
sp_cancel_customer_reserve 611650 611650 0 0.00 1 24322 66 0 0
sp_cancel_pay_from_customer 636 636 0 0.00 3 5784 88 0 0
sp_cancel_pay_to_supplier 382 382 0 0.00 3 3398 78 0 0
sp_cancel_supplier_invoice 6840 6834 6 0.08 2 88102 887 0 6
sp_cancel_supplier_order 422 422 0 0.00 8 1101330 120800 0 0
sp_cancel_write_off 11319 11319 0 0.00 1 9653 121 0 0
sp_client_order 19344 19344 0 0.00 4 27352 455 0 0
sp_customer_reserve 450898 450898 0 0.00 0 129569 178 0 0
sp_fill_shopping_cart 29976 29976 0 0.00 1 19799 44 0 0
sp_fill_shopping_cart_clo_res 450887 450887 0 0.00 0 15376 17 0 0
sp_get_clo_for_invoice 4789 4789 0 0.00 0 34447 398 0 0
sp_kill_cost_storno 18523 18523 0 0.00 0 36191 57 0 0
sp_kill_qstorno_ret_qs2qd 1241875 1241875 0 0.00 0 88093 36 0 0
sp_kill_qty_storno 652448 652448 0 0.00 0 88094 76 0 0
sp_lock_selected_doc 19897 19897 0 0.00 0 8478 8 0 0
sp_make_cost_storno 26821 26821 0 0.00 0 28632 61 0 0
sp_multiply_rows_for_pdistr 26821 26821 0 0.00 0 19045 39 0 0
sp_multiply_rows_for_qdistr 19344 19344 0 0.00 0 24271 239 0 0
sp_pay_from_customer 6697 6697 0 0.00 4 19258 146 0 0
sp_pay_to_supplier 5779 5779 0 0.00 4 19489 150 0 0
sp_payment_common 12476 12476 0 0.00 1 7414 25 0 0
sp_qd_handle_on_cancel_clo 1692 1692 0 0.00 0 9130 266 0 0
sp_qd_handle_on_invoice_upd_sts 10975 10975 0 0.00 0 30546 348 0 0
sp_qd_handle_on_reserve_upd_sts 9556 9556 0 0.00 0 9506 52 0 0
sp_reserve_write_off 9556 9556 0 0.00 7 17678 191 0 0
sp_supplier_invoice 5250 5250 0 0.00 9 128803 3651 0 0
sp_supplier_order 5382 5382 0 0.00 6 90495 1387 0 0
srv_aggregate_perf_data 94 94 0 0.00 2684 212835 18158 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1945 159196 45568 0 0
srv_find_qd_qs_mism 1103077 1103077 0 0.00 0 14051 5 0 0
srv_make_invnt_saldo 442 442 0 0.00 53 130576 3406 0 0
srv_make_money_saldo 705 705 0 0.00 1 16152 511 0 0
srv_recalc_idx_stat 4 4 0 0.00 233537 506379 349571 0 0
t$perf-abend:sp_add_invoice_to_stock 103 103 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4803 4803 0 0.00 0 12130 182 0 0
t$perf-norm:sp_cancel_adding_invoice 391 391 0 0.00 0 12831 106 0 0
t$perf-norm:sp_cancel_client_order 1692 1692 0 0.00 0 2748 13 0 0
t$perf-norm:sp_cancel_customer_reserve 636 636 0 0.00 0 3534 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 636 636 0 0.00 0 2096 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 382 382 0 0.00 0 2127 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 406 406 0 0.00 0 895 21 0 0
t$perf-norm:sp_cancel_supplier_order 422 422 0 0.00 0 19533 1499 0 0
t$perf-norm:sp_cancel_write_off 987 987 0 0.00 0 3383 7 0 0
t$perf-norm:sp_client_order 19344 19344 0 0.00 0 11629 20 0 0
t$perf-norm:sp_customer_reserve 930 930 0 0.00 0 3211 10 0 0
t$perf-norm:sp_pay_from_customer 6697 6697 0 0.00 0 5959 10 0 0
t$perf-norm:sp_pay_to_supplier 5779 5779 0 0.00 0 10833 13 0 0
t$perf-norm:sp_reserve_write_off 9556 9556 0 0.00 0 10134 14 0 0
t$perf-norm:sp_supplier_invoice 5250 5250 0 0.00 0 4121 15 0 0
t$perf-norm:sp_supplier_order 5382 5382 0 0.00 0 7597 20 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 3200 45 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 128 6 0 0
t$perf-norm:srv_make_invnt_saldo 442 442 0 0.00 0 2951 14 0 0
t$perf-norm:srv_make_money_saldo 705 705 0 0.00 0 564 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 29 10 0 0
v_add_invoice_to_stock 9784 9578 206 2.10 0 5308 18 0 206
v_all_customers 35454 35454 0 0.00 0 59 1 0 0
v_all_suppliers 29934 29934 0 0.00 0 173 1 0 0
v_all_wares 38688 38688 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 782 782 0 0.00 0 4000 11 0 0
v_cancel_client_order 3384 3384 0 0.00 0 3994 6 0 0
v_cancel_customer_prepayment 1272 1272 0 0.00 0 5770 11 0 0
v_cancel_customer_reserve 1272 1272 0 0.00 0 739 4 0 0
v_cancel_payment_to_supplier 764 764 0 0.00 0 3315 11 0 0
v_cancel_supplier_invoice 824 812 12 1.45 0 5736 23 0 12
v_cancel_supplier_order 844 844 0 0.00 0 2849 8 0 0
v_cancel_write_off 1974 1974 0 0.00 0 1124 13 0 0
v_max_id_clo_ord 5382 5382 0 0.00 0 359 1 0 0
v_max_id_clo_res 930 930 0 0.00 0 750 6 0 0
v_max_id_ord_sup 5250 5250 0 0.00 0 8472 7 0 0
v_max_non_paid_invoice 1444 1444 0 0.00 0 5345 7 0 0
v_max_non_paid_realizn 3424 3424 0 0.00 0 3485 5 0 0
v_min_id_clo_ord 5382 5382 0 0.00 0 10881 11 0 0
v_min_id_clo_res 930 930 0 0.00 0 8047 14 0 0
v_min_id_ord_sup 5250 5250 0 0.00 0 3234 4 0 0
v_min_non_paid_invoice 5779 5779 0 0.00 0 6126 15 0 0
v_min_non_paid_realizn 6697 6697 0 0.00 0 4734 19 0 0
v_our_firm 9780 9780 0 0.00 0 15 1 0 0
v_reserve_write_off 19112 19112 0 0.00 0 6174 6 0 0
x_lock_depdocs_on_canc_invoice 12614 12614 0 0.00 0 7124 95 0 0
x_lock_depdocs_on_canc_sup_ord 422 422 0 0.00 0 12784 376 0 0
x_make_qstorno_1000_1200 5382 5382 0 0.00 1 90479 1297 0 0
x_make_qstorno_1200_2000 5250 5250 0 0.00 5 128796 3495 0 0
x_make_qstorno_2100_3300 450898 450898 0 0.00 0 129567 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 15-12-2021 10:39:16 to 15-12-2021 10:39:20.

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 5 8192000000 8799615008 8811241472 5386336 0.065 2 1 2 0 5500640 65824 48480
08:08:13 30963 8192000000 9730280000 10029879296 811024496 9.008 100 92 184 0 841051200 8179536 21847168
08:09:53 22133 8192000000 9775067168 10080870400 842917200 9.329 100 90 180 1 878464192 14469568 21077424
08:11:22 17028 8192000000 9808435520 10130505728 876066544 9.661 100 78 156 0 917210672 23525712 17618416
08:12:41 11311 8192000000 9876635952 10211459072 922337728 10.119 100 80 160 0 994889920 55349408 17202784
08:13:59 7178 8192000000 9930019600 10277785600 971470464 10.601 100 78 154 2 1036021888 49019456 15531968
08:15:08 5110 8192000000 9984704560 10340675584 1008317296 10.959 100 86 170 1 1098639040 73454112 16867632
08:16:14 2018 8192000000 10032302352 10395627520 1033301632 11.200 100 88 174 2 1137972672 86902432 17768608
08:17:17 1754 8192000000 10013287216 10385137664 1052526384 11.385 100 85 170 0 1126026848 56228464 17272000
08:18:20 25130 8192000000 10022290192 10405400576 1066110992 11.515 100 63 125 3 1141930528 63540352 12279184
08:19:53 5668 8192000000 9998881984 10402217984 1078460192 11.633 100 54 107 2 1125009520 35045904 11503424
08:21:04 5695 8192000000 9998065296 10390855680 1084027744 11.686 100 40 81 1 1110684768 17538288 9118736
08:22:12 6054 8192000000 10011686640 10417037312 1091455520 11.756 100 28 56 0 1130373872 33042704 5875648
08:23:18 38726 8192000000 10022375008 10415132672 1100803280 11.845 100 67 132 5 1130290048 14591568 14895200
08:24:58 4571 8192000000 10040778432 10440876032 1106585648 11.900 100 47 94 0 1150813648 34249584 9978416
08:26:05 4030 8192000000 10034781632 10434060288 1108322096 11.917 100 40 81 0 1150305216 33761104 8222016
08:27:12 8263 8192000000 10014412064 10414899200 1098622592 11.825 100 31 61 2 1129551456 24103664 6825200
08:28:21 43094 8192000000 9997379888 10384871424 1009583600 10.971 93 49 93 2 1032496272 12129632 10783040
08:30:06 8392 8192000000 9942920384 10325483520 990551760 10.787 94 34 68 0 1030791504 32996688 7243056
08:31:15 4175 8192000000 9908772928 10285043712 981503792 10.699 97 57 113 1 1010528960 15659120 13366048
08:32:20 12686 8192000000 9958797520 10342739968 1034579168 11.213 100 33 65 3 1071172192 29294976 7298048
08:33:37 22676 8192000000 9944220384 10342277120 1033773968 11.205 99 32 64 2 1069310352 28065696 7470688
08:35:00 5289 8192000000 9966279536 10362277888 1053269984 11.392 100 49 98 3 1080956640 16822080 10864576
08:36:13 8785 8192000000 9976056720 10372304896 1061035376 11.466 100 40 79 0 1087539120 17836640 8667104
08:37:28 26960 8192000000 10001026608 10388959232 1071851040 11.570 100 56 114 0 1103791568 19681344 12259184
08:39:01 49836 8192000000 10026460624 10427695104 1082282752 11.669 100 35 70 0 1153341728 63523088 7535888
08:40:53 7563 8192000000 10081143024 10484039680 1100897536 11.846 100 50 98 1 1197182784 85409840 10875408
08:42:09 6680 8192000000 10098276336 10498842624 1118473472 12.013 100 53 105 1 1206811056 76142064 12195520
08:43:18 29118 8192000000 10127557008 10532691968 1121933888 12.045 100 36 70 1 1229657664 100149824 7573952
08:44:49 12171 8192000000 10196740416 10618421248 1130593296 12.127 100 48 96 1 1320610176 178649824 11367056
08:46:21 7377 8192000000 10197083312 10601590784 1151871760 12.327 100 51 102 1 1302200864 138703552 11625552
08:47:35 17241 8192000000 10256484384 10678878208 1151080576 12.320 99 53 102 3 1346662960 184102528 11479856
08:48:55 2746 8192000000 10312320128 10714124288 1182873184 12.617 100 76 148 0 1411587392 212321984 16392224
08:50:02 3238 8192000000 10311633760 10714570752 1187233744 12.658 100 87 172 0 1410314192 202354768 20725680
08:51:08 1944 8192000000 10377354464 10773098496 1175131056 12.545 100 84 168 0 1477530512 283752496 18646960
08:52:14 3552 8192000000 10370860832 10782560256 1174121968 12.535 100 73 142 1 1475515472 285713680 15679824
08:53:23 2884 8192000000 10412019088 10812006400 1164582848 12.446 100 80 155 1 1515265520 333183296 17499376
08:54:30 3029 8192000000 10468919088 10874339328 1163691456 12.438 100 77 154 0 1566017984 383640736 18685792
08:55:37 2909 8192000000 10376127008 10784264192 1153226000 12.340 100 62 122 1 1477623872 309595648 14802224
08:56:44 4683 8192000000 10353815888 10793668608 1150997088 12.319 100 38 76 1 1454762864 294272288 9493488
08:57:54 2615 8192000000 10434221856 10843521024 1174168752 12.536 100 64 127 2 1528083968 338896592 15018624
08:59:09 4888 8192000000 10459226128 10874548224 1164777760 12.448 100 45 90 0 1559494032 382899312 11816960
09:00:20 12219 8192000000 10513981744 10940915712 1175912560 12.552 100 46 92 1 1609290208 421379552 11998096
09:01:38 11018 8192000000 10519838320 10960879616 1170807072 12.504 100 45 90 1 1630745248 448526352 11411824
09:02:52 18560 8192000000 10537776480 11058376704 1161243136 12.415 100 36 72 0 1628896960 457514320 10139504
09:04:13 18861 8192000000 10460106544 10905354240 1103997392 11.876 97 28 56 0 1519579328 408300416 7281520
09:05:37 33338 8192000000 10154788112 10649210880 1056554272 11.423 94 10 20 0 1215716448 156861344 2300832
09:07:11 20960 8192000000 10041240752 10484322304 1098136944 11.820 98 27 48 2 1122076624 18913104 5026576
09:08:41 68627 8192000000 10039139648 10488315904 1102817632 11.864 99 23 46 0 1135243056 27930320 4495104
09:10:57 70174 8192000000 10118840784 10570485760 1117572448 12.004 99 21 42 0 1192511552 70444368 4494736
09:13:09 72473 8192000000 10089321232 10524811264 1120544800 12.032 100 38 76 1 1191367056 61834112 8988144
09:15:42 46856 8192000000 10095903792 10536665088 1116585616 11.995 98 29 58 1 1178642368 55845008 6211744
09:17:41 159195 8192000000 10092633744 10576031744 1125254752 12.077 99 9 18 0 1190171728 62986832 1930144
09:21:21 45536 8192000000 10078867680 10582560768 1141859072 12.233 100 8 16 0 1208346480 65026464 1460944
09:23:07 21047 8192000000 10079805264 10526228480 1145308720 12.265 100 23 46 0 1180798176 30115968 5373488
09:24:35 52386 8192000000 10152120416 10603126784 1159502064 12.399 100 23 46 0 1233404704 68892192 5010448
09:26:29 79639 8192000000 10072998256 10583810048 1115495360 11.984 97 14 28 1 1193254016 74231488 3527168
09:29:13 50448 8192000000 10088824656 10552053760 1105713056 11.892 97 31 62 0 1167227776 55415968 6098752
09:31:12 45188 8192000000 10118077584 10625802240 1131729280 12.138 98 28 56 2 1240819296 103211264 5878752
09:33:25 28255 8192000000 10066324816 10494189568 1107394800 11.908 97 8 16 0 1131638208 22835424 1407984
09:34:55 100832 8192000000 10036529808 10487611392 1104765488 11.883 97 23 46 1 1163825632 54335104 4725040
09:37:41 35032 8192000000 10070299920 10519298048 1087551392 11.719 96 23 45 1 1136832080 44025200 5255488
09:39:23 63312 8192000000 10073236528 10519785472 1078390848 11.632 96 13 26 0 1133307440 52021696 2894896
09:41:35 64738 8192000000 10119076560 10580140032 1116913248 11.998 99 15 31 1 1228321632 108317472 3090912
09:43:40 79654 8192000000 10079013840 10546745344 1105856640 11.893 98 15 30 1 1188426272 79050080 3519552
09:46:10 40452 8192000000 10119268000 10567225344 1129916592 12.121 99 24 48 1 1233521696 97937280 5667824
09:48:09 47714 8192000000 10149459328 10587566080 1118529664 12.013 99 16 32 0 1250700992 127971824 4199504
09:50:00 59644 8192000000 10078230192 10531905536 1072152960 11.573 95 9 18 0 1118744592 45062352 1529280
09:52:00 66680 8192000000 10082380224 10623102976 1126419632 12.088 99 25 50 0 1277946144 146278128 5248384
09:54:09 124209 8192000000 10054376512 10490744832 1110514368 11.937 98 27 54 0 1142165344 26119280 5531696
09:57:16 86601 8192000000 10030186848 10500726784 1075719488 11.607 96 17 34 1 1120726688 41177056 3830144
09:59:52 44483 8192000000 10037508688 10510712832 1099461088 11.833 99 14 28 0 1143664080 41554432 2648560
10:01:38 55053 8192000000 10089699664 10562609152 1109002912 11.923 99 12 24 0 1204655984 92873232 2779840
10:03:37 49859 8192000000 10069886480 10519105536 1094763616 11.788 97 17 35 1 1135426320 37657200 3005504
10:05:31 31176 8192000000 10076438400 10514247680 1122473488 12.050 100 36 72 0 1182943232 52816432 7653312
10:07:02 68817 8192000000 10080913200 10521022464 1132239776 12.142 100 21 42 0 1186875040 50371856 4263408
10:09:12 96120 8192000000 10100937216 10593644544 1088081232 11.724 96 11 22 0 1186076896 95538688 2456976
10:11:49 60051 8192000000 10078277792 10563059712 1094177328 11.782 97 21 42 1 1163733680 65439680 4116672
10:13:49 112477 8192000000 10043576592 10495451136 1093586496 11.777 98 31 62 0 1161407280 61084656 6736128
10:19:17 65059 8192000000 10102498128 10558291968 1143452416 12.248 100 18 36 1 1199635664 52291264 3891984
10:21:41 60494 8192000000 10066453152 10502893568 1117431600 12.003 98 13 25 0 1135435168 15350400 2653168
10:23:42 67548 8192000000 10095434416 10549444608 1081368784 11.661 95 23 46 0 1155635936 69663200 4603952
10:25:53 87517 8192000000 10092956272 10577678336 1106763136 11.902 98 16 32 0 1170231232 60042304 3425792
10:28:35 90280 8192000000 10072533520 10537230336 1094100368 11.782 97 4 8 0 1147679104 52932688 646048
10:31:09 73318 8192000000 10085524912 10545397760 1097127168 11.810 97 27 54 0 1177887248 75632432 5127648
10:33:29 81798 8192000000 10088483296 10525212672 1123047728 12.056 99 33 66 1 1166604352 36526496 7030128
10:35:51 13579 8192000000 10087720720 10566504448 1132596384 12.146 100 36 71 1 1208448672 68875520 6976768

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 15-12-2021 10:39:22 to 15-12-2021 10:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 103 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 15-12-2021 10:39:37 to 15-12-2021 10:39:39.

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          230967
MON$OLDEST_ACTIVE               233067
MON$OLDEST_SNAPSHOT             233067
MON$NEXT_TRANSACTION            233067
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                       2074144
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             792
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

15.12.21 10:39:40. 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-15 10:39:42 No severe PSQL-related problems occured
15.12.21 10:39:44. End of report.