Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.12.2021 22: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 16373860 kB
MemAvailable 31397924 kB
Buffers 0 kB
Cached 15272520 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 3.0.9
DB_NAME                         /home/bases/oltp30-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/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/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=3333
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/oltp30-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-oltp30
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-19 22:38:02
DTS_END                         2021-12-19 22:38:02.5490
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(57:17)
IP                              ::1/46033
TRN_ID                          246701
ATT_ID                          711
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: 9626.45 22599 1155174
customer order: creation 178.48 325 21417
customer order: refuse 15.18 358 1821
order to supplier: creation 47.68 1243 5722
order to supplier: removal 3.38 100666 406
invoice (draft): creation 47.88 3515 5745
invoice (draft): removal 54.36 758 6523
invoice accept: apply 43.43 17671 5211
invoice accept: cancel 49.46 810 5935
customer reserve: creation 4043.80 172 485256
customer reserve: removal 4832.02 54 579842
realization accept: apply 86.26 186 10351
realization accept: cancel 92.39 108 11087
payment from customer: creation 60.39 119 7247
payment from customer: removal 6.32 72 758
payment to supplier: creation 49.58 147 5950
payment to supplier: removal 3.74 60 449
service: total inventory turnovers 4.47 1136 536
service: total monetary turnovers 6.69 27 803
service: aggregate perf. data 0.93 21755 111
service: refresh index statistics 0.03 302805 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 19-12-2021 22:38:39 to 19-12-2021 22:38:41.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6926
TEST_TIME 2 4526
TEST_TIME 3 5276
TEST_TIME 4 5437
TEST_TIME 5 5105
TEST_TIME 6 4841
TEST_TIME 7 5028
TEST_TIME 8 5427
TEST_TIME 9 6382
TEST_TIME 10 6915
TEST_TIME 11 7109
TEST_TIME 12 7215
TEST_TIME 13 7150
TEST_TIME 14 7122
TEST_TIME 15 7247
TEST_TIME 16 7442
TEST_TIME 17 7790
TEST_TIME 18 7829
TEST_TIME 19 8397
TEST_TIME 20 8303
TEST_TIME 21 8605
TEST_TIME 22 8665
TEST_TIME 23 9179
TEST_TIME 24 9235
TEST_TIME 25 9839
TEST_TIME 26 10369
TEST_TIME 27 10816
TEST_TIME 28 10978
TEST_TIME 29 11165
TEST_TIME 30 10945
TEST_TIME 31 10992
TEST_TIME 32 10900
TEST_TIME 33 10804
TEST_TIME 34 10874
TEST_TIME 35 10819
TEST_TIME 36 10727
TEST_TIME 37 10490
TEST_TIME 38 10772
TEST_TIME 39 10799
TEST_TIME 40 10687
TEST_TIME 41 10718
TEST_TIME 42 10628
TEST_TIME 43 10653
TEST_TIME 44 10465
TEST_TIME 45 10251
TEST_TIME 46 10234
TEST_TIME 47 10140
TEST_TIME 48 10031
TEST_TIME 49 10027
TEST_TIME 50 10046
TEST_TIME 51 10097
TEST_TIME 52 10043
TEST_TIME 53 9986
TEST_TIME 54 9879
TEST_TIME 55 9957
TEST_TIME 56 9860
TEST_TIME 57 9837
TEST_TIME 58 9767
TEST_TIME 59 9771
TEST_TIME 60 9648
TEST_TIME 61 9621
TEST_TIME 62 9695
TEST_TIME 63 9685
TEST_TIME 64 9685
TEST_TIME 65 9668
TEST_TIME 66 9809
TEST_TIME 67 9830
TEST_TIME 68 9832
TEST_TIME 69 9785
TEST_TIME 70 9854
TEST_TIME 71 9913
TEST_TIME 72 9937
TEST_TIME 73 9985
TEST_TIME 74 9928
TEST_TIME 75 9974
TEST_TIME 76 9995
TEST_TIME 77 9937
TEST_TIME 78 9877
TEST_TIME 79 9818
TEST_TIME 80 9808
TEST_TIME 81 9809
TEST_TIME 82 9764
TEST_TIME 83 9693
TEST_TIME 84 9697
TEST_TIME 85 9681
TEST_TIME 86 9667
TEST_TIME 87 9623
TEST_TIME 88 9641
TEST_TIME 89 9654
TEST_TIME 90 9635
TEST_TIME 91 9678
TEST_TIME 92 9744
TEST_TIME 93 9713
TEST_TIME 94 9757
TEST_TIME 95 9764
TEST_TIME 96 9752
TEST_TIME 97 9757
TEST_TIME 98 9804
TEST_TIME 99 9796
TEST_TIME 100 9777
TEST_TIME 101 9805
TEST_TIME 102 9738
TEST_TIME 103 9717
TEST_TIME 104 9697
TEST_TIME 105 9684
TEST_TIME 106 9664
TEST_TIME 107 9634
TEST_TIME 108 9629
TEST_TIME 109 9601
TEST_TIME 110 9584
TEST_TIME 111 9626
TEST_TIME 112 9612
TEST_TIME 113 9623
TEST_TIME 114 9685
TEST_TIME 115 9658
TEST_TIME 116 9643
TEST_TIME 117 9661
TEST_TIME 118 9652
TEST_TIME 119 9708
TEST_TIME 120 9628

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-12-2021 22:38:43 to 19-12-2021 22:38:46.

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 1603640 1603640 0 0.00 0 16463 6 0 0
doc_list_biud 1603639 1603639 0 0.00 0 25704 25 0 0
sp_add_invoice_to_stock 5282 5211 71 1.34 25 186927 17671 0 71
sp_cancel_adding_invoice 5935 5935 0 0.00 5 59981 810 0 0
sp_cancel_client_order 1821 1821 0 0.00 4 12679 358 0 0
sp_cancel_customer_reserve 579842 579842 0 0.00 0 24800 55 0 0
sp_cancel_pay_from_customer 758 758 0 0.00 3 4388 73 0 0
sp_cancel_pay_to_supplier 449 449 0 0.00 3 5966 61 0 0
sp_cancel_supplier_invoice 6528 6523 5 0.07 1 21021 759 0 5
sp_cancel_supplier_order 406 406 0 0.00 9 1221234 100667 0 0
sp_cancel_write_off 11087 11087 0 0.00 1 19261 108 0 0
sp_client_order 21417 21417 0 0.00 3 20778 326 0 0
sp_customer_reserve 485256 485256 0 0.00 0 48200 173 0 0
sp_fill_shopping_cart 32876 32876 0 0.00 1 20814 40 0 0
sp_fill_shopping_cart_clo_res 485254 485254 0 0.00 0 17071 12 0 0
sp_get_clo_for_invoice 5209 5209 0 0.00 0 30543 297 0 0
sp_kill_cost_storno 18229 18229 0 0.00 0 15566 58 0 0
sp_kill_qstorno_ret_qs2qd 1177700 1177700 0 0.00 0 24794 30 0 0
sp_kill_qty_storno 621174 621174 0 0.00 0 25703 65 0 0
sp_lock_selected_doc 21629 21629 0 0.00 0 7953 4 0 0
sp_make_cost_storno 28757 28757 0 0.00 0 16448 55 0 0
sp_multiply_rows_for_pdistr 28757 28757 0 0.00 0 15700 33 0 0
sp_multiply_rows_for_qdistr 21417 21417 0 0.00 0 19147 155 0 0
sp_pay_from_customer 7247 7247 0 0.00 4 21569 120 0 0
sp_pay_to_supplier 5950 5950 0 0.00 5 18163 148 0 0
sp_payment_common 13197 13197 0 0.00 1 21561 33 0 0
sp_qd_handle_on_cancel_clo 1821 1821 0 0.00 0 12673 321 0 0
sp_qd_handle_on_invoice_upd_sts 11144 11144 0 0.00 0 23513 351 0 0
sp_qd_handle_on_reserve_upd_sts 10351 10351 0 0.00 0 25703 60 0 0
sp_reserve_write_off 10351 10351 0 0.00 6 25726 187 0 0
sp_supplier_invoice 5745 5745 0 0.00 7 104183 3515 0 0
sp_supplier_order 5722 5722 0 0.00 5 31026 1244 0 0
srv_aggregate_perf_data 111 111 0 0.00 2109 463458 21755 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1909 105891 42910 0 0
srv_find_qd_qs_mism 1105209 1105209 0 0.00 0 11445 5 0 0
srv_make_invnt_saldo 536 536 0 0.00 42 32113 1136 0 0
srv_make_money_saldo 803 803 0 0.00 1 594 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 180135 446257 302806 0 0
t$perf-abend:sp_add_invoice_to_stock 71 71 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5211 5211 0 0.00 0 16766 151 0 0
t$perf-norm:sp_cancel_adding_invoice 452 452 0 0.00 0 6443 92 0 0
t$perf-norm:sp_cancel_client_order 1821 1821 0 0.00 0 12663 18 0 0
t$perf-norm:sp_cancel_customer_reserve 677 677 0 0.00 0 3620 25 0 0
t$perf-norm:sp_cancel_pay_from_customer 758 758 0 0.00 0 1034 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 449 449 0 0.00 0 1070 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 426 426 0 0.00 0 4637 40 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 25099 1587 0 0
t$perf-norm:sp_cancel_write_off 1080 1080 0 0.00 0 2271 9 0 0
t$perf-norm:sp_client_order 21417 21417 0 0.00 0 12014 16 0 0
t$perf-norm:sp_customer_reserve 1032 1032 0 0.00 0 1151 3 0 0
t$perf-norm:sp_pay_from_customer 7247 7247 0 0.00 0 6848 9 0 0
t$perf-norm:sp_pay_to_supplier 5950 5950 0 0.00 0 8437 9 0 0
t$perf-norm:sp_reserve_write_off 10351 10351 0 0.00 0 5811 10 0 0
t$perf-norm:sp_supplier_invoice 5745 5745 0 0.00 0 12350 20 0 0
t$perf-norm:sp_supplier_order 5722 5722 0 0.00 0 13395 17 0 0
t$perf-norm:srv_aggregate_perf_data 111 111 0 0.00 0 1906 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1118 18 0 0
t$perf-norm:srv_make_invnt_saldo 536 536 0 0.00 0 1833 25 0 0
t$perf-norm:srv_make_money_saldo 803 803 0 0.00 0 1584 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10560 10418 142 1.34 0 9272 23 0 142
v_all_customers 39134 39134 0 0.00 0 139 1 0 0
v_all_suppliers 31562 31562 0 0.00 0 26 1 0 0
v_all_wares 42834 42834 0 0.00 0 129 1 0 0
v_cancel_adding_invoice 904 904 0 0.00 0 2001 8 0 0
v_cancel_client_order 3642 3642 0 0.00 0 8604 12 0 0
v_cancel_customer_prepayment 1516 1516 0 0.00 0 1606 6 0 0
v_cancel_customer_reserve 1354 1354 0 0.00 0 525 4 0 0
v_cancel_payment_to_supplier 898 898 0 0.00 0 1145 4 0 0
v_cancel_supplier_invoice 862 852 10 1.16 0 4183 21 0 10
v_cancel_supplier_order 812 812 0 0.00 0 1967 7 0 0
v_cancel_write_off 2160 2160 0 0.00 0 7272 16 0 0
v_max_id_clo_ord 5718 5718 0 0.00 0 541 1 0 0
v_max_id_clo_res 1032 1032 0 0.00 0 2385 16 0 0
v_max_id_ord_sup 5741 5741 0 0.00 0 6310 7 0 0
v_max_non_paid_invoice 1628 1628 0 0.00 0 189 2 0 0
v_max_non_paid_realizn 3885 3885 0 0.00 0 2152 4 0 0
v_min_id_clo_ord 5718 5718 0 0.00 0 19065 25 0 0
v_min_id_clo_res 1032 1032 0 0.00 0 1188 4 0 0
v_min_id_ord_sup 5741 5741 0 0.00 0 5826 8 0 0
v_min_non_paid_invoice 5950 5950 0 0.00 0 7995 21 0 0
v_min_non_paid_realizn 7247 7247 0 0.00 0 21555 29 0 0
v_our_firm 10424 10424 0 0.00 0 18 1 0 0
v_reserve_write_off 20702 20702 0 0.00 0 8601 9 0 0
x_lock_depdocs_on_canc_invoice 12032 12032 0 0.00 0 12889 118 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 24659 401 0 0
x_make_qstorno_1000_1200 5722 5722 0 0.00 2 23772 1144 0 0
x_make_qstorno_1200_2000 5745 5745 0 0.00 3 104170 3386 0 0
x_make_qstorno_2100_3300 485256 485256 0 0.00 0 48199 158 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-12-2021 22:38:47 to 19-12-2021 22:38:51.

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
20:08:06 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:07 22116 8192000000 9906475152 10220052480 987646096 10.759 100 87 174 0 1016314656 6265808 22402752
20:10:36 17648 8192000000 9949175616 10272071680 1026708480 11.137 100 87 174 0 1057225680 7834720 22682480
20:11:58 19114 8192000000 9984038016 10316349440 1063925856 11.494 100 86 173 0 1095830432 9119376 22785200
20:13:29 15366 8192000000 10042838256 10396344320 1131578288 12.136 100 80 160 0 1161350784 10428320 19344176
20:14:48 11074 8192000000 10093926624 10456064000 1178110624 12.573 100 83 166 2 1209651088 12482512 19057952
20:16:01 6415 8192000000 10132008800 10503913472 1219904272 12.961 100 65 130 0 1246600480 12127168 14569040
20:17:10 2452 8192000000 10171099168 10549358592 1255527808 13.289 100 89 179 0 1290127328 14715648 19883872
20:18:14 1940 8192000000 10195206736 10576814080 1278219696 13.497 100 87 173 1 1311129872 13605712 19304464
20:19:19 2448 8192000000 10205967216 10592559104 1292792496 13.630 100 91 178 2 1326140736 12873056 20475184
20:20:23 19477 8192000000 10219562672 10610507776 1303971248 13.731 100 71 144 1 1328003664 6795984 17236432
20:21:44 11414 8192000000 10198523792 10614595584 1309096752 13.778 100 28 56 1 1325947920 10558048 6293120
20:22:56 7657 8192000000 10205165888 10621992960 1317983888 13.858 100 19 38 0 1327710096 5887920 3838288
20:24:04 86502 8192000000 10217983568 10640109568 1325176160 13.924 100 44 88 1 1342019616 5598960 11244496
20:26:31 5181 8192000000 10222959408 10642259968 1328437040 13.953 100 28 56 1 1341060272 6099248 6523984
20:27:38 8980 8192000000 10214063520 10628939776 1298612064 13.683 98 31 63 2 1311465088 5799152 7053872
20:28:50 7788 8192000000 10184598544 10601107456 1263927968 13.366 97 14 28 3 1272078000 5411984 2738048
20:30:02 16822 8192000000 10169067088 10586333184 1274214144 13.460 99 34 68 0 1288339680 5213920 8911616
20:31:20 6152 8192000000 10131283888 10534404096 1205250096 12.825 96 38 76 0 1219917104 4734960 9932048
20:32:27 8430 8192000000 10145974928 10543218688 1239501264 13.142 99 25 50 1 1251364192 5684256 6178672
20:33:37 13900 8192000000 10174589216 10585518080 1282052960 13.532 100 17 34 1 1291677648 5539664 4085024
20:34:51 15192 8192000000 10164090272 10577219584 1276370784 13.480 100 19 39 4 1285379072 5085632 3922656
20:36:07 8353 8192000000 10190001872 10600718336 1296064608 13.659 100 17 34 1 1305114032 5048080 4001344
20:37:17 8087 8192000000 10192265968 10611384320 1304597904 13.737 100 19 38 1 1314203104 5270288 4334912
20:38:29 13236 8192000000 10227960336 10638159872 1325875216 13.930 100 34 68 2 1341204048 7675680 7653152
20:39:47 72556 8192000000 10263277824 10687897600 1359472000 14.233 100 42 83 3 1379965904 10709424 9784480
20:42:22 13159 8192000000 10289649376 10704805888 1372946288 14.353 100 45 91 1 1395238912 11040608 11252016
20:43:44 17298 8192000000 10276241136 10693730304 1364625792 14.279 99 41 82 3 1387431168 13253968 9551408
20:45:05 16345 8192000000 10298021296 10724823040 1383515296 14.448 100 41 82 3 1411014544 17968016 9531232
20:46:31 14188 8192000000 10318206032 10743906304 1384961936 14.461 99 59 118 1 1424676672 24691264 15023472
20:47:56 8397 8192000000 10324217648 10748444672 1398719200 14.584 100 53 107 0 1431392048 20085584 12587264
20:49:08 2640 8192000000 10328898432 10736984064 1375420048 14.376 99 87 173 1 1418101584 22016400 20665136
20:50:17 13438 8192000000 10319301616 10732453888 1371644928 14.342 99 70 141 0 1416542208 28614480 16282800
20:51:35 2832 8192000000 10337353648 10750439424 1398131264 14.578 100 75 145 2 1443362128 28420688 16810176
20:52:44 2871 8192000000 10352613072 10762022912 1392724336 14.530 99 79 158 1 1445955024 33593728 19636960
20:53:52 2658 8192000000 10357806896 10769027072 1407291328 14.660 100 84 168 1 1467128384 38588528 21248528
20:55:00 2679 8192000000 10352068720 10747977728 1366206848 14.293 98 91 182 2 1429443824 40596768 22640208
20:56:09 9024 8192000000 10340398016 10750504960 1378897520 14.407 98 60 118 1 1438056640 43459616 15699504
20:57:23 10170 8192000000 10344719888 10765811712 1393531680 14.537 100 40 80 0 1450963584 46553264 10878640
20:58:41 1909 8192000000 10347694064 10753708032 1376851792 14.388 99 82 163 1 1446061056 48358800 20850464
20:59:47 12489 8192000000 10336868432 10753576960 1371910528 14.344 99 43 84 2 1428135712 44712224 11512960
21:01:02 18914 8192000000 10334980736 10755063808 1367965664 14.309 99 43 87 0 1432624064 52030480 12627920
21:02:27 12612 8192000000 10326279584 10751901696 1377225344 14.392 100 34 68 1 1431136400 44466112 9444944
21:03:49 39224 8192000000 10327501120 10770087936 1370490960 14.331 100 22 44 0 1434315376 56521088 7303328
21:05:32 23400 8192000000 10287993168 10738348032 1357169824 14.212 100 12 24 2 1398197296 37537504 3489968
21:07:35 20669 8192000000 10283303984 10734325760 1372375424 14.348 100 18 36 1 1390248384 13644736 4228224
21:08:56 52703 8192000000 10296570128 10746695680 1382180736 14.436 100 22 42 1 1404128672 17210368 4737568
21:10:54 100318 8192000000 10304840912 10764746752 1383416256 14.447 99 15 30 0 1403727712 17061664 3249792
21:13:47 91101 8192000000 10306310928 10752630784 1371466912 14.340 98 6 12 0 1384269136 11677520 1124704
21:16:20 47180 8192000000 10315492304 10775949312 1356524960 14.206 97 7 13 0 1386119120 27406912 2187248
21:18:09 6492 8192000000 10327381152 10757808128 1389961680 14.506 100 70 138 1 1418604896 11504672 17138544
21:19:23 74847 8192000000 10310296704 10756472832 1365534480 14.287 98 14 29 1 1379790048 10925456 3330112
21:21:46 103538 8192000000 10316245408 10765344768 1401770752 14.611 100 17 34 1 1419174608 12442976 4960880
21:24:30 24863 8192000000 10329309056 10773946368 1409263184 14.677 100 44 88 0 1429969888 11249728 9456976
21:25:57 45219 8192000000 10319243888 10771095552 1398835536 14.585 99 14 28 0 1412303792 10350304 3117952
21:27:45 47994 8192000000 10322650224 10776911872 1395171808 14.552 99 24 49 2 1420233696 18987632 6074256
21:29:48 34366 8192000000 10288730208 10749554688 1365260496 14.285 97 17 34 0 1379407456 10415280 3731680
21:31:24 49472 8192000000 10290509264 10749620224 1353908064 14.183 97 13 26 0 1373351968 17000576 2443328
21:33:16 71028 8192000000 10289028352 10759462912 1377316608 14.393 99 12 24 1 1394641392 14585520 2739264
21:35:27 48029 8192000000 10292318176 10737541120 1366388128 14.295 99 38 75 1 1382996000 8000128 8607744
21:37:16 98538 8192000000 10277822048 10748891136 1366389472 14.295 99 16 32 0 1383178656 13188000 3601184
21:40:03 73943 8192000000 10268638304 10724446208 1284351024 13.553 93 12 24 0 1297953056 11285968 2316064
21:42:18 29950 8192000000 10222673472 10665099264 1254443664 13.279 92 27 55 1 1270673472 10314864 5914944
21:44:11 46084 8192000000 10251189136 10704117760 1296587344 13.664 96 13 26 0 1319890256 20184352 3118560
21:45:59 17507 8192000000 10274375952 10712137728 1353520112 14.179 100 53 104 1 1375105936 9849392 11736432
21:47:19 57997 8192000000 10269084128 10719625216 1329938608 13.967 98 26 52 1 1354898528 19236400 5723520
21:49:39 28568 8192000000 10262378352 10705211392 1348282144 14.132 100 21 42 0 1369389184 16329536 4777504
21:51:09 61475 8192000000 10266872688 10708107264 1296737088 13.666 95 18 36 0 1316265040 14785584 4742368
21:53:11 56091 8192000000 10270151600 10726285312 1351029696 14.157 99 16 32 0 1368385824 13723856 3632272
21:55:12 85809 8192000000 10291248096 10732396544 1367309872 14.303 100 33 66 1 1384294976 9783184 7201920
21:57:49 37278 8192000000 10271992640 10724884480 1347033808 14.121 99 35 70 0 1371774416 16555024 8185584
21:59:32 75798 8192000000 10262122640 10725875712 1334365104 14.007 97 11 23 0 1351824240 14714480 2744656
22:01:48 54101 8192000000 10276609152 10738434048 1365856320 14.290 99 13 26 1 1384378320 15486064 3035936
22:03:47 40343 8192000000 10276897312 10749804544 1383547616 14.448 100 11 22 0 1398607488 12653248 2406624
22:06:35 64732 8192000000 10304702672 10757144576 1367977568 14.309 98 16 32 0 1384542160 13071168 3493424
22:08:41 80867 8192000000 10300222784 10768138240 1382741680 14.441 99 20 40 0 1398270128 11228560 4299888
22:11:06 76519 8192000000 10307586304 10764800000 1364029088 14.274 97 16 32 0 1382904544 15291920 3583536
22:13:29 45603 8192000000 10301992336 10755362816 1372922704 14.353 98 34 65 2 1387985680 8248256 6814720
22:15:15 55715 8192000000 10315522672 10772135936 1396985856 14.568 100 34 68 0 1417198288 12834848 7377584
22:17:20 20484 8192000000 10301318192 10753232896 1375877600 14.380 98 26 52 0 1389756768 8069904 5809264
22:18:43 105891 8192000000 10291456144 10765766656 1374091408 14.364 99 12 24 1 1393885664 16943024 2851232
22:21:34 60547 8192000000 10277992400 10739183616 1362834848 14.263 98 19 38 0 1378870064 11520720 4514496
22:23:40 64002 8192000000 10287015552 10742558720 1386095392 14.471 100 17 35 1 1402948000 12711120 4141488
22:25:56 49493 8192000000 10255511104 10719690752 1324671728 13.919 96 9 18 0 1336296864 9753184 1871952
22:29:23 80874 8192000000 10275089760 10731204608 1305044464 13.741 94 11 22 0 1320359552 12894656 2420432
22:31:44 85462 8192000000 10271906848 10725842944 1319104528 13.869 97 10 20 0 1338927872 17646128 2177216
22:34:10 17248 8192000000 10250754272 10687918080 1324839200 13.921 99 44 88 0 1345672512 11365824 9467488
22:35:32 46990 8192000000 10254576480 10701139968 1312996048 13.813 97 23 46 1 1334631040 16515696 5119296
22:37:22 43269 8192000000 9006454800 9202962432 171215696 2.047 13 1 2 0 172417344 1159296 42352

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-12-2021 22:38:53 to 19-12-2021 22:39:08.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 71 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 19-12-2021 22:39:09 to 19-12-2021 22:39:11.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp30-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   12
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          247179
MON$OLDEST_ACTIVE               247180
MON$OLDEST_SNAPSHOT             247180
MON$NEXT_TRANSACTION            247180
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-03 16:41:55.3470
MON$PAGES                       2054624
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33546 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33546 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33546 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33546 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.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-oltp30 according to config parameter 'remove_isql_logs'

19.12.21 22:39:12. There are 3 logs in /var/tmp/logs-oltp30 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-19 22:39:15 No severe PSQL-related problems occured
19.12.21 22:39:16. End of report.