Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.08.2021 16: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 15999136 kB
MemAvailable 30911156 kB
Buffers 0 kB
Cached 15651300 kB
SwapCached 1324 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-08-27 16:37:50
DTS_END                         2021-08-27 16:37:50.1590
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/51298
TRN_ID                          251219
ATT_ID                          705
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: 9392.02 25155 1127042
customer order: creation 187.50 315 22500
customer order: refuse 16.27 326 1953
order to supplier: creation 50.66 1250 6079
order to supplier: removal 3.38 114690 406
invoice (draft): creation 49.80 3191 5976
invoice (draft): removal 50.65 1112 6078
invoice accept: apply 45.53 17326 5463
invoice accept: cancel 45.10 844 5412
customer reserve: creation 4165.09 173 499811
customer reserve: removal 4467.98 65 536157
realization accept: apply 90.49 182 10859
realization accept: cancel 78.03 101 9363
payment from customer: creation 64.51 117 7741
payment from customer: removal 6.12 79 734
payment to supplier: creation 53.60 125 6432
payment to supplier: removal 3.88 85 466
service: total inventory turnovers 5.29 1131 635
service: total monetary turnovers 7.25 40 870
service: aggregate perf. data 0.86 22651 103
service: refresh index statistics 0.03 339289 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-08-2021 16:38:55 to 27-08-2021 16:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2941
TEST_TIME 2 3504
TEST_TIME 3 4421
TEST_TIME 4 5428
TEST_TIME 5 5235
TEST_TIME 6 5136
TEST_TIME 7 5293
TEST_TIME 8 5292
TEST_TIME 9 5605
TEST_TIME 10 5751
TEST_TIME 11 6220
TEST_TIME 12 6835
TEST_TIME 13 6919
TEST_TIME 14 7128
TEST_TIME 15 7077
TEST_TIME 16 7304
TEST_TIME 17 7627
TEST_TIME 18 7709
TEST_TIME 19 7512
TEST_TIME 20 7854
TEST_TIME 21 8088
TEST_TIME 22 8220
TEST_TIME 23 8325
TEST_TIME 24 8571
TEST_TIME 25 8444
TEST_TIME 26 8555
TEST_TIME 27 9127
TEST_TIME 28 9911
TEST_TIME 29 10787
TEST_TIME 30 10938
TEST_TIME 31 10967
TEST_TIME 32 11045
TEST_TIME 33 11131
TEST_TIME 34 11063
TEST_TIME 35 10898
TEST_TIME 36 10899
TEST_TIME 37 10829
TEST_TIME 38 10721
TEST_TIME 39 10634
TEST_TIME 40 10442
TEST_TIME 41 10377
TEST_TIME 42 10279
TEST_TIME 43 10380
TEST_TIME 44 10326
TEST_TIME 45 10368
TEST_TIME 46 10368
TEST_TIME 47 10461
TEST_TIME 48 10379
TEST_TIME 49 10268
TEST_TIME 50 10084
TEST_TIME 51 10088
TEST_TIME 52 10041
TEST_TIME 53 9980
TEST_TIME 54 9908
TEST_TIME 55 9875
TEST_TIME 56 9833
TEST_TIME 57 9799
TEST_TIME 58 9925
TEST_TIME 59 9838
TEST_TIME 60 9767
TEST_TIME 61 9713
TEST_TIME 62 9673
TEST_TIME 63 9597
TEST_TIME 64 9555
TEST_TIME 65 9517
TEST_TIME 66 9487
TEST_TIME 67 9484
TEST_TIME 68 9461
TEST_TIME 69 9529
TEST_TIME 70 9546
TEST_TIME 71 9549
TEST_TIME 72 9573
TEST_TIME 73 9536
TEST_TIME 74 9576
TEST_TIME 75 9540
TEST_TIME 76 9623
TEST_TIME 77 9620
TEST_TIME 78 9609
TEST_TIME 79 9657
TEST_TIME 80 9684
TEST_TIME 81 9617
TEST_TIME 82 9549
TEST_TIME 83 9552
TEST_TIME 84 9479
TEST_TIME 85 9468
TEST_TIME 86 9401
TEST_TIME 87 9348
TEST_TIME 88 9315
TEST_TIME 89 9313
TEST_TIME 90 9372
TEST_TIME 91 9374
TEST_TIME 92 9384
TEST_TIME 93 9363
TEST_TIME 94 9376
TEST_TIME 95 9450
TEST_TIME 96 9454
TEST_TIME 97 9467
TEST_TIME 98 9445
TEST_TIME 99 9543
TEST_TIME 100 9535
TEST_TIME 101 9521
TEST_TIME 102 9482
TEST_TIME 103 9593
TEST_TIME 104 9589
TEST_TIME 105 9536
TEST_TIME 106 9495
TEST_TIME 107 9476
TEST_TIME 108 9420
TEST_TIME 109 9462
TEST_TIME 110 9436
TEST_TIME 111 9402
TEST_TIME 112 9378
TEST_TIME 113 9349
TEST_TIME 114 9351
TEST_TIME 115 9332
TEST_TIME 116 9379
TEST_TIME 117 9376
TEST_TIME 118 9367
TEST_TIME 119 9370
TEST_TIME 120 9395

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-08-2021 16:38:59 to 27-08-2021 16:39:02.

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 1588338 1588338 0 0.00 0 23235 6 0 0
doc_list_biud 1588338 1588338 0 0.00 0 30888 29 0 0
sp_add_invoice_to_stock 5597 5463 134 2.39 49 277719 17327 0 134
sp_cancel_adding_invoice 5412 5412 0 0.00 5 51352 845 0 0
sp_cancel_client_order 1953 1953 0 0.00 4 9168 326 0 0
sp_cancel_customer_reserve 536157 536157 0 0.00 1 27720 65 0 0
sp_cancel_pay_from_customer 734 734 0 0.00 2 4289 79 0 0
sp_cancel_pay_to_supplier 466 466 0 0.00 3 6966 86 0 0
sp_cancel_supplier_invoice 6098 6078 20 0.32 2 26876 1113 0 20
sp_cancel_supplier_order 406 406 0 0.00 6 1073191 114691 0 0
sp_cancel_write_off 9363 9363 0 0.00 1 14775 102 0 0
sp_client_order 22500 22500 0 0.00 3 25461 316 0 0
sp_customer_reserve 499811 499811 0 0.00 0 125256 173 0 0
sp_fill_shopping_cart 34535 34535 0 0.00 1 14015 38 0 0
sp_fill_shopping_cart_clo_res 499806 499806 0 0.00 0 13972 12 0 0
sp_get_clo_for_invoice 5447 5447 0 0.00 0 21602 235 0 0
sp_kill_cost_storno 15975 15975 0 0.00 0 3899 55 0 0
sp_kill_qstorno_ret_qs2qd 1088161 1088161 0 0.00 0 27698 36 0 0
sp_kill_qty_storno 575671 575671 0 0.00 0 30887 79 0 0
sp_lock_selected_doc 22511 22511 0 0.00 0 7241 5 0 0
sp_make_cost_storno 30475 30475 0 0.00 0 23225 52 0 0
sp_multiply_rows_for_pdistr 30475 30475 0 0.00 0 7018 25 0 0
sp_multiply_rows_for_qdistr 22500 22500 0 0.00 0 10846 120 0 0
sp_pay_from_customer 7741 7741 0 0.00 4 25654 117 0 0
sp_pay_to_supplier 6432 6432 0 0.00 5 25478 125 0 0
sp_payment_common 14173 14173 0 0.00 1 19383 27 0 0
sp_qd_handle_on_cancel_clo 1953 1953 0 0.00 0 9162 301 0 0
sp_qd_handle_on_invoice_upd_sts 10855 10855 0 0.00 0 30884 358 0 0
sp_qd_handle_on_reserve_upd_sts 10859 10859 0 0.00 0 26312 62 0 0
sp_reserve_write_off 10859 10859 0 0.00 6 26615 182 0 0
sp_supplier_invoice 5976 5976 0 0.00 6 94696 3192 0 0
sp_supplier_order 6079 6079 0 0.00 6 85180 1251 0 0
srv_aggregate_perf_data 103 103 0 0.00 2858 427852 22652 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1483 172844 47137 0 0
srv_find_qd_qs_mism 1074330 1074330 0 0.00 0 14754 6 0 0
srv_make_invnt_saldo 635 635 0 0.00 38 153805 1131 0 0
srv_make_money_saldo 870 870 0 0.00 0 16242 40 0 0
srv_recalc_idx_stat 4 4 0 0.00 193011 486559 339290 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 20 20 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5463 5463 0 0.00 0 12137 152 0 0
t$perf-norm:sp_cancel_adding_invoice 404 404 0 0.00 0 4223 95 0 0
t$perf-norm:sp_cancel_client_order 1953 1953 0 0.00 0 1102 10 0 0
t$perf-norm:sp_cancel_customer_reserve 721 721 0 0.00 0 917 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 734 734 0 0.00 0 1142 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 466 466 0 0.00 0 902 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 458 458 0 0.00 0 732 19 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 21536 1795 0 0
t$perf-norm:sp_cancel_write_off 1067 1067 0 0.00 0 9264 16 0 0
t$perf-norm:sp_client_order 22500 22500 0 0.00 0 4908 16 0 0
t$perf-norm:sp_customer_reserve 1106 1106 0 0.00 0 1962 10 0 0
t$perf-norm:sp_pay_from_customer 7741 7741 0 0.00 0 6558 11 0 0
t$perf-norm:sp_pay_to_supplier 6432 6432 0 0.00 0 9269 11 0 0
t$perf-norm:sp_reserve_write_off 10859 10859 0 0.00 0 4557 9 0 0
t$perf-norm:sp_supplier_invoice 5976 5976 0 0.00 0 4267 17 0 0
t$perf-norm:sp_supplier_order 6079 6079 0 0.00 0 4669 17 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 343 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 419 16 0 0
t$perf-norm:srv_make_invnt_saldo 635 635 0 0.00 0 1638 24 0 0
t$perf-norm:srv_make_money_saldo 870 870 0 0.00 0 1673 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 11154 10886 268 2.40 0 14260 21 0 268
v_all_customers 40922 40922 0 0.00 0 276 1 0 0
v_all_suppliers 33304 33304 0 0.00 0 26 1 0 0
v_all_wares 45000 45000 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 808 808 0 0.00 0 741 7 0 0
v_cancel_client_order 3906 3906 0 0.00 0 6952 7 0 0
v_cancel_customer_prepayment 1468 1468 0 0.00 0 2064 6 0 0
v_cancel_customer_reserve 1442 1442 0 0.00 0 5713 11 0 0
v_cancel_payment_to_supplier 932 932 0 0.00 0 4084 9 0 0
v_cancel_supplier_invoice 956 916 40 4.18 0 2445 18 0 40
v_cancel_supplier_order 812 812 0 0.00 0 2017 10 0 0
v_cancel_write_off 2134 2134 0 0.00 0 2485 14 0 0
v_max_id_clo_ord 6079 6079 0 0.00 0 631 1 0 0
v_max_id_clo_res 1106 1106 0 0.00 0 3156 11 0 0
v_max_id_ord_sup 5956 5956 0 0.00 0 8331 5 0 0
v_max_non_paid_invoice 1815 1815 0 0.00 0 1177 4 0 0
v_max_non_paid_realizn 4153 4153 0 0.00 0 898 3 0 0
v_min_id_clo_ord 6079 6079 0 0.00 0 3699 6 0 0
v_min_id_clo_res 1106 1106 0 0.00 0 1697 7 0 0
v_min_id_ord_sup 5956 5956 0 0.00 0 8220 7 0 0
v_min_non_paid_invoice 6432 6432 0 0.00 0 6685 19 0 0
v_min_non_paid_realizn 7741 7741 0 0.00 0 11165 15 0 0
v_our_firm 11254 11254 0 0.00 0 14 1 0 0
v_reserve_write_off 21718 21718 0 0.00 0 22165 11 0 0
x_lock_depdocs_on_canc_invoice 11032 11032 0 0.00 0 20913 125 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 10733 361 0 0
x_make_qstorno_1000_1200 6079 6079 0 0.00 2 85161 1166 0 0
x_make_qstorno_1200_2000 5976 5976 0 0.00 3 94683 3061 0 0
x_make_qstorno_2100_3300 499811 499811 0 0.00 0 125256 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-08-2021 16:39:03 to 27-08-2021 16:39:07.

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
14:07:54 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
14:08:54 27846 8192000000 10374690400 10696187904 1428476480 14.848 100 80 159 1 1459728640 6196960 25055200
14:10:28 26103 8192000000 10458575584 10791866368 1504967744 15.519 100 92 184 0 1543426160 9169504 29288912
14:11:58 15232 8192000000 10502783296 10844454912 1552326816 15.930 100 90 180 0 1591475824 10951184 28197824
14:13:23 9181 8192000000 10537572496 10896441344 1607063424 16.400 100 74 149 0 1639914112 10877120 21973568
14:14:37 10635 8192000000 10624871888 10993586176 1686357104 17.071 100 72 145 3 1719212176 11732352 21122720
14:15:51 6151 8192000000 10719572144 11095371776 1773455600 17.796 100 76 150 1 1805804400 11739056 20609744
14:16:59 2593 8192000000 10742984992 11125960704 1804368416 18.050 100 84 169 2 1841944272 14925360 22650496
14:18:04 2367 8192000000 10790360016 11176538112 1845927072 18.389 100 92 184 2 1884987008 12250592 26809344
14:19:08 15250 8192000000 10790131840 11189665792 1861941472 18.519 100 69 136 1 1891942192 10556320 19444400
14:20:25 11623 8192000000 10770388464 11202244608 1870529216 18.589 100 43 85 1 1891152736 8835152 11788368
14:21:47 1547 8192000000 10809033792 11222458368 1887505056 18.726 100 51 100 1 1911016672 9902736 13608880
14:22:49 4732 8192000000 10806092688 11229052928 1889452160 18.741 100 31 62 1 1906544576 7664544 9427872
14:23:56 6231 8192000000 10799234080 11232415744 1894966720 18.786 100 17 33 2 1904696960 5556464 4173776
14:25:03 44820 8192000000 10805811328 11245780992 1902623184 18.847 100 35 68 1 1918930912 5980192 10327536
14:26:50 6648 8192000000 10823914048 11255291904 1910818624 18.913 100 24 49 0 1924484992 6282528 7383840
14:27:57 5079 8192000000 10784095712 11214012416 1852754112 18.444 98 22 44 1 1864526736 5030464 6742160
14:29:09 63682 8192000000 10764942480 11159302144 1777490240 17.829 96 44 88 0 1797380224 6354528 13535456
14:31:12 7385 8192000000 10709328176 11124461568 1741061040 17.527 96 31 59 4 1754420960 4517776 8842144
14:32:21 8572 8192000000 10676513200 11091025920 1743379920 17.547 97 27 50 1 1761149152 10029248 7739984
14:33:33 21353 8192000000 10738030576 11152809984 1799705168 18.011 99 26 52 1 1813641360 6630208 7305984
14:34:55 9644 8192000000 10761510272 11177754624 1836333888 18.311 100 20 40 0 1847654272 5192864 6127520
14:36:05 7374 8192000000 10761122976 11189972992 1850913168 18.430 100 22 44 3 1862807472 5395760 6498544
14:37:18 86902 8192000000 10815933632 11235409920 1882964928 18.689 100 36 70 1 1898921872 6096624 9860320
14:39:52 3442 8192000000 10846959360 11261853696 1915649872 18.952 100 59 116 0 1942626720 10345712 16631136
14:41:01 8437 8192000000 10868977104 11291537408 1932337808 19.086 100 50 100 1 1957754928 11201952 14215168
14:42:16 8607 8192000000 10895742208 11312771072 1953909312 19.258 100 62 124 0 1982785824 12237920 16638592
14:43:31 13647 8192000000 10905726592 11327885312 1962491248 19.326 100 54 106 2 1990001552 12219248 15291056
14:44:50 10521 8192000000 10905108944 11339907072 1973038208 19.410 100 56 112 2 2004376512 14877920 16460384
14:46:13 16606 8192000000 10920312768 11358470144 1981389088 19.476 100 41 82 0 2013129952 19633520 12107344
14:47:33 5997 8192000000 10943473152 11352264704 1949623504 19.223 98 74 149 3 1991459312 20399760 21436048
14:48:42 4594 8192000000 10941563904 11372371968 1987157792 19.521 100 59 118 1 2029676400 24461776 18056832
14:49:49 5577 8192000000 10947529984 11378405376 1993706160 19.573 100 65 128 0 2035309392 22745952 18857280
14:50:59 7843 8192000000 10957104976 11387613184 1998581424 19.612 100 61 122 1 2044801728 27869360 18350944
14:52:14 5046 8192000000 10988701856 11412934656 2015090576 19.742 100 73 146 0 2069519008 31562496 22865936
14:53:25 7177 8192000000 10961146032 11389198336 1992802832 19.566 100 65 129 3 2049338464 35101968 21433664
14:54:42 1481 8192000000 10987411920 11398131712 1998899504 19.614 100 89 181 1 2066609296 39802928 27906864
14:55:52 3419 8192000000 10960120032 11384344576 1969258080 19.380 99 66 132 0 2033772336 43415280 21098976
14:57:04 2662 8192000000 10985107888 11400830976 1984359872 19.499 99 80 157 1 2055945792 46694272 24891648
14:58:11 5975 8192000000 10964964992 11391905792 1985064944 19.505 100 57 113 0 2048900912 45814400 18021568
14:59:24 2622 8192000000 10970929056 11389431808 1972107952 19.402 99 80 158 1 2043768016 46093312 25566752
15:00:31 14964 8192000000 10945408992 11381444608 1969457232 19.381 100 36 72 1 2030348128 48341376 12549520
15:01:57 11178 8192000000 10927079120 11367460864 1955301328 19.269 100 33 66 3 2014302992 47302992 11698672
15:03:36 24194 8192000000 10927925056 11373395968 1937509888 19.127 100 38 76 0 2018386912 65989472 14887552
15:05:04 19512 8192000000 10912907504 11380498432 1919593328 18.984 99 36 72 1 1999721264 67037328 13090608
15:06:39 22094 8192000000 10925720496 11373690880 1966260464 19.356 100 33 66 2 2008868368 31725344 10882560
15:09:32 39057 8192000000 10892296272 11377008640 1945339248 19.189 99 21 42 2 1976920480 25315152 6266080
15:11:12 108863 8192000000 10899206000 11363201024 1959814464 19.305 100 9 18 0 1974947376 12941600 2191312
15:14:01 57210 8192000000 10881260912 11337801728 1946074944 19.195 99 12 25 0 1965398912 15714928 3609040
15:15:58 73632 8192000000 10906194912 11364429824 1953427056 19.254 99 19 37 0 1965210528 6973024 4810448
15:18:12 70826 8192000000 10942380224 11393425408 1980996624 19.473 99 24 48 1 2002144176 14670080 6477472
15:20:31 63471 8192000000 10910217920 11380858880 1962297472 19.324 99 17 34 1 1982281136 15016320 4967344
15:22:35 99591 8192000000 10927016720 11399557120 1972971584 19.409 99 10 20 0 1994027264 18417456 2638224
15:25:16 81474 8192000000 10929351248 11397677056 1993208240 19.569 100 22 44 1 2014998640 14799168 6991232
15:27:48 82980 8192000000 10918322512 11374125056 1960275280 19.308 98 31 61 2 1976932448 7157920 9499248
15:30:15 157885 8192000000 10906348864 11377065984 1882856976 18.688 94 25 48 2 1898844016 8604400 7382640
15:33:54 14201 8192000000 10913798896 11367854080 1961974528 19.322 99 35 71 4 1981834384 9838560 10021296
15:35:09 60328 8192000000 10896153840 11378999296 1942739008 19.169 98 27 54 2 1964772560 14069584 7963968
15:37:12 172833 8192000000 10866014480 11341103104 1826632608 18.232 92 12 25 0 1843265760 12904848 3728304
15:41:05 97759 8192000000 10858348096 11338878976 1871066144 18.593 95 11 22 1 1880650768 6598768 2985856
15:43:44 56758 8192000000 10846554080 11317923840 1869571520 18.581 96 21 42 0 1883226480 7896464 5758496
15:45:42 45618 8192000000 10846628032 11303522304 1828261984 18.245 94 21 42 0 1849688928 14920208 6506736
15:47:27 14644 8192000000 10835715136 11267162112 1852837360 18.445 97 39 75 4 1870856176 7185024 10833792
15:48:46 30425 8192000000 10852251232 11311226880 1895787824 18.792 98 22 44 0 1909484400 7865312 5831264
15:50:21 17606 8192000000 10885463536 11329458176 1935189328 19.108 100 41 81 2 1954791760 8898304 10704128
15:51:45 103400 8192000000 10871843600 11344957440 1907745856 18.889 98 27 54 3 1930343568 14977968 7619744
15:54:31 24601 8192000000 10883205520 11332050944 1909313040 18.901 98 38 74 1 1928778688 8366624 11099024
15:55:57 58808 8192000000 10855389680 11319033856 1892295376 18.764 97 17 34 0 1908383136 11261072 4826688
15:57:59 114869 8192000000 10866473680 11335180288 1934671136 19.104 99 27 54 1 1952309088 9879072 7758880
16:01:28 15602 8192000000 10915269296 11369779200 1974803120 19.424 100 50 99 2 2001911936 11719584 15389232
16:02:44 64956 8192000000 10883997264 11362578432 1950191344 19.228 99 9 18 1 1964024816 11378704 2454768
16:04:49 59073 8192000000 10900007312 11378257920 1946980048 19.202 98 16 32 0 1966853680 14388672 5484960
16:06:51 72885 8192000000 10888261792 11364245504 1915328736 18.949 97 19 38 2 1932378016 11767472 5281808
16:09:09 61489 8192000000 10913643472 11390808064 1971472224 19.397 99 23 46 1 1988356512 10475808 6408480
16:11:11 89413 8192000000 10928426464 11409133568 1981091408 19.473 99 10 20 0 2003315680 19247648 2976624
16:13:42 40239 8192000000 10918693168 11390988288 1944360544 19.182 97 37 74 2 1965707856 11245216 10102096
16:15:23 24064 8192000000 10910244704 11374305280 1942245280 19.165 98 24 47 4 1963681376 15074480 6361616
16:16:50 128014 8192000000 10882361664 11343839232 1805408368 18.058 91 13 26 2 1821740416 12688688 3643360
16:19:58 72815 8192000000 10871378480 11350859776 1909964928 18.906 98 26 52 0 1936397136 18180352 8251856
16:22:20 92019 8192000000 10853966176 11304275968 1884930368 18.705 97 38 76 2 1903758544 6530592 12297584
16:25:52 31358 8192000000 10850624256 11326201856 1926586944 19.040 100 22 44 0 1943350880 10439344 6324592
16:28:15 29225 8192000000 10842356624 11309133824 1871946816 18.600 97 12 22 0 1883824064 8828368 3048880
16:29:46 72147 8192000000 10831250960 11304640512 1875889456 18.632 97 19 38 0 1893986016 11625312 6471248
16:32:05 45174 8192000000 10843144656 11315601408 1900003072 18.826 99 11 22 0 1915968256 13083392 2881792
16:33:50 72589 8192000000 10867994512 11331108864 1851982336 18.438 95 16 32 1 1865610240 9214704 4413200
16:36:09 59118 8192000000 10832957456 11296370688 1876340608 18.636 98 22 44 0 1895805776 13066208 6398960

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-08-2021 16:39:09 to 27-08-2021 16:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 v_add_invoice_to_stock 335544517
except 20 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 27-08-2021 16:39:25 to 27-08-2021 16:39:26.

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          248995
MON$OLDEST_ACTIVE               251472
MON$OLDEST_SNAPSHOT             251472
MON$NEXT_TRANSACTION            251472
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                       2050208
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.33489 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33489 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33489 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33489 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 ---
:594: 
:595: mscs-test-161.budzdorov.ru    Fri Aug 27 16:37:52 2021
:596:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:597: 
:598: 
:599: mscs-test-161.budzdorov.ru    Fri Aug 27 16:38:00 2021
:600:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:601: 
--- end of diff output ---

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

27.08.21 16:39:28. 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-08-27 16:39:30 No severe PSQL-related problems occured
27.08.21 16:39:31. End of report.