Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.01.2021 04:27
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16411416 kB
MemAvailable 31175092 kB
Buffers 20 kB
Cached 15090376 kB
SwapCached 4864 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.0
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-01-19 04:26:55
DTS_END                         2021-01-19 04:26:55.7870
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/40038
TRN_ID                          233692
ATT_ID                          682
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: 8749.12 22806 1049894
customer order: creation 170.41 417 20449
customer order: refuse 14.18 362 1702
order to supplier: creation 44.91 1352 5389
order to supplier: removal 3.38 121427 405
invoice (draft): creation 46.21 3548 5545
invoice (draft): removal 48.63 878 5836
invoice accept: apply 41.27 19196 4952
invoice accept: cancel 44.37 942 5324
customer reserve: creation 3742.28 191 449073
customer reserve: removal 4306.33 76 516760
realization accept: apply 81.83 175 9819
realization accept: cancel 79.58 137 9550
payment from customer: creation 58.49 123 7019
payment from customer: removal 5.52 81 662
payment to supplier: creation 47.66 132 5719
payment to supplier: removal 3.32 98 398
service: total inventory turnovers 3.93 3758 471
service: total monetary turnovers 5.93 720 712
service: aggregate perf. data 0.88 19610 105
service: refresh index statistics 0.03 282887 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-01-2021 04:27:45 to 19-01-2021 04:27:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6011
TEST_TIME 2 6396
TEST_TIME 3 5590
TEST_TIME 4 6412
TEST_TIME 5 6524
TEST_TIME 6 6716
TEST_TIME 7 6809
TEST_TIME 8 6409
TEST_TIME 9 6398
TEST_TIME 10 6368
TEST_TIME 11 6418
TEST_TIME 12 6616
TEST_TIME 13 6703
TEST_TIME 14 6849
TEST_TIME 15 6719
TEST_TIME 16 6681
TEST_TIME 17 6829
TEST_TIME 18 6778
TEST_TIME 19 6745
TEST_TIME 20 6973
TEST_TIME 21 7108
TEST_TIME 22 7328
TEST_TIME 23 7630
TEST_TIME 24 7879
TEST_TIME 25 7947
TEST_TIME 26 7982
TEST_TIME 27 8046
TEST_TIME 28 8056
TEST_TIME 29 8376
TEST_TIME 30 8577
TEST_TIME 31 8566
TEST_TIME 32 8746
TEST_TIME 33 8988
TEST_TIME 34 9476
TEST_TIME 35 10387
TEST_TIME 36 10293
TEST_TIME 37 10189
TEST_TIME 38 9930
TEST_TIME 39 9807
TEST_TIME 40 9698
TEST_TIME 41 9583
TEST_TIME 42 9510
TEST_TIME 43 9588
TEST_TIME 44 9588
TEST_TIME 45 9448
TEST_TIME 46 9381
TEST_TIME 47 9286
TEST_TIME 48 9222
TEST_TIME 49 9078
TEST_TIME 50 9160
TEST_TIME 51 9312
TEST_TIME 52 9310
TEST_TIME 53 9308
TEST_TIME 54 9267
TEST_TIME 55 9327
TEST_TIME 56 9372
TEST_TIME 57 9279
TEST_TIME 58 9295
TEST_TIME 59 9248
TEST_TIME 60 9291
TEST_TIME 61 9274
TEST_TIME 62 9272
TEST_TIME 63 9205
TEST_TIME 64 9101
TEST_TIME 65 9070
TEST_TIME 66 8976
TEST_TIME 67 8979
TEST_TIME 68 8951
TEST_TIME 69 8900
TEST_TIME 70 8861
TEST_TIME 71 8896
TEST_TIME 72 8869
TEST_TIME 73 8828
TEST_TIME 74 8917
TEST_TIME 75 8940
TEST_TIME 76 8921
TEST_TIME 77 8912
TEST_TIME 78 8906
TEST_TIME 79 8984
TEST_TIME 80 8950
TEST_TIME 81 8956
TEST_TIME 82 8850
TEST_TIME 83 8814
TEST_TIME 84 8752
TEST_TIME 85 8732
TEST_TIME 86 8670
TEST_TIME 87 8687
TEST_TIME 88 8678
TEST_TIME 89 8669
TEST_TIME 90 8654
TEST_TIME 91 8673
TEST_TIME 92 8760
TEST_TIME 93 8706
TEST_TIME 94 8746
TEST_TIME 95 8786
TEST_TIME 96 8740
TEST_TIME 97 8776
TEST_TIME 98 8766
TEST_TIME 99 8808
TEST_TIME 100 8854
TEST_TIME 101 8789
TEST_TIME 102 8715
TEST_TIME 103 8738
TEST_TIME 104 8685
TEST_TIME 105 8658
TEST_TIME 106 8624
TEST_TIME 107 8598
TEST_TIME 108 8577
TEST_TIME 109 8566
TEST_TIME 110 8550
TEST_TIME 111 8533
TEST_TIME 112 8531
TEST_TIME 113 8538
TEST_TIME 114 8553
TEST_TIME 115 8602
TEST_TIME 116 8636
TEST_TIME 117 8658
TEST_TIME 118 8694
TEST_TIME 119 8735
TEST_TIME 120 8751

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-01-2021 04:27:49 to 19-01-2021 04:27:52.

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 1466636 1466636 0 0.00 0 22279 7 0 0
doc_list_biud 1466635 1466635 0 0.00 0 46977 33 0 0
sp_add_invoice_to_stock 5028 4952 76 1.51 32 186847 19196 0 76
sp_cancel_adding_invoice 5324 5324 0 0.00 6 51517 942 0 0
sp_cancel_client_order 1702 1702 0 0.00 4 10761 362 0 0
sp_cancel_customer_reserve 516760 516760 0 0.00 1 37939 77 0 0
sp_cancel_pay_from_customer 662 662 0 0.00 3 4584 81 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 2 6612 98 0 0
sp_cancel_supplier_invoice 5845 5836 9 0.15 2 39522 878 0 9
sp_cancel_supplier_order 405 405 0 0.00 11 1092431 121428 0 0
sp_cancel_write_off 9550 9550 0 0.00 1 18030 138 0 0
sp_client_order 20449 20449 0 0.00 4 37539 418 0 0
sp_customer_reserve 449073 449073 0 0.00 0 115997 192 0 0
sp_fill_shopping_cart 31376 31376 0 0.00 1 17640 44 0 0
sp_fill_shopping_cart_clo_res 449070 449070 0 0.00 0 19892 18 0 0
sp_get_clo_for_invoice 4949 4949 0 0.00 0 38009 377 0 0
sp_kill_cost_storno 15934 15934 0 0.00 0 22261 66 0 0
sp_kill_qstorno_ret_qs2qd 1049311 1049311 0 0.00 0 39510 41 0 0
sp_kill_qty_storno 554344 554344 0 0.00 0 46976 87 0 0
sp_lock_selected_doc 20486 20486 0 0.00 0 9911 4 0 0
sp_make_cost_storno 27506 27506 0 0.00 0 15104 59 0 0
sp_multiply_rows_for_pdistr 27505 27505 0 0.00 0 8181 33 0 0
sp_multiply_rows_for_qdistr 20449 20449 0 0.00 0 28239 232 0 0
sp_pay_from_customer 7019 7019 0 0.00 4 19482 123 0 0
sp_pay_to_supplier 5719 5719 0 0.00 4 19466 132 0 0
sp_payment_common 12738 12738 0 0.00 1 11785 21 0 0
sp_qd_handle_on_cancel_clo 1702 1702 0 0.00 0 10755 335 0 0
sp_qd_handle_on_invoice_upd_sts 10273 10273 0 0.00 0 46976 407 0 0
sp_qd_handle_on_reserve_upd_sts 9818 9818 0 0.00 0 7855 49 0 0
sp_reserve_write_off 9819 9819 0 0.00 6 13826 175 0 0
sp_supplier_invoice 5545 5545 0 0.00 7 110178 3548 0 0
sp_supplier_order 5389 5389 0 0.00 5 92033 1353 0 0
srv_aggregate_perf_data 105 105 0 0.00 2609 315028 19610 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1608 190528 45177 0 0
srv_find_qd_qs_mism 1003594 1003594 0 0.00 0 13414 6 0 0
srv_make_invnt_saldo 471 471 0 0.00 64 111579 3759 0 0
srv_make_money_saldo 712 712 0 0.00 1 16282 720 0 0
srv_recalc_idx_stat 4 4 0 0.00 74996 489473 282887 0 0
t$perf-abend:sp_add_invoice_to_stock 76 76 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4952 4952 0 0.00 0 7754 160 0 0
t$perf-norm:sp_cancel_adding_invoice 435 435 0 0.00 0 3171 103 0 0
t$perf-norm:sp_cancel_client_order 1702 1702 0 0.00 0 6436 20 0 0
t$perf-norm:sp_cancel_customer_reserve 722 722 0 0.00 0 2896 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 662 662 0 0.00 0 2801 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 942 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 666 14 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 16611 1657 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 16059 21 0 0
t$perf-norm:sp_client_order 20449 20449 0 0.00 0 5718 19 0 0
t$perf-norm:sp_customer_reserve 1049 1049 0 0.00 0 423 3 0 0
t$perf-norm:sp_pay_from_customer 7019 7019 0 0.00 0 5034 8 0 0
t$perf-norm:sp_pay_to_supplier 5719 5719 0 0.00 0 5704 8 0 0
t$perf-norm:sp_reserve_write_off 9819 9819 0 0.00 0 3918 9 0 0
t$perf-norm:sp_supplier_invoice 5545 5545 0 0.00 0 13700 23 0 0
t$perf-norm:sp_supplier_order 5389 5389 0 0.00 0 6455 17 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 760 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 1413 28 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 573 5 0 0
t$perf-norm:srv_make_money_saldo 712 712 0 0.00 0 444 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10048 9896 152 1.51 0 6169 15 0 152
v_all_customers 37338 37338 0 0.00 0 108 1 0 0
v_all_suppliers 29758 29758 0 0.00 0 128 1 0 0
v_all_wares 40896 40896 0 0.00 0 18 1 0 0
v_cancel_adding_invoice 870 870 0 0.00 0 640 6 0 0
v_cancel_client_order 3404 3404 0 0.00 0 6874 6 0 0
v_cancel_customer_prepayment 1324 1324 0 0.00 0 2196 8 0 0
v_cancel_customer_reserve 1444 1444 0 0.00 0 2349 4 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 6547 13 0 0
v_cancel_supplier_invoice 810 792 18 2.22 0 1498 14 0 18
v_cancel_supplier_order 810 810 0 0.00 0 1683 8 0 0
v_cancel_write_off 2000 2000 0 0.00 0 1922 12 0 0
v_max_id_clo_ord 5386 5386 0 0.00 0 584 1 0 0
v_max_id_clo_res 1049 1049 0 0.00 0 2612 16 0 0
v_max_id_ord_sup 5541 5541 0 0.00 0 8012 7 0 0
v_max_non_paid_invoice 1767 1767 0 0.00 0 951 3 0 0
v_max_non_paid_realizn 3646 3646 0 0.00 0 8192 8 0 0
v_min_id_clo_ord 5386 5386 0 0.00 0 13249 10 0 0
v_min_id_clo_res 1049 1049 0 0.00 0 4555 13 0 0
v_min_id_ord_sup 5541 5541 0 0.00 0 6576 8 0 0
v_min_non_paid_invoice 5719 5719 0 0.00 0 11777 12 0 0
v_min_non_paid_realizn 7019 7019 0 0.00 0 11766 14 0 0
v_our_firm 10304 10304 0 0.00 0 17 1 0 0
v_reserve_write_off 19636 19636 0 0.00 0 6871 5 0 0
x_lock_depdocs_on_canc_invoice 10764 10764 0 0.00 0 15219 130 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 3224 366 0 0
x_make_qstorno_1000_1200 5389 5389 0 0.00 2 89347 1268 0 0
x_make_qstorno_1200_2000 5545 5545 0 0.00 3 110162 3384 0 0
x_make_qstorno_2100_3300 449073 449073 0 0.00 0 115996 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-01-2021 04:27:53 to 19-01-2021 04:27:57.

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
01:56:59 5 8192000000 8796638048 8807108608 3168016 0.038 1 1 2 0 3265632 61840 35776
01:58:00 19895 8192000000 9716822768 10012020736 794879568 8.844 100 78 156 0 821156448 7114336 19162544
01:59:25 26739 8192000000 9771891024 10078576640 841747056 9.317 100 89 178 0 875287600 12706352 20834192
02:00:57 27996 8192000000 9808253920 10125381632 871844736 9.618 100 84 168 0 912047040 21099808 19102496
02:02:36 13997 8192000000 9862711776 10197831680 910660848 10.004 100 71 141 2 972973344 46844912 15467584
02:03:53 8975 8192000000 9905746432 10248577024 953446000 10.425 100 82 164 0 1022535232 51651040 17438192
02:05:05 4623 8192000000 9960571424 10315141120 994996176 10.830 100 79 156 1 1079702160 69039616 15666368
02:06:11 5873 8192000000 10003906608 10363019264 1017941040 11.052 100 80 159 1 1105794416 71919856 15933520
02:07:18 1158 8192000000 10039170672 10408271872 1052701904 11.387 100 87 171 1 1148763696 78937776 17124016
02:08:20 1064 8192000000 10037677392 10410225664 1067883888 11.532 100 93 184 1 1148068672 60923232 19261552
02:09:22 9471 8192000000 9984997344 10384748544 1070537136 11.557 100 24 48 2 1097178480 21856720 4784624
02:10:32 8794 8192000000 10000794736 10410078208 1079332320 11.641 100 25 50 1 1124257520 39790832 5134368
02:11:43 5911 8192000000 9996708224 10398363648 1086139968 11.706 100 32 64 0 1120429376 27355040 6934368
02:12:51 61470 8192000000 9992156768 10405912576 1090116560 11.744 100 41 81 2 1111915520 12480256 9318704
02:14:55 6932 8192000000 10025574928 10437050368 1100341232 11.841 100 33 66 2 1129791136 22296336 7153568
02:16:04 6053 8192000000 10008131872 10416533504 1089391680 11.737 99 30 58 0 1113009344 17146640 6471024
02:17:14 8691 8192000000 9994186752 10396786688 1059456016 11.451 97 22 44 0 1088591472 24461936 4673520
02:18:27 55056 8192000000 9969816448 10353782784 986361888 10.746 91 45 88 1 1007131392 10430240 10339264
02:20:22 7716 8192000000 9939461024 10326749184 966780400 10.555 92 49 95 3 1002760832 25194736 10785696
02:21:33 6991 8192000000 9947542752 10330669056 1018534000 11.058 99 41 81 4 1052132224 25502128 8096096
02:22:41 9175 8192000000 9951688288 10346418176 1034646544 11.213 100 49 98 0 1072126160 26664416 10815200
02:23:53 39328 8192000000 9933061600 10328690688 1018297952 11.056 98 36 72 1 1045377920 17977280 9102688
02:25:33 6119 8192000000 9974801856 10372308992 1052714704 11.387 100 33 66 0 1080696240 20082000 7899536
02:26:40 6552 8192000000 9990363616 10393587712 1075753696 11.607 100 29 58 2 1105171856 23528208 5889952
02:27:55 3499 8192000000 10056193424 10444349440 1101206384 11.849 100 76 152 2 1163440320 46450304 15783632
02:29:02 41292 8192000000 10049332048 10452533248 1108468464 11.918 100 49 96 1 1165875728 47773152 9634112
02:30:50 15432 8192000000 10073818640 10487230464 1122793824 12.053 100 42 84 2 1189155472 57808304 8553344
02:32:08 4605 8192000000 10106547120 10506072064 1128480496 12.107 100 72 142 1 1203839088 61438800 13919792
02:33:23 6968 8192000000 10116381840 10523377664 1136433952 12.182 100 55 110 0 1224708752 76760688 11514112
02:34:40 7449 8192000000 10093626096 10505748480 1144110464 12.254 100 48 94 1 1200713344 46591408 10011472
02:35:54 18103 8192000000 10131426848 10540310528 1148517072 12.296 100 50 98 1 1241949456 82177520 11254864
02:37:15 8405 8192000000 10128818432 10542211072 1148241488 12.293 100 45 90 1 1237859360 79433216 10184656
02:38:29 9898 8192000000 10132638448 10556813312 1153762576 12.345 100 39 78 1 1241476752 79076912 8637264
02:39:49 9640 8192000000 10179162544 10590588928 1163017424 12.432 100 67 133 1 1280712384 103458720 14236240
02:41:07 8021 8192000000 10192004816 10601259008 1153732256 12.345 100 56 110 1 1293531216 128095856 11703104
02:42:19 3129 8192000000 10244519440 10652467200 1164367568 12.444 100 65 130 0 1347264400 168354624 14542208
02:43:25 2683 8192000000 10287581504 10690977792 1159782944 12.401 100 77 153 0 1396406800 218698112 17925744
02:44:42 3621 8192000000 10318752080 10712461312 1165321744 12.453 100 86 172 0 1413652592 228899760 19431088
02:45:52 2085 8192000000 10351454064 10754211840 1159337344 12.397 100 83 165 1 1453313376 274150000 19826032
02:47:00 1665 8192000000 10339280560 10731188224 1129378352 12.116 98 82 164 3 1436653792 288215168 19060272
02:48:09 2187 8192000000 10389741104 10800066560 1139502080 12.211 100 83 163 0 1489216816 330587264 19127472
02:49:17 1605 8192000000 10364683568 10753126400 1131423824 12.135 100 87 172 1 1463974528 312914000 19636704
02:50:24 13740 8192000000 10361437104 10797989888 1142214352 12.236 100 43 86 0 1475967712 322934176 10819184
02:51:50 16214 8192000000 10383283104 10785890304 1137909056 12.196 100 62 123 1 1478026512 325640496 14476960
02:53:08 4651 8192000000 10404430656 10827857920 1132876272 12.148 100 49 95 1 1502845856 357793856 12175728
02:54:33 4729 8192000000 10445555520 10866692096 1137032912 12.188 100 60 118 3 1541473008 390096432 14343664
02:55:46 8330 8192000000 10405052848 10887979008 1139120736 12.207 100 30 59 0 1499778096 353261216 7396144
02:57:21 13660 8192000000 10401066672 10822758400 1139553664 12.211 100 29 58 0 1491599888 344359488 7686736
02:58:52 35324 8192000000 10374467904 10861629440 1137093200 12.188 99 22 44 2 1489982864 346890688 5998976
03:00:29 18745 8192000000 10304660928 10837889024 1129589120 12.117 100 25 50 1 1490091648 354364432 6138096
03:01:50 46462 8192000000 10056918864 10486800384 1123351472 12.059 99 25 51 0 1160394992 32323728 4719792
03:03:37 148321 8192000000 10062225232 10511626240 1128629680 12.108 99 18 35 0 1177033600 44967472 3436448
03:07:08 18217 8192000000 10121607680 10547826688 1140912368 12.224 99 52 104 0 1199500384 47865728 10722288
03:08:38 65811 8192000000 10117096544 10612486144 1140089184 12.216 99 12 24 1 1236240704 93475184 2676336
03:10:55 101029 8192000000 10139745696 10607300608 1160035616 12.404 100 17 35 2 1231716640 67745024 3936000
03:15:14 91016 8192000000 10208066912 10691342336 1156026608 12.366 99 18 37 0 1312379200 151957808 4394784
03:17:45 50650 8192000000 10108722144 10549243904 1138721648 12.204 98 27 55 1 1172704576 27761008 6221920
03:19:42 78797 8192000000 10124976640 10563305472 1129427600 12.116 97 24 48 2 1202430192 67595552 5407040
03:22:10 90921 8192000000 10112181856 10555654144 1143742016 12.251 98 28 56 0 1196681680 46679632 6260032
03:24:42 27899 8192000000 10119652368 10562023424 1164889248 12.449 100 37 73 3 1223005936 50672784 7443904
03:26:13 79523 8192000000 10079016304 10548842496 1104592240 11.881 95 8 16 0 1157513856 51416576 1505040
03:28:33 71011 8192000000 10065722368 10493784064 1103312176 11.869 96 23 44 0 1132507088 25182096 4012816
03:30:45 87442 8192000000 10062324992 10522910720 1131211840 12.133 99 20 40 2 1188259104 53422160 3625104
03:33:24 59808 8192000000 10063523008 10546462720 1088784176 11.731 96 11 22 2 1147192256 56051680 2356400
03:35:54 68374 8192000000 10104760336 10566205440 1125062224 12.075 98 33 66 2 1189734848 57460816 7211808
03:38:07 54676 8192000000 10098896752 10533490688 1097365024 11.813 97 20 40 0 1152820368 51129408 4325936
03:40:06 39546 8192000000 10089860944 10582228992 1106438848 11.899 98 27 54 1 1206949280 94804112 5706320
03:41:48 79724 8192000000 10093150864 10528509952 1108239344 11.916 98 22 44 1 1150332576 37866976 4226256
03:44:11 52105 8192000000 10098402272 10518011904 1106944112 11.903 98 32 61 3 1146868400 33641664 6282624
03:46:04 190528 8192000000 10037439328 10479968256 1062391792 11.479 94 18 34 2 1103424464 37337024 3695648
03:50:27 62007 8192000000 10067526128 10519056384 1128937184 12.111 100 5 10 0 1166634256 36645168 1051904
03:54:01 54635 8192000000 10058738704 10541191168 1111309920 11.945 99 21 41 0 1167958592 52246064 4402608
03:56:05 43417 8192000000 10067320816 10505773056 1080349072 11.651 96 17 34 0 1139730736 56180560 3201104
03:57:49 52109 8192000000 10062931136 10588549120 1110214048 11.934 100 16 31 1 1176161200 62631472 3315680
03:59:42 46714 8192000000 10126595424 10584309760 1130166672 12.123 100 32 64 1 1181752656 45084944 6501040
04:01:43 74160 8192000000 10111186208 10552438784 1130145456 12.123 99 25 50 0 1164189760 29229280 4815024
04:03:57 97990 8192000000 10094174128 10570977280 1116235968 11.991 97 16 32 0 1170114736 50490128 3388640
04:06:37 145590 8192000000 10117481584 10587623424 1131796592 12.138 98 11 22 0 1187494400 53435616 2262192
04:10:15 61095 8192000000 10095489584 10595053568 1151891584 12.327 100 5 10 0 1193112800 40173216 1048000
04:12:16 39589 8192000000 10088638112 10550763520 1156038592 12.366 100 13 26 0 1189569456 30993392 2537472
04:13:58 44870 8192000000 10068292240 10544549888 1145180576 12.264 99 12 24 0 1183325520 35410016 2734928
04:15:47 37256 8192000000 10079453568 10512482304 1120062032 12.028 97 29 58 2 1151864032 24823056 6978944
04:17:26 44839 8192000000 10142838240 10586906624 1112542128 11.956 96 24 48 1 1182855056 65156304 5156624
04:19:23 71260 8192000000 10158257072 10636296192 1107713152 11.911 96 27 54 0 1253223728 138934672 6575904
04:21:34 78325 8192000000 10101648288 10636398592 1103432992 11.870 98 19 40 0 1199981888 92650208 3898688
04:23:53 66125 8192000000 10077127792 10550603776 1102893184 11.865 98 14 28 1 1188070288 82034128 3142976
04:26:11 58558 8192000000 9093678560 9348632576 242446864 2.874 22 3 4 1 251316624 8746144 123616

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-01-2021 04:27:59 to 19-01-2021 04:28:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 76 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-01-2021 04:28:15 to 19-01-2021 04:28:17.

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          234220
MON$OLDEST_ACTIVE               234221
MON$OLDEST_SNAPSHOT             234221
MON$NEXT_TRANSACTION            234221
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                       2114688
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             796
MON$NEXT_STATEMENT              53


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

19.01.21 04:28:18. 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-01-19 04:28:20 No severe PSQL-related problems occured
19.01.21 04:28:21. End of report.