Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.09.2021 01: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 15875220 kB
MemAvailable 30726048 kB
Buffers 0 kB
Cached 15786248 kB
SwapCached 2964 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-29 01:37:50
DTS_END                         2021-09-29 01:37:50.7360
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/50838
TRN_ID                          242842
ATT_ID                          670
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: 9522.48 19957 1142698
customer order: creation 174.85 372 20982
customer order: refuse 14.97 372 1796
order to supplier: creation 46.08 1352 5530
order to supplier: removal 3.50 104496 420
invoice (draft): creation 47.66 3367 5719
invoice (draft): removal 53.97 787 6476
invoice accept: apply 43.15 18164 5178
invoice accept: cancel 48.78 765 5853
customer reserve: creation 4032.33 178 483879
customer reserve: removal 4754.39 59 570527
realization accept: apply 85.96 196 10315
realization accept: cancel 84.35 106 10122
payment from customer: creation 60.51 151 7261
payment from customer: removal 5.88 87 706
payment to supplier: creation 50.20 137 6024
payment to supplier: removal 3.43 82 412
service: total inventory turnovers 5.11 1156 613
service: total monetary turnovers 6.48 24 778
service: aggregate perf. data 0.86 22611 103
service: refresh index statistics 0.03 244685 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-09-2021 01:39:00 to 29-09-2021 01:39:02.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3921
TEST_TIME 2 3524
TEST_TIME 3 4370
TEST_TIME 4 4876
TEST_TIME 5 4944
TEST_TIME 6 5002
TEST_TIME 7 5172
TEST_TIME 8 5385
TEST_TIME 9 5244
TEST_TIME 10 5475
TEST_TIME 11 5691
TEST_TIME 12 5655
TEST_TIME 13 5786
TEST_TIME 14 6225
TEST_TIME 15 6414
TEST_TIME 16 6400
TEST_TIME 17 6382
TEST_TIME 18 6585
TEST_TIME 19 6700
TEST_TIME 20 7220
TEST_TIME 21 7178
TEST_TIME 22 7827
TEST_TIME 23 8210
TEST_TIME 24 8273
TEST_TIME 25 8600
TEST_TIME 26 8775
TEST_TIME 27 9018
TEST_TIME 28 9105
TEST_TIME 29 9636
TEST_TIME 30 10238
TEST_TIME 31 9982
TEST_TIME 32 10161
TEST_TIME 33 10200
TEST_TIME 34 10095
TEST_TIME 35 9993
TEST_TIME 36 9838
TEST_TIME 37 9903
TEST_TIME 38 10046
TEST_TIME 39 9928
TEST_TIME 40 9974
TEST_TIME 41 10026
TEST_TIME 42 10168
TEST_TIME 43 10146
TEST_TIME 44 9950
TEST_TIME 45 9812
TEST_TIME 46 9786
TEST_TIME 47 9790
TEST_TIME 48 9652
TEST_TIME 49 9679
TEST_TIME 50 9690
TEST_TIME 51 9587
TEST_TIME 52 9647
TEST_TIME 53 9755
TEST_TIME 54 9647
TEST_TIME 55 9674
TEST_TIME 56 9617
TEST_TIME 57 9565
TEST_TIME 58 9495
TEST_TIME 59 9421
TEST_TIME 60 9408
TEST_TIME 61 9465
TEST_TIME 62 9363
TEST_TIME 63 9344
TEST_TIME 64 9296
TEST_TIME 65 9356
TEST_TIME 66 9338
TEST_TIME 67 9495
TEST_TIME 68 9516
TEST_TIME 69 9519
TEST_TIME 70 9581
TEST_TIME 71 9632
TEST_TIME 72 9609
TEST_TIME 73 9595
TEST_TIME 74 9740
TEST_TIME 75 9830
TEST_TIME 76 9806
TEST_TIME 77 9728
TEST_TIME 78 9658
TEST_TIME 79 9639
TEST_TIME 80 9677
TEST_TIME 81 9616
TEST_TIME 82 9669
TEST_TIME 83 9615
TEST_TIME 84 9614
TEST_TIME 85 9548
TEST_TIME 86 9492
TEST_TIME 87 9477
TEST_TIME 88 9442
TEST_TIME 89 9411
TEST_TIME 90 9495
TEST_TIME 91 9454
TEST_TIME 92 9541
TEST_TIME 93 9544
TEST_TIME 94 9533
TEST_TIME 95 9511
TEST_TIME 96 9498
TEST_TIME 97 9547
TEST_TIME 98 9584
TEST_TIME 99 9561
TEST_TIME 100 9629
TEST_TIME 101 9642
TEST_TIME 102 9632
TEST_TIME 103 9681
TEST_TIME 104 9643
TEST_TIME 105 9558
TEST_TIME 106 9585
TEST_TIME 107 9536
TEST_TIME 108 9519
TEST_TIME 109 9505
TEST_TIME 110 9497
TEST_TIME 111 9465
TEST_TIME 112 9489
TEST_TIME 113 9447
TEST_TIME 114 9446
TEST_TIME 115 9462
TEST_TIME 116 9513
TEST_TIME 117 9543
TEST_TIME 118 9567
TEST_TIME 119 9544
TEST_TIME 120 9531

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 29-09-2021 01:39:04 to 29-09-2021 01:39:07.

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 1588525 1588525 0 0.00 0 16130 7 0 0
doc_list_biud 1588522 1588522 0 0.00 0 31876 27 0 0
sp_add_invoice_to_stock 5270 5178 92 1.74 47 165424 18164 0 92
sp_cancel_adding_invoice 5853 5853 0 0.00 6 87679 766 0 0
sp_cancel_client_order 1796 1796 0 0.00 4 19186 373 0 0
sp_cancel_customer_reserve 570527 570527 0 0.00 1 31878 60 0 0
sp_cancel_pay_from_customer 706 706 0 0.00 2 8994 87 0 0
sp_cancel_pay_to_supplier 412 412 0 0.00 3 4566 83 0 0
sp_cancel_supplier_invoice 6481 6476 5 0.07 1 13397 787 0 5
sp_cancel_supplier_order 420 420 0 0.00 5 920825 104497 0 0
sp_cancel_write_off 10122 10122 0 0.00 1 8206 106 0 0
sp_client_order 20982 20982 0 0.00 3 20891 372 0 0
sp_customer_reserve 483879 483879 0 0.00 0 37648 179 0 0
sp_fill_shopping_cart 32222 32222 0 0.00 1 8459 37 0 0
sp_fill_shopping_cart_clo_res 483869 483869 0 0.00 0 13742 13 0 0
sp_get_clo_for_invoice 5160 5160 0 0.00 0 23131 282 0 0
sp_kill_cost_storno 17093 17093 0 0.00 0 8985 60 0 0
sp_kill_qstorno_ret_qs2qd 1158072 1158072 0 0.00 0 31873 32 0 0
sp_kill_qty_storno 610669 610669 0 0.00 0 31876 70 0 0
sp_lock_selected_doc 21389 21389 0 0.00 0 7578 6 0 0
sp_make_cost_storno 28760 28760 0 0.00 0 14476 57 0 0
sp_multiply_rows_for_pdistr 28760 28760 0 0.00 0 16127 34 0 0
sp_multiply_rows_for_qdistr 20982 20982 0 0.00 0 20880 156 0 0
sp_pay_from_customer 7261 7261 0 0.00 4 31801 151 0 0
sp_pay_to_supplier 6024 6024 0 0.00 5 14686 138 0 0
sp_payment_common 13285 13285 0 0.00 2 31756 36 0 0
sp_qd_handle_on_cancel_clo 1796 1796 0 0.00 0 19176 343 0 0
sp_qd_handle_on_invoice_upd_sts 11013 11013 0 0.00 0 23147 335 0 0
sp_qd_handle_on_reserve_upd_sts 10315 10315 0 0.00 0 23966 67 0 0
sp_reserve_write_off 10315 10315 0 0.00 6 23998 197 0 0
sp_supplier_invoice 5719 5719 0 0.00 6 78713 3367 0 0
sp_supplier_order 5530 5530 0 0.00 5 27650 1352 0 0
srv_aggregate_perf_data 103 103 0 0.00 2847 347402 22611 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1170 148741 42783 0 0
srv_find_qd_qs_mism 1092403 1092403 0 0.00 0 15642 6 0 0
srv_make_invnt_saldo 613 613 0 0.00 15 57220 1157 0 0
srv_make_money_saldo 778 778 0 0.00 0 1439 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 183821 275502 244685 0 0
t$perf-abend:sp_add_invoice_to_stock 92 92 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 5178 5178 0 0.00 0 9127 158 0 0
t$perf-norm:sp_cancel_adding_invoice 437 437 0 0.00 0 4966 99 0 0
t$perf-norm:sp_cancel_client_order 1796 1796 0 0.00 0 4554 12 0 0
t$perf-norm:sp_cancel_customer_reserve 685 685 0 0.00 0 2920 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 706 706 0 0.00 0 1201 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 412 412 0 0.00 0 4869 20 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 2010 19 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 20410 1642 0 0
t$perf-norm:sp_cancel_write_off 1048 1048 0 0.00 0 2759 6 0 0
t$perf-norm:sp_client_order 20982 20982 0 0.00 0 5790 17 0 0
t$perf-norm:sp_customer_reserve 1052 1052 0 0.00 0 5789 11 0 0
t$perf-norm:sp_pay_from_customer 7261 7261 0 0.00 0 5354 13 0 0
t$perf-norm:sp_pay_to_supplier 6024 6024 0 0.00 0 4285 11 0 0
t$perf-norm:sp_reserve_write_off 10315 10315 0 0.00 0 5364 12 0 0
t$perf-norm:sp_supplier_invoice 5719 5719 0 0.00 0 3013 16 0 0
t$perf-norm:sp_supplier_order 5530 5530 0 0.00 0 8256 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 293 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1511 25 0 0
t$perf-norm:srv_make_invnt_saldo 613 613 0 0.00 0 1959 22 0 0
t$perf-norm:srv_make_money_saldo 778 778 0 0.00 0 1050 15 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10504 10320 184 1.75 0 4813 20 0 184
v_all_customers 38530 38530 0 0.00 0 28 1 0 0
v_all_suppliers 31216 31216 0 0.00 0 17 1 0 0
v_all_wares 41964 41964 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 874 874 0 0.00 0 2783 19 0 0
v_cancel_client_order 3592 3592 0 0.00 0 4113 7 0 0
v_cancel_customer_prepayment 1412 1412 0 0.00 0 3469 13 0 0
v_cancel_customer_reserve 1370 1370 0 0.00 0 2968 8 0 0
v_cancel_payment_to_supplier 824 824 0 0.00 0 2055 7 0 0
v_cancel_supplier_invoice 830 820 10 1.20 0 3167 29 0 10
v_cancel_supplier_order 840 840 0 0.00 0 2279 7 0 0
v_cancel_write_off 2096 2096 0 0.00 0 5858 19 0 0
v_max_id_clo_ord 5530 5530 0 0.00 0 1579 1 0 0
v_max_id_clo_res 1052 1052 0 0.00 0 3473 17 0 0
v_max_id_ord_sup 5710 5710 0 0.00 0 4579 4 0 0
v_max_non_paid_invoice 1656 1656 0 0.00 0 780 3 0 0
v_max_non_paid_realizn 3819 3819 0 0.00 0 1174 3 0 0
v_min_id_clo_ord 5530 5530 0 0.00 0 4854 6 0 0
v_min_id_clo_res 1052 1052 0 0.00 0 470 3 0 0
v_min_id_ord_sup 5710 5710 0 0.00 0 5358 8 0 0
v_min_non_paid_invoice 6024 6024 0 0.00 0 7497 17 0 0
v_min_non_paid_realizn 7261 7261 0 0.00 0 24074 25 0 0
v_our_firm 10318 10318 0 0.00 0 13 1 0 0
v_reserve_write_off 20630 20630 0 0.00 0 7130 8 0 0
x_lock_depdocs_on_canc_invoice 11919 11919 0 0.00 0 16191 118 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 5217 354 0 0
x_make_qstorno_1000_1200 5530 5530 0 0.00 2 27609 1278 0 0
x_make_qstorno_1200_2000 5719 5719 0 0.00 3 78702 3236 0 0
x_make_qstorno_2100_3300 483877 483877 0 0.00 0 30905 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 29-09-2021 01:39:08 to 29-09-2021 01:39:12.

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
23:07:54 1 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
23:08:55 26982 8192000000 10366135248 10683072512 1419710192 14.770 100 90 180 0 1454531760 5877888 28943680
23:10:25 15997 8192000000 10416676800 10741858304 1467169792 15.189 100 87 174 0 1503841984 7894688 28777504
23:11:48 17861 8192000000 10461616112 10798723072 1520303328 15.653 100 88 176 0 1557271520 8967008 28001184
23:13:11 11322 8192000000 10533920224 10889617408 1591296320 16.265 100 74 149 0 1623347376 9732544 22318512
23:14:25 11933 8192000000 10628294928 10993287168 1679709104 17.015 100 75 149 0 1713263536 12253184 21301248
23:15:39 6905 8192000000 10698532912 11071479808 1755139904 17.644 100 87 174 2 1792531952 13079088 24312960
23:16:52 7041 8192000000 10740926224 11125485568 1810271952 18.098 100 73 145 0 1841383504 10602336 20509216
23:18:00 3209 8192000000 10794319648 11183521792 1854395712 18.458 100 91 181 0 1893294832 13238752 25660368
23:19:05 2977 8192000000 10812101312 11198976000 1873066624 18.609 100 97 194 0 1913965680 13533632 27365424
23:20:11 8780 8192000000 10822293568 11214331904 1876628832 18.638 100 69 135 2 1909406720 12562848 20215040
23:21:21 6153 8192000000 10789948944 11214516224 1884692976 18.703 100 40 76 2 1902894704 7126080 11075648
23:22:31 7409 8192000000 10798568656 11231453184 1896796208 18.801 100 30 58 1 1910516528 5928496 7791824
23:23:39 59181 8192000000 10823876512 11249778688 1908510800 18.895 100 30 60 3 1922565440 5297104 8757536
23:25:40 6362 8192000000 10828877824 11257147392 1911146544 18.916 100 40 77 3 1929371376 7177872 11046960
23:26:47 6773 8192000000 10832260000 11264507904 1917111520 18.964 100 29 58 0 1931836944 6714912 8010512
23:27:57 14600 8192000000 10777668016 11209134080 1798902752 18.005 95 13 26 1 1811586704 9321376 3362576
23:29:12 20685 8192000000 10778354512 11205902336 1829005392 18.251 97 43 86 0 1847653568 5998240 12649936
23:30:35 4276 8192000000 10751569760 11169546240 1819782192 18.176 99 38 75 0 1841391360 10805600 10803568
23:31:39 5307 8192000000 10703928864 11124985856 1777846048 17.832 99 24 48 0 1791073872 6123088 7104736
23:32:46 7675 8192000000 10710336416 11134124032 1802645824 18.036 100 24 48 2 1815009728 5428096 6935808
23:33:55 16745 8192000000 10748512640 11174117376 1835197264 18.302 100 35 70 1 1852031712 7399328 9435120
23:35:14 9245 8192000000 10766801520 11195482112 1856044592 18.471 100 31 61 0 1870847344 6413008 8389744
23:36:24 7982 8192000000 10772515440 11207647232 1866523456 18.556 100 18 36 0 1876819536 5267984 5028096
23:37:35 12396 8192000000 10819497072 11244208128 1899782480 18.825 100 21 42 1 1910452320 5055168 5614672
23:38:53 62199 8192000000 10834226096 11265327104 1911942144 18.922 100 35 70 0 1927551808 5940688 9668976
23:40:59 12917 8192000000 10881546352 11304648704 1946086608 19.195 100 50 100 0 1970017184 9093248 14837328
23:42:16 8708 8192000000 10898298928 11323031552 1961944880 19.321 100 60 120 2 1989244128 10470912 16828336
23:43:27 8235 8192000000 10908641024 11339767808 1974565328 19.422 100 47 95 2 1999877072 11647664 13664080
23:44:40 36353 8192000000 10926636720 11364134912 1987375792 19.523 100 38 76 1 2012488400 13613120 11499488
23:46:25 9880 8192000000 10931423408 11370225664 1997300352 19.601 100 55 110 1 2027631920 13923888 16407680
23:47:44 7432 8192000000 10958139824 11383574528 1999711424 19.620 100 73 145 1 2046672384 26224896 20736064
23:48:57 12168 8192000000 10939188608 11376037888 1992541744 19.564 100 51 100 2 2028142800 19701104 15899952
23:50:24 6105 8192000000 10982925344 11402739712 2011390592 19.712 100 71 142 1 2061628368 28528768 21709008
23:51:35 10492 8192000000 10948787872 11381596160 1991339152 19.554 100 69 137 4 2037156608 24926912 20890544
23:52:50 1816 8192000000 10967698976 11379781632 1992801696 19.566 100 85 170 0 2044368592 25708736 25858160
23:54:00 8919 8192000000 10961472864 11398098944 2000504944 19.627 100 49 98 1 2047445984 31236256 15704784
23:55:26 5961 8192000000 10951872624 11380125696 1982416496 19.484 100 68 132 1 2036846400 32914416 21515488
23:56:42 1169 8192000000 10983620912 11402235904 2003322672 19.649 100 81 163 0 2064843552 35313104 26207776
23:57:49 10985 8192000000 10922468688 11359854592 1957841824 19.289 99 46 92 3 2006194208 32064096 16288288
23:59:10 2964 8192000000 10945434944 11355533312 1936472384 19.119 99 89 179 4 2011985520 46985440 28527696
00:00:18 12088 8192000000 10934593312 11381952512 1980230976 19.467 100 35 70 1 2023821792 32190480 11400336
00:01:32 7466 8192000000 10946404208 11386023936 1980084416 19.465 100 40 80 0 2031222672 37076816 14061440
00:02:56 18410 8192000000 10904029488 11354636288 1925710416 19.033 98 34 69 2 1973419920 36641984 11067520
00:04:20 35844 8192000000 10882340192 11332354048 1901124000 18.835 98 27 54 1 1951899168 41514000 9261168
00:05:58 11143 8192000000 10882425792 11338600448 1914795792 18.945 100 33 64 1 1968581952 42687904 11098256
00:07:13 57066 8192000000 10838409728 11291090944 1869202432 18.578 97 12 24 0 1893290960 20898640 3189888
00:09:11 81639 8192000000 10870029632 11336208384 1924210720 19.021 99 15 30 2 1945406000 17150384 4044896
00:11:34 50069 8192000000 10828853104 11284799488 1851457328 18.434 97 11 22 0 1869039008 14518160 3063520
00:13:25 30877 8192000000 10894228208 11323809792 1948543232 19.215 100 62 124 1 1974088512 7016096 18529184
00:15:03 76489 8192000000 10893356480 11340267520 1889258272 18.740 97 4 8 0 1903937696 13445712 1233712
00:17:24 43424 8192000000 10900042672 11361468416 1943016496 19.171 99 21 42 1 1965181504 15891696 6273312
00:19:09 142973 8192000000 10927170368 11403350016 1974216624 19.419 100 10 20 0 2000311312 23582752 2511936
00:22:48 61183 8192000000 10911976528 11378278400 1958500128 19.294 98 34 68 0 1985079280 15336160 11242992
00:24:55 25660 8192000000 10926293616 11376205824 1980438784 19.468 100 37 73 2 1999694224 8790976 10464464
00:26:23 15965 8192000000 10916256512 11391045632 1992994624 19.567 100 18 36 0 2006836752 8922976 4919152
00:27:42 22870 8192000000 10934294864 11382030336 1979861936 19.464 99 31 62 1 1998666544 10181376 8623232
00:29:05 41313 8192000000 10915124864 11389784064 1974082464 19.418 99 35 69 2 1999675968 14954848 10638656
00:31:05 41697 8192000000 10914455200 11389394944 1989444480 19.539 100 9 17 2 1999572368 7577504 2550384
00:32:51 37821 8192000000 10921885856 11396145152 1985398160 19.507 99 18 36 0 1998378672 7365776 5614736
00:34:30 96013 8192000000 10938244416 11401732096 1981969120 19.480 99 16 32 0 1993452704 7265856 4217728
00:37:14 39032 8192000000 10898621968 11376717824 1950885840 19.234 98 16 32 1 1966130640 11148880 4095920
00:38:59 60811 8192000000 10924477472 11409358848 1994840784 19.582 100 7 14 0 2007287520 10938800 1507936
00:41:00 65767 8192000000 10939473920 11424608256 1995889312 19.590 99 22 44 2 2017808832 15551856 6367664
00:43:39 45022 8192000000 10911223200 11383209984 1938471696 19.135 98 20 41 3 1969481136 24726336 6283104
00:45:24 40591 8192000000 10873173648 11342053376 1899802576 18.825 97 24 48 0 1920964912 14198144 6964192
00:47:05 66111 8192000000 10870969008 11345502208 1880174944 18.667 97 12 23 0 1907441248 23594672 3671632
00:49:16 18196 8192000000 10894463872 11341152256 1938562752 19.135 100 49 95 1 1961842336 10164336 13115248
00:50:35 46217 8192000000 10896515792 11368824832 1942970576 19.170 99 21 42 1 1972073472 23116128 5986768
00:52:25 81708 8192000000 10883472896 11342458880 1842054624 18.358 95 12 24 0 1862881008 18002880 2823504
00:54:50 46749 8192000000 10862403408 11324608512 1933661520 19.096 100 35 70 1 1950170144 6386608 10122016
00:56:44 83599 8192000000 10876860848 11358973952 1930838976 19.074 99 22 44 0 1953760400 16820128 6101296
00:59:12 64702 8192000000 10877151104 11360464896 1943268688 19.173 99 11 22 1 1954417920 8292672 2856560
01:01:17 51875 8192000000 10871052976 11356200960 1949003776 19.219 100 17 34 1 1961562432 7793312 4765344
01:03:13 67222 8192000000 10852079712 11332222976 1917533056 18.967 99 12 24 0 1929398800 8411712 3454032
01:05:27 10232 8192000000 10866246912 11330650112 1919584432 18.984 99 34 68 0 1938334432 8724384 10025616
01:06:44 49894 8192000000 10869986288 11351752704 1923441968 19.014 99 12 24 2 1943253776 16148832 3662976
01:08:34 88781 8192000000 10886699408 11362799616 1929925968 19.066 98 15 31 0 1946577808 12673088 3978752
01:11:06 36300 8192000000 10900428896 11368017920 1949383296 19.222 99 25 46 2 1964596960 8670416 6543248
01:12:47 19948 8192000000 10922393888 11373694976 1957502864 19.286 99 49 98 0 1983006176 12134736 13368576
01:14:08 59268 8192000000 10909862752 11375009792 1939143472 19.140 98 15 30 1 1959532272 15591376 4797424
01:16:11 19826 8192000000 10922999280 11380404224 1963477856 19.334 99 30 60 1 1980998928 9793888 7727184
01:17:31 80129 8192000000 10909670896 11379081216 1939153232 19.140 98 12 24 0 1951968448 9995920 2819296
01:19:52 148740 8192000000 10895374816 11374813184 1927937696 19.050 97 25 50 0 1951263824 15886800 7439328
01:23:40 41403 8192000000 10895673312 11360743424 1952065648 19.243 99 32 59 1 1965272480 5586336 7620496
01:25:24 63872 8192000000 10877239024 11358679040 1909300832 18.901 97 21 42 1 1926638800 11619168 5718800
01:27:32 53306 8192000000 10886488960 11361394688 1908814544 18.897 97 15 30 0 1924433008 11288528 4329936
01:29:28 72371 8192000000 10896443360 11370782720 1940133728 19.148 98 21 42 0 1959903632 13471840 6298064
01:31:43 76607 8192000000 10904503856 11392516096 1937926240 19.130 98 17 34 0 1959962256 16821568 5214448
01:35:04 54964 8192000000 10851709760 11329986560 1875699712 18.630 96 18 36 0 1890821264 9942128 5179424
01:36:59 54064 8192000000 10386097216 10810347520 1464787024 15.168 76 21 42 0 1484741200 14193088 5761088

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-09-2021 01:39:14 to 29-09-2021 01:39:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 92 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 29-09-2021 01:39:30 to 29-09-2021 01:39:32.

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          241394
MON$OLDEST_ACTIVE               243373
MON$OLDEST_SNAPSHOT             243373
MON$NEXT_TRANSACTION            243373
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                       2042144
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.33501 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33501 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33501 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33501 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 ---
:1012: 
:1013: mscs-test-161.budzdorov.ru    Wed Sep 29 01:38:03 2021
:1014:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1015: 
:1016: 
:1017: mscs-test-161.budzdorov.ru    Wed Sep 29 01:38:04 2021
:1018:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:1019: 
--- end of diff output ---

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

29.09.21 01:39:33. 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-29 01:39:35 No severe PSQL-related problems occured
29.09.21 01:39:37. End of report.