Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.09.2021 19: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 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15969756 kB
MemAvailable 30856036 kB
Buffers 0 kB
Cached 15699876 kB
SwapCached 340 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.8
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-09-07 19:37:44
DTS_END                         2021-09-07 19:37:44.5500
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(57:17)
IP                              ::1/57810
TRN_ID                          240286
ATT_ID                          642
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: 9579.13 24478 1149495
customer order: creation 173.98 390 20877
customer order: refuse 15.24 399 1829
order to supplier: creation 46.15 1342 5538
order to supplier: removal 3.74 110113 449
invoice (draft): creation 46.28 3420 5554
invoice (draft): removal 55.11 878 6613
invoice accept: apply 41.13 18714 4935
invoice accept: cancel 49.88 793 5986
customer reserve: creation 3918.76 178 470251
customer reserve: removal 4929.64 64 591557
realization accept: apply 83.98 195 10078
realization accept: cancel 83.16 120 9979
payment from customer: creation 59.91 116 7189
payment from customer: removal 6.10 106 732
payment to supplier: creation 49.78 137 5974
payment to supplier: removal 3.78 83 454
service: total inventory turnovers 4.75 1467 570
service: total monetary turnovers 6.96 46 835
service: aggregate perf. data 0.76 20887 91
service: refresh index statistics 0.03 330105 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-09-2021 19:39:04 to 07-09-2021 19:39:06.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2728
TEST_TIME 2 2699
TEST_TIME 3 2933
TEST_TIME 4 3834
TEST_TIME 5 4029
TEST_TIME 6 4143
TEST_TIME 7 5164
TEST_TIME 8 5735
TEST_TIME 9 5779
TEST_TIME 10 5951
TEST_TIME 11 6240
TEST_TIME 12 6384
TEST_TIME 13 6577
TEST_TIME 14 6713
TEST_TIME 15 6963
TEST_TIME 16 6827
TEST_TIME 17 7145
TEST_TIME 18 7263
TEST_TIME 19 7322
TEST_TIME 20 7596
TEST_TIME 21 7677
TEST_TIME 22 7844
TEST_TIME 23 8257
TEST_TIME 24 8526
TEST_TIME 25 8899
TEST_TIME 26 9471
TEST_TIME 27 9940
TEST_TIME 28 11056
TEST_TIME 29 11182
TEST_TIME 30 11190
TEST_TIME 31 11108
TEST_TIME 32 11057
TEST_TIME 33 10927
TEST_TIME 34 10682
TEST_TIME 35 10746
TEST_TIME 36 10699
TEST_TIME 37 10577
TEST_TIME 38 10669
TEST_TIME 39 10501
TEST_TIME 40 10311
TEST_TIME 41 10265
TEST_TIME 42 10171
TEST_TIME 43 10211
TEST_TIME 44 10407
TEST_TIME 45 10330
TEST_TIME 46 10233
TEST_TIME 47 10141
TEST_TIME 48 10086
TEST_TIME 49 9987
TEST_TIME 50 10058
TEST_TIME 51 9959
TEST_TIME 52 10001
TEST_TIME 53 9915
TEST_TIME 54 10003
TEST_TIME 55 10010
TEST_TIME 56 9995
TEST_TIME 57 10025
TEST_TIME 58 9913
TEST_TIME 59 10140
TEST_TIME 60 10088
TEST_TIME 61 10164
TEST_TIME 62 10202
TEST_TIME 63 10184
TEST_TIME 64 10060
TEST_TIME 65 9990
TEST_TIME 66 9910
TEST_TIME 67 9816
TEST_TIME 68 9858
TEST_TIME 69 9826
TEST_TIME 70 9759
TEST_TIME 71 9688
TEST_TIME 72 9622
TEST_TIME 73 9699
TEST_TIME 74 9704
TEST_TIME 75 9661
TEST_TIME 76 9602
TEST_TIME 77 9705
TEST_TIME 78 9688
TEST_TIME 79 9725
TEST_TIME 80 9716
TEST_TIME 81 9735
TEST_TIME 82 9732
TEST_TIME 83 9728
TEST_TIME 84 9754
TEST_TIME 85 9766
TEST_TIME 86 9813
TEST_TIME 87 9813
TEST_TIME 88 9864
TEST_TIME 89 9835
TEST_TIME 90 9740
TEST_TIME 91 9763
TEST_TIME 92 9710
TEST_TIME 93 9702
TEST_TIME 94 9650
TEST_TIME 95 9641
TEST_TIME 96 9615
TEST_TIME 97 9614
TEST_TIME 98 9601
TEST_TIME 99 9619
TEST_TIME 100 9589
TEST_TIME 101 9641
TEST_TIME 102 9646
TEST_TIME 103 9702
TEST_TIME 104 9676
TEST_TIME 105 9711
TEST_TIME 106 9713
TEST_TIME 107 9857
TEST_TIME 108 9851
TEST_TIME 109 9835
TEST_TIME 110 9756
TEST_TIME 111 9736
TEST_TIME 112 9693
TEST_TIME 113 9715
TEST_TIME 114 9697
TEST_TIME 115 9660
TEST_TIME 116 9630
TEST_TIME 117 9600
TEST_TIME 118 9559
TEST_TIME 119 9570
TEST_TIME 120 9581

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 07-09-2021 19:39:07 to 07-09-2021 19:39:11.

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 1585758 1585758 0 0.00 0 15729 6 0 0
doc_list_biud 1585758 1585758 0 0.00 0 46873 30 0 0
sp_add_invoice_to_stock 5063 4935 128 2.52 22 230034 18714 0 128
sp_cancel_adding_invoice 5986 5986 0 0.00 5 46483 794 0 0
sp_cancel_client_order 1829 1829 0 0.00 5 13673 400 0 0
sp_cancel_customer_reserve 591557 591557 0 0.00 1 46874 65 0 0
sp_cancel_pay_from_customer 732 732 0 0.00 2 6299 107 0 0
sp_cancel_pay_to_supplier 454 454 0 0.00 3 4396 83 0 0
sp_cancel_supplier_invoice 6624 6613 11 0.16 2 43062 879 0 11
sp_cancel_supplier_order 449 449 0 0.00 6 1095480 110113 0 0
sp_cancel_write_off 9979 9979 0 0.00 1 8073 120 0 0
sp_client_order 20877 20877 0 0.00 3 35320 390 0 0
sp_customer_reserve 470251 470251 0 0.00 0 163818 178 0 0
sp_fill_shopping_cart 31949 31949 0 0.00 1 23193 41 0 0
sp_fill_shopping_cart_clo_res 470232 470232 0 0.00 0 13898 15 0 0
sp_get_clo_for_invoice 4916 4916 0 0.00 0 29550 330 0 0
sp_kill_cost_storno 17151 17151 0 0.00 0 7932 64 0 0
sp_kill_qstorno_ret_qs2qd 1200155 1200155 0 0.00 0 46870 35 0 0
sp_kill_qty_storno 631407 631407 0 0.00 0 46872 76 0 0
sp_lock_selected_doc 21022 21022 0 0.00 0 8385 6 0 0
sp_make_cost_storno 28157 28157 0 0.00 0 15717 60 0 0
sp_multiply_rows_for_pdistr 28157 28157 0 0.00 0 6536 29 0 0
sp_multiply_rows_for_qdistr 20877 20877 0 0.00 0 35314 174 0 0
sp_pay_from_customer 7189 7189 0 0.00 4 12994 117 0 0
sp_pay_to_supplier 5974 5974 0 0.00 4 12620 137 0 0
sp_payment_common 13163 13163 0 0.00 1 12989 33 0 0
sp_qd_handle_on_cancel_clo 1829 1829 0 0.00 0 13664 365 0 0
sp_qd_handle_on_invoice_upd_sts 10902 10902 0 0.00 0 40802 378 0 0
sp_qd_handle_on_reserve_upd_sts 10078 10078 0 0.00 0 20577 59 0 0
sp_reserve_write_off 10078 10078 0 0.00 6 20632 195 0 0
sp_supplier_invoice 5554 5554 0 0.00 7 99188 3421 0 0
sp_supplier_order 5538 5538 0 0.00 5 46319 1342 0 0
srv_aggregate_perf_data 91 91 0 0.00 2743 123404 20887 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1775 224358 49204 0 0
srv_find_qd_qs_mism 1101415 1101415 0 0.00 0 14099 5 0 0
srv_make_invnt_saldo 570 570 0 0.00 21 79484 1468 0 0
srv_make_money_saldo 835 835 0 0.00 1 9321 46 0 0
srv_recalc_idx_stat 4 4 0 0.00 206857 428592 330106 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4935 4935 0 0.00 0 21322 202 0 0
t$perf-norm:sp_cancel_adding_invoice 369 369 0 0.00 1 7791 110 0 0
t$perf-norm:sp_cancel_client_order 1829 1829 0 0.00 0 8151 16 0 0
t$perf-norm:sp_cancel_customer_reserve 765 765 0 0.00 0 915 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 732 732 0 0.00 0 5041 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 454 454 0 0.00 0 8345 22 0 0
t$perf-norm:sp_cancel_supplier_invoice 403 403 0 0.00 0 873 17 0 0
t$perf-norm:sp_cancel_supplier_order 449 449 0 0.00 0 33291 1808 0 0
t$perf-norm:sp_cancel_write_off 1027 1027 0 0.00 0 3994 15 0 0
t$perf-norm:sp_client_order 20877 20877 0 0.00 0 11154 19 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 6026 21 0 0
t$perf-norm:sp_pay_from_customer 7189 7189 0 0.00 0 5419 8 0 0
t$perf-norm:sp_pay_to_supplier 5974 5974 0 0.00 0 4329 8 0 0
t$perf-norm:sp_reserve_write_off 10078 10078 0 0.00 0 20714 23 0 0
t$perf-norm:sp_supplier_invoice 5554 5554 0 0.00 0 8039 24 0 0
t$perf-norm:sp_supplier_order 5538 5538 0 0.00 0 10728 20 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 2769 38 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 570 570 0 0.00 0 8791 42 0 0
t$perf-norm:srv_make_money_saldo 835 835 0 0.00 0 698 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 108 30 0 0
v_add_invoice_to_stock 10088 9832 256 2.53 0 6652 21 0 256
v_all_customers 38006 38006 0 0.00 0 154 1 0 0
v_all_suppliers 31174 31174 0 0.00 0 14 1 0 0
v_all_wares 41754 41754 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 738 738 0 0.00 0 3858 13 0 0
v_cancel_client_order 3658 3658 0 0.00 0 5593 7 0 0
v_cancel_customer_prepayment 1464 1464 0 0.00 0 5624 14 0 0
v_cancel_customer_reserve 1530 1530 0 0.00 0 1816 7 0 0
v_cancel_payment_to_supplier 908 908 0 0.00 0 1211 7 0 0
v_cancel_supplier_invoice 828 806 22 2.65 0 6851 25 0 22
v_cancel_supplier_order 898 898 0 0.00 0 4421 12 0 0
v_cancel_write_off 2054 2054 0 0.00 0 4369 21 0 0
v_max_id_clo_ord 5528 5528 0 0.00 0 3447 2 0 0
v_max_id_clo_res 990 990 0 0.00 0 2267 18 0 0
v_max_id_ord_sup 5544 5544 0 0.00 0 3358 2 0 0
v_max_non_paid_invoice 1459 1459 0 0.00 0 2213 5 0 0
v_max_non_paid_realizn 3902 3902 0 0.00 0 1161 3 0 0
v_min_id_clo_ord 5528 5528 0 0.00 0 3974 8 0 0
v_min_id_clo_res 990 990 0 0.00 0 10472 26 0 0
v_min_id_ord_sup 5544 5544 0 0.00 0 8330 10 0 0
v_min_non_paid_invoice 5974 5974 0 0.00 0 10737 20 0 0
v_min_non_paid_realizn 7189 7189 0 0.00 0 5871 20 0 0
v_our_firm 10322 10322 0 0.00 0 17 1 0 0
v_reserve_write_off 20156 20156 0 0.00 0 8701 11 0 0
x_lock_depdocs_on_canc_invoice 12196 12196 0 0.00 0 16517 122 0 0
x_lock_depdocs_on_canc_sup_ord 449 449 0 0.00 0 4616 331 0 0
x_make_qstorno_1000_1200 5538 5538 0 0.00 2 46307 1263 0 0
x_make_qstorno_1200_2000 5554 5554 0 0.00 4 98907 3268 0 0
x_make_qstorno_2100_3300 470251 470251 0 0.00 0 163817 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-09-2021 19:39:12 to 07-09-2021 19:39:16.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:07:48 6 8192000000 8807297888 8820133888 12097920 0.147 3 1 2 0 12218544 58064 62560
17:08:49 31027 8192000000 10375995344 10697162752 1431145984 14.871 100 89 178 0 1466836720 6485856 29204880
17:10:33 16356 8192000000 10439361504 10771386368 1497260608 15.452 100 89 178 1 1534252912 8639168 28353136
17:11:53 17028 8192000000 10492823088 10833035264 1538425008 15.810 100 80 160 1 1573033376 9476576 25131792
17:13:15 8058 8192000000 10569379040 10923692032 1624459376 16.548 100 84 168 0 1660899024 10844032 25595616
17:14:26 9070 8192000000 10635239072 11008581632 1700300752 17.188 100 82 162 2 1736148576 12229408 23618416
17:15:42 3300 8192000000 10708787120 11086139392 1772846640 17.791 100 81 160 0 1807303472 11903136 22553696
17:16:49 2799 8192000000 10756311776 11139330048 1814351472 18.131 100 76 151 2 1847232848 12335712 20545664
17:17:53 2175 8192000000 10806382640 11191713792 1855290480 18.465 100 94 188 0 1896173344 15050512 25832352
17:18:58 6799 8192000000 10805905216 11207020544 1874873776 18.624 100 67 133 1 1902626736 9864288 17888672
17:20:06 3352 8192000000 10796153104 11214036992 1879963232 18.665 100 54 108 0 1905785456 10748832 15073392
17:21:10 5215 8192000000 10798483776 11223056384 1887003632 18.722 100 30 60 1 1902900160 7141728 8754800
17:22:16 8861 8192000000 10805928496 11237097472 1896988208 18.802 100 14 28 3 1910787200 10377904 3421088
17:23:25 43828 8192000000 10829386304 11260600320 1910594304 18.911 100 19 38 0 1925490816 10104800 4791712
17:25:13 5270 8192000000 10840612800 11270012928 1921956544 19.003 100 38 76 0 1938547952 5768928 10822480
17:26:21 3431 8192000000 10843091488 11265048576 1899887040 18.825 99 30 59 1 1914487040 6064208 8535792
17:27:27 11240 8192000000 10815615968 11249426432 1872961488 18.608 98 18 36 1 1883285200 5440528 4883184
17:28:38 14921 8192000000 10793304400 11222061056 1810702592 18.102 95 24 49 0 1823188160 6085984 6399584
17:29:55 10884 8192000000 10730796688 11150245888 1732224432 17.454 94 26 52 0 1745664528 5688688 7751408
17:31:07 8140 8192000000 10736195360 11149398016 1796691664 17.987 99 24 48 1 1812725536 8958848 7075024
17:32:18 64338 8192000000 10731119376 11156221952 1791283744 17.942 98 20 40 1 1801966432 5062688 5620000
17:34:23 4935 8192000000 10762105408 11196522496 1857029824 18.479 100 25 50 0 1870207792 5869296 7308672
17:35:31 8700 8192000000 10772755920 11210600448 1866082496 18.553 100 13 26 1 1875248928 5697536 3468896
17:36:43 21202 8192000000 10801254848 11230011392 1880529008 18.669 100 24 49 0 1894185760 5609136 8047616
17:38:07 70119 8192000000 10847475616 11273027584 1920320064 18.989 100 47 94 2 1940001264 6353584 13327616
17:40:38 43049 8192000000 10884773872 11322294272 1957639296 19.287 100 43 86 1 1981016976 10889520 12488160
17:42:30 36285 8192000000 10905646176 11343241216 1972596352 19.406 100 38 76 1 2001216320 17464960 11155008
17:44:09 9982 8192000000 10926482032 11364118528 1986062848 19.513 100 60 118 2 2022613152 19076896 17473408
17:45:26 4807 8192000000 10943010176 11370287104 1990771328 19.550 100 58 116 2 2030046416 21750560 17524528
17:46:39 1893 8192000000 10955451216 11380887552 1996683040 19.597 100 71 143 0 2040065072 23045600 20336432
17:47:45 24642 8192000000 10969268784 11408494592 2016948672 19.756 100 60 118 1 2061306192 26966992 17390528
17:49:12 4330 8192000000 11000280000 11423514624 2018803920 19.771 99 85 167 1 2073168704 29699680 24665104
17:50:28 6328 8192000000 10999390208 11428102144 2025372848 19.822 100 77 153 1 2083770960 35548736 22849376
17:51:40 7961 8192000000 11007416736 11439091712 2033821200 19.889 100 81 161 2 2095369552 36092144 25456208
17:52:54 6835 8192000000 11016011856 11448410112 2043845872 19.967 100 70 139 1 2101210416 35694528 21670016
17:54:09 5816 8192000000 11023290912 11441741824 2032497504 19.878 100 81 161 2 2101412736 44400480 24514752
17:55:21 1854 8192000000 11024559840 11438587904 2025130624 19.820 100 94 185 1 2100444672 47318736 27995312
17:56:32 2170 8192000000 11014022080 11428282368 1995062912 19.584 98 96 189 5 2076502272 51760240 29679120
17:57:42 2749 8192000000 11020483104 11440365568 2015601344 19.746 100 83 166 1 2096134464 54182288 26350832
17:58:50 2078 8192000000 10997938384 11424153600 1993793296 19.574 100 69 137 3 2076669648 59917088 22959264
17:59:59 1773 8192000000 10989093360 11397640192 1966814544 19.360 100 91 183 2 2057508464 61929040 28764880
18:01:10 18592 8192000000 10948439648 11388153856 1947605472 19.207 99 45 90 1 2027373408 63723888 16044048
18:02:35 18864 8192000000 10943488080 11399622656 1944643168 19.184 99 36 72 1 2017134928 58858672 13633088
18:03:57 21695 8192000000 10912175776 11382497280 1942534080 19.167 100 22 44 2 2000885168 50143632 8207456
18:05:58 54540 8192000000 10883102896 11350843392 1926105040 19.036 99 12 24 0 1951400368 21745392 3549936
18:07:58 82853 8192000000 10869851616 11318218752 1875377808 18.628 96 14 26 0 1890637216 11889968 3369440
18:10:21 135400 8192000000 10912747328 11361079296 1955507200 19.270 100 29 58 0 1978053184 13997008 8548976
18:13:40 88729 8192000000 10874153664 11329843200 1875012288 18.625 96 17 33 1 1889559440 9397504 5149648
18:16:10 138493 8192000000 10919847904 11362398208 1912226400 18.925 97 34 68 0 1933757440 10474592 11056448
18:19:30 26995 8192000000 10933518128 11359395840 1954809616 19.265 99 33 66 0 1980481376 15677584 9994176
18:21:09 43879 8192000000 10902398000 11384668160 1978683936 19.454 100 27 52 1 2006114336 19261104 8169296
18:23:01 224332 8192000000 10881782336 11359793152 1900899600 18.834 96 16 32 0 1916800992 11399504 4501888
18:27:50 62968 8192000000 10908491952 11379195904 1980795792 19.471 100 13 27 1 1996669568 12440560 3433216
18:30:34 22912 8192000000 10910488272 11373633536 1981032752 19.473 100 52 105 2 2008943856 13298832 14612272
18:32:02 50202 8192000000 10922162944 11401162752 1980379680 19.468 100 8 16 0 2001460976 19103824 1977472
18:33:55 37143 8192000000 10913660208 11383123968 1968318080 19.372 99 21 42 1 1990203232 15901168 5983984
18:35:35 76538 8192000000 10906141104 11393740800 1967547584 19.366 99 13 26 1 1990121344 18757056 3816704
18:37:52 64866 8192000000 10857187984 11330879488 1867107424 18.561 94 10 20 0 1885752192 15732400 2912368
18:39:57 106490 8192000000 10879482400 11342467072 1882349360 18.684 96 17 35 1 1900298928 13062784 4886784
18:43:12 76171 8192000000 10891750816 11335364608 1917194464 18.964 97 35 70 2 1935861840 8771232 9896144
18:45:31 44753 8192000000 10862751216 11344527360 1905552720 18.871 97 16 33 1 1920582656 10494288 4535648
18:47:16 48311 8192000000 10868198352 11325657088 1903430768 18.854 97 29 58 0 1924885648 12789360 8665520
18:49:05 46828 8192000000 10825668160 11297366016 1837211408 18.318 95 11 22 1 1865403360 24921568 3270384
18:51:04 94712 8192000000 10840478720 11323920384 1906638240 18.880 99 13 26 1 1925405904 14862256 3905408
18:53:48 29423 8192000000 10851663040 11328176128 1910567504 18.911 99 27 54 1 1930068064 11639536 7861024
18:56:10 51316 8192000000 10839655376 11306565632 1882711232 18.687 98 13 26 0 1893959360 8074112 3174016
18:58:05 66248 8192000000 10838072736 11305680896 1848360816 18.409 96 36 72 1 1870754288 12188928 10204544
19:00:21 50507 8192000000 10859383024 11309936640 1841714672 18.355 95 31 62 0 1859818560 9588032 8515856
19:02:18 10139 8192000000 10856768000 11299401728 1898751056 18.816 99 44 88 1 1922416176 11607104 12058016
19:03:34 50938 8192000000 10876418832 11328929792 1932310960 19.085 100 17 34 1 1945582496 8585104 4686432
19:05:26 42305 8192000000 10881476112 11343130624 1943387040 19.174 100 9 18 0 1955436320 9451792 2597488
19:07:09 103360 8192000000 10870091312 11344285696 1918391872 18.974 98 20 40 0 1932698800 8822544 5484384
19:10:02 16467 8192000000 10875791808 11327369216 1915811216 18.953 98 31 61 1 1932308096 8628880 7868000
19:11:27 58689 8192000000 10859825392 11340754944 1931420576 19.078 99 8 16 0 1943810144 10145744 2243824
19:13:29 17153 8192000000 10886624240 11336552448 1920896880 18.994 98 29 59 0 1934799840 5639728 8263232
19:14:50 40207 8192000000 10891820560 11363024896 1963698240 19.335 100 17 34 0 1979843184 10957584 5187360
19:16:34 41515 8192000000 10892873120 11358670848 1928412480 19.054 97 25 50 0 1951072736 14826112 7834144
19:18:20 67453 8192000000 10890169952 11356901376 1877377856 18.644 95 16 32 0 1894335072 12464416 4492800
19:20:28 61706 8192000000 10906369008 11369861120 1951197968 19.236 99 20 40 0 1968238144 10981152 6059024
19:22:30 74564 8192000000 10901613472 11365109760 1943730976 19.177 98 21 42 1 1970046768 20104480 6211312
19:24:52 73701 8192000000 10886808064 11359444992 1892547136 18.766 96 13 26 0 1909328048 13088928 3691984
19:27:06 141515 8192000000 10867220240 11335516160 1865549376 18.548 95 22 44 0 1884764944 13024496 6191072
19:30:29 72316 8192000000 10850068336 11326291968 1866356816 18.555 96 13 26 0 1878918848 8658464 3903568
19:32:45 84092 8192000000 10856956096 11337502720 1912121792 18.924 98 14 28 1 1930699392 14839264 3738336
19:35:16 31122 8192000000 10853354160 11321798656 1919208240 18.980 99 20 40 1 1936820832 11939360 5673232
19:37:00 46913 8192000000 9712686832 10063110144 715612256 8.033 37 7 14 0 720616032 3067088 1936688

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 07-09-2021 19:39:18 to 07-09-2021 19:39:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-09-2021 19:39:33 to 07-09-2021 19:39:35.

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          241124
MON$OLDEST_ACTIVE               241125
MON$OLDEST_SNAPSHOT             241125
MON$NEXT_TRANSACTION            241125
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                       2045664
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


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

07.09.21 19:39:36. 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-09-07 19:39:39 No severe PSQL-related problems occured
07.09.21 19:39:40. End of report.