Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.07.2022 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 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16001948 kB
MemAvailable 30978064 kB
Buffers 0 kB
Cached 15680240 kB
SwapCached 2512 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.11
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 2022-07-30 19:38:25
DTS_END                         2022-07-30 19:38:25.3570
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/50432
TRN_ID                          238447
ATT_ID                          651
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: 9172.14 23507 1100657
customer order: creation 166.55 411 19986
customer order: refuse 14.87 423 1784
order to supplier: creation 45.07 1384 5408
order to supplier: removal 3.38 112814 405
invoice (draft): creation 44.70 3553 5364
invoice (draft): removal 53.03 820 6364
invoice accept: apply 40.97 18626 4916
invoice accept: cancel 47.98 789 5758
customer reserve: creation 3775.53 184 453064
customer reserve: removal 4682.27 64 561872
realization accept: apply 81.22 196 9746
realization accept: cancel 88.72 109 10646
payment from customer: creation 59.91 146 7189
payment from customer: removal 5.41 99 649
payment to supplier: creation 46.82 150 5618
payment to supplier: removal 3.62 76 434
service: total inventory turnovers 4.70 1022 564
service: total monetary turnovers 6.53 28 784
service: aggregate perf. data 0.85 20659 102
service: refresh index statistics 0.03 308583 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-07-2022 19:39:07 to 30-07-2022 19:39:09.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2701
TEST_TIME 2 3981
TEST_TIME 3 4814
TEST_TIME 4 4704
TEST_TIME 5 5308
TEST_TIME 6 4890
TEST_TIME 7 4799
TEST_TIME 8 4844
TEST_TIME 9 5088
TEST_TIME 10 5306
TEST_TIME 11 5637
TEST_TIME 12 5825
TEST_TIME 13 6129
TEST_TIME 14 6306
TEST_TIME 15 6426
TEST_TIME 16 6713
TEST_TIME 17 7200
TEST_TIME 18 7664
TEST_TIME 19 7582
TEST_TIME 20 7574
TEST_TIME 21 7937
TEST_TIME 22 8056
TEST_TIME 23 8560
TEST_TIME 24 8477
TEST_TIME 25 8672
TEST_TIME 26 9090
TEST_TIME 27 9393
TEST_TIME 28 9505
TEST_TIME 29 10309
TEST_TIME 30 10781
TEST_TIME 31 10661
TEST_TIME 32 10477
TEST_TIME 33 10294
TEST_TIME 34 10109
TEST_TIME 35 9956
TEST_TIME 36 9852
TEST_TIME 37 9756
TEST_TIME 38 9652
TEST_TIME 39 9610
TEST_TIME 40 9595
TEST_TIME 41 9604
TEST_TIME 42 9511
TEST_TIME 43 9453
TEST_TIME 44 9534
TEST_TIME 45 9435
TEST_TIME 46 9274
TEST_TIME 47 9212
TEST_TIME 48 9272
TEST_TIME 49 9138
TEST_TIME 50 9051
TEST_TIME 51 9013
TEST_TIME 52 8964
TEST_TIME 53 9063
TEST_TIME 54 9015
TEST_TIME 55 8961
TEST_TIME 56 8896
TEST_TIME 57 8918
TEST_TIME 58 8832
TEST_TIME 59 8861
TEST_TIME 60 8922
TEST_TIME 61 8924
TEST_TIME 62 9042
TEST_TIME 63 8984
TEST_TIME 64 9078
TEST_TIME 65 9050
TEST_TIME 66 9185
TEST_TIME 67 9140
TEST_TIME 68 9155
TEST_TIME 69 9146
TEST_TIME 70 9155
TEST_TIME 71 9165
TEST_TIME 72 9290
TEST_TIME 73 9277
TEST_TIME 74 9357
TEST_TIME 75 9257
TEST_TIME 76 9176
TEST_TIME 77 9191
TEST_TIME 78 9194
TEST_TIME 79 9127
TEST_TIME 80 9108
TEST_TIME 81 9111
TEST_TIME 82 9112
TEST_TIME 83 9084
TEST_TIME 84 9066
TEST_TIME 85 9059
TEST_TIME 86 9080
TEST_TIME 87 9056
TEST_TIME 88 9049
TEST_TIME 89 9164
TEST_TIME 90 9119
TEST_TIME 91 9201
TEST_TIME 92 9191
TEST_TIME 93 9218
TEST_TIME 94 9238
TEST_TIME 95 9273
TEST_TIME 96 9226
TEST_TIME 97 9308
TEST_TIME 98 9313
TEST_TIME 99 9320
TEST_TIME 100 9294
TEST_TIME 101 9216
TEST_TIME 102 9177
TEST_TIME 103 9190
TEST_TIME 104 9150
TEST_TIME 105 9089
TEST_TIME 106 9043
TEST_TIME 107 8999
TEST_TIME 108 9111
TEST_TIME 109 9108
TEST_TIME 110 9131
TEST_TIME 111 9152
TEST_TIME 112 9153
TEST_TIME 113 9128
TEST_TIME 114 9158
TEST_TIME 115 9189
TEST_TIME 116 9162
TEST_TIME 117 9196
TEST_TIME 118 9198
TEST_TIME 119 9206
TEST_TIME 120 9173

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-07-2022 19:39:11 to 30-07-2022 19:39:14.

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 1520070 1520070 0 0.00 0 23344 6 0 0
doc_list_biud 1520069 1520069 0 0.00 0 42780 30 0 0
sp_add_invoice_to_stock 5035 4916 119 2.36 28 171311 18627 0 119
sp_cancel_adding_invoice 5758 5758 0 0.00 4 62496 789 0 0
sp_cancel_client_order 1784 1784 0 0.00 4 11905 424 0 0
sp_cancel_customer_reserve 561872 561872 0 0.00 0 42781 65 0 0
sp_cancel_pay_from_customer 649 649 0 0.00 3 5797 100 0 0
sp_cancel_pay_to_supplier 434 434 0 0.00 3 7119 77 0 0
sp_cancel_supplier_invoice 6371 6364 7 0.10 1 19839 820 0 7
sp_cancel_supplier_order 405 405 0 0.00 6 1232549 112814 0 0
sp_cancel_write_off 10646 10646 0 0.00 1 9612 109 0 0
sp_client_order 19986 19986 0 0.00 3 22229 412 0 0
sp_customer_reserve 453064 453064 0 0.00 0 58397 185 0 0
sp_fill_shopping_cart 30758 30758 0 0.00 1 18493 49 0 0
sp_fill_shopping_cart_clo_res 453062 453062 0 0.00 0 15851 16 0 0
sp_get_clo_for_invoice 4914 4914 0 0.00 0 23459 406 0 0
sp_kill_cost_storno 17487 17487 0 0.00 0 9591 55 0 0
sp_kill_qstorno_ret_qs2qd 1141159 1141159 0 0.00 0 42777 35 0 0
sp_kill_qty_storno 601489 601489 0 0.00 0 42780 75 0 0
sp_lock_selected_doc 20320 20320 0 0.00 0 7886 6 0 0
sp_make_cost_storno 27467 27467 0 0.00 0 22902 54 0 0
sp_multiply_rows_for_pdistr 27467 27467 0 0.00 0 12839 35 0 0
sp_multiply_rows_for_qdistr 19986 19986 0 0.00 0 19906 189 0 0
sp_pay_from_customer 7189 7189 0 0.00 4 14171 146 0 0
sp_pay_to_supplier 5618 5618 0 0.00 4 16192 151 0 0
sp_payment_common 12807 12807 0 0.00 1 14039 32 0 0
sp_qd_handle_on_cancel_clo 1784 1784 0 0.00 0 11892 384 0 0
sp_qd_handle_on_invoice_upd_sts 10672 10672 0 0.00 0 24640 340 0 0
sp_qd_handle_on_reserve_upd_sts 9746 9746 0 0.00 0 21226 74 0 0
sp_reserve_write_off 9746 9746 0 0.00 6 21321 196 0 0
sp_supplier_invoice 5364 5364 0 0.00 6 68630 3554 0 0
sp_supplier_order 5408 5408 0 0.00 4 70631 1385 0 0
srv_aggregate_perf_data 102 102 0 0.00 2400 240969 20660 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2969 110337 45008 0 0
srv_find_qd_qs_mism 1053835 1053835 0 0.00 0 20265 6 0 0
srv_make_invnt_saldo 564 564 0 0.00 40 33309 1022 0 0
srv_make_money_saldo 784 784 0 0.00 1 524 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 178248 490547 308583 0 0
t$perf-abend:sp_add_invoice_to_stock 119 119 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4916 4916 0 0.00 0 9189 158 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 4690 101 0 0
t$perf-norm:sp_cancel_client_order 1784 1784 0 0.00 0 3322 13 0 0
t$perf-norm:sp_cancel_customer_reserve 637 637 0 0.00 0 8607 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 649 649 0 0.00 0 2806 14 0 0
t$perf-norm:sp_cancel_pay_to_supplier 434 434 0 0.00 0 3538 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 368 368 0 0.00 0 4032 21 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 27165 1836 0 0
t$perf-norm:sp_cancel_write_off 973 973 0 0.00 0 4674 12 0 0
t$perf-norm:sp_client_order 19986 19986 0 0.00 0 7154 17 0 0
t$perf-norm:sp_customer_reserve 949 949 0 0.00 0 3541 17 0 0
t$perf-norm:sp_pay_from_customer 7189 7189 0 0.00 0 12213 10 0 0
t$perf-norm:sp_pay_to_supplier 5618 5618 0 0.00 0 8931 10 0 0
t$perf-norm:sp_reserve_write_off 9746 9746 0 0.00 0 9318 15 0 0
t$perf-norm:sp_supplier_invoice 5364 5364 0 0.00 0 6300 19 0 0
t$perf-norm:sp_supplier_order 5408 5408 0 0.00 0 12052 16 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 282 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 78 2 0 0
t$perf-norm:srv_make_invnt_saldo 564 564 0 0.00 0 660 19 0 0
t$perf-norm:srv_make_money_saldo 784 784 0 0.00 0 2005 19 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 12 5 0 0
v_add_invoice_to_stock 10066 9828 238 2.36 0 9516 20 0 238
v_all_customers 36836 36836 0 0.00 0 15 1 0 0
v_all_suppliers 29494 29494 0 0.00 0 27 1 0 0
v_all_wares 39972 39972 0 0.00 0 28 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 2476 11 0 0
v_cancel_client_order 3568 3568 0 0.00 0 3063 9 0 0
v_cancel_customer_prepayment 1298 1298 0 0.00 0 3789 15 0 0
v_cancel_customer_reserve 1274 1274 0 0.00 0 2989 8 0 0
v_cancel_payment_to_supplier 868 868 0 0.00 0 450 5 0 0
v_cancel_supplier_invoice 750 736 14 1.86 0 1462 16 0 14
v_cancel_supplier_order 810 810 0 0.00 0 2547 8 0 0
v_cancel_write_off 1946 1946 0 0.00 0 1387 15 0 0
v_max_id_clo_ord 5408 5408 0 0.00 0 4870 2 0 0
v_max_id_clo_res 949 949 0 0.00 0 7456 32 0 0
v_max_id_ord_sup 5364 5364 0 0.00 0 7574 7 0 0
v_max_non_paid_invoice 1643 1643 0 0.00 0 4063 5 0 0
v_max_non_paid_realizn 3803 3803 0 0.00 0 2547 5 0 0
v_min_id_clo_ord 5408 5408 0 0.00 0 17059 20 0 0
v_min_id_clo_res 949 949 0 0.00 0 3873 10 0 0
v_min_id_ord_sup 5364 5364 0 0.00 0 7075 9 0 0
v_min_non_paid_invoice 5618 5618 0 0.00 0 14034 25 0 0
v_min_non_paid_realizn 7189 7189 0 0.00 0 12607 26 0 0
v_our_firm 9908 9908 0 0.00 0 30 1 0 0
v_reserve_write_off 19492 19492 0 0.00 0 12052 10 0 0
x_lock_depdocs_on_canc_invoice 11754 11754 0 0.00 0 10378 121 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 22123 381 0 0
x_make_qstorno_1000_1200 5408 5408 0 0.00 1 70609 1294 0 0
x_make_qstorno_1200_2000 5364 5364 0 0.00 2 68623 3370 0 0
x_make_qstorno_2100_3300 453064 453064 0 0.00 0 58394 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-07-2022 19:39:15 to 30-07-2022 19:39:19.

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:08:28 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:28 38400 8192000000 9912581536 10227625984 995635856 10.836 100 87 174 0 1024875808 6314528 22925424
17:11:15 16873 8192000000 9956591840 10280865792 1035306192 11.220 100 88 176 0 1066526800 8910480 22310128
17:12:47 12101 8192000000 9987205600 10322321408 1074067120 11.591 100 86 173 0 1105901856 10147008 21687728
17:14:04 6319 8192000000 10026789008 10374381568 1119294624 12.020 100 77 153 0 1148051744 10536048 18221072
17:15:13 3920 8192000000 10071411904 10427346944 1161516112 12.417 100 85 168 2 1192930080 11898480 19515488
17:16:19 3590 8192000000 10120665008 10485469184 1206975104 12.841 100 80 158 0 1237868048 12891664 18001280
17:17:25 2020 8192000000 10158301408 10531213312 1244192928 13.185 100 86 172 1 1276340336 13124016 19023392
17:18:29 2195 8192000000 10190979008 10570629120 1274481616 13.463 100 80 159 0 1305586320 14031584 17073120
17:19:32 27696 8192000000 10203231424 10602643456 1298473312 13.681 100 49 98 1 1319608512 10559328 10575872
17:21:18 3292 8192000000 10205169696 10616442880 1311997744 13.804 100 32 64 0 1331223376 12471152 6754480
17:22:27 7610 8192000000 10203508704 10619985920 1319595968 13.873 100 15 27 1 1327918336 5370048 2952320
17:23:36 4797 8192000000 10216379456 10633150464 1323971504 13.913 100 23 47 0 1339606608 11148416 4486688
17:24:45 55588 8192000000 10215574208 10644602880 1331709824 13.983 100 27 53 1 1342595056 4922400 5962832
17:26:42 8749 8192000000 10226987264 10652114944 1335208448 14.014 100 17 34 0 1348978400 9260928 4509024
17:27:54 7487 8192000000 10196126736 10611630080 1280629952 13.519 97 15 30 1 1288823952 5218688 2975312
17:29:02 10137 8192000000 10168309344 10576912384 1245588224 13.198 96 15 31 0 1254067504 4884288 3594992
17:30:12 35061 8192000000 10146116928 10554068992 1167773424 12.476 91 15 30 0 1176031920 4918624 3339872
17:31:48 3613 8192000000 10148191856 10549780480 1244334496 13.186 99 26 52 1 1255529808 5398000 5797312
17:32:55 6809 8192000000 10140589792 10540310528 1228782480 13.043 98 26 52 1 1240874208 5092784 6998944
17:34:04 64326 8192000000 10155702752 10565713920 1256123648 13.294 99 32 64 1 1269264544 5528912 7611984
17:36:09 8094 8192000000 10205228352 10608848896 1298742512 13.684 100 35 69 0 1311995408 5223488 8029408
17:37:19 4339 8192000000 10199530432 10614718464 1306189120 13.751 100 18 36 0 1316323776 5485856 4648800
17:38:26 29150 8192000000 10225566288 10636353536 1327844880 13.948 100 33 66 0 1340670464 5670528 7155056
17:40:04 53636 8192000000 10251020592 10672889856 1347884352 14.128 100 43 86 0 1366479104 8981328 9613424
17:42:12 25007 8192000000 10268738608 10694569984 1364818448 14.281 100 42 83 0 1385737856 11572736 9346672
17:43:41 11111 8192000000 10291375344 10714894336 1379527760 14.412 100 39 77 1 1398324496 10347280 8449456
17:44:58 13213 8192000000 10307266080 10732929024 1379510688 14.412 99 49 96 1 1407452336 16420208 11521440
17:46:16 4921 8192000000 10312190272 10732691456 1390267328 14.508 100 58 116 1 1425878800 21587104 14024368
17:47:24 7104 8192000000 10322790576 10741248000 1392280032 14.526 100 62 122 0 1434274784 27264176 14730576
17:48:37 2968 8192000000 10320296112 10736218112 1380845168 14.424 99 61 122 0 1428443872 33541616 14057088
17:49:46 8107 8192000000 10325884480 10745667584 1380788720 14.424 99 68 136 1 1431042848 33009040 17245088
17:50:55 4479 8192000000 10338954640 10751987712 1392317296 14.527 100 69 138 0 1445694096 35308400 18068400
17:52:07 5373 8192000000 10340938880 10756526080 1392458464 14.528 100 66 131 1 1446071760 37463872 16149424
17:53:21 3888 8192000000 10359429776 10776956928 1408279904 14.669 100 65 128 1 1466783792 41356224 17147664
17:54:30 8344 8192000000 10348362304 10763452416 1391441904 14.519 99 65 126 0 1454217232 46966016 15809312
17:55:44 4162 8192000000 10373335520 10788126720 1393048912 14.533 99 76 151 1 1463714480 52096336 18569232
17:56:53 6222 8192000000 10365248992 10790858752 1411107696 14.694 100 45 92 0 1469718816 47646656 10964464
17:58:03 12307 8192000000 10386775248 10809049088 1415857536 14.736 100 53 106 0 1487048320 56528352 14662432
17:59:25 7983 8192000000 10386380944 10814230528 1416671936 14.743 100 53 105 1 1492641136 61829312 14139888
18:00:41 5605 8192000000 10385893680 10803687424 1409360640 14.678 100 71 139 1 1490680336 63150192 18169504
18:01:53 9130 8192000000 10366249952 10799443968 1412929312 14.710 100 36 73 0 1471338080 48461520 9947248
18:03:05 20457 8192000000 10338500544 10771664896 1349700144 14.145 97 32 64 1 1416150832 56976256 9474432
18:04:48 17364 8192000000 10336401824 10786914304 1379467056 14.412 100 21 42 0 1439638832 54090432 6081344
18:06:25 31817 8192000000 10323171904 10766139392 1362214064 14.257 100 25 50 0 1431429888 61750512 7465312
18:07:59 40310 8192000000 10279153984 10740350976 1341362944 14.070 98 14 28 0 1380307328 35176896 3767488
18:09:43 67013 8192000000 10271553664 10709819392 1332306928 13.988 97 31 64 1 1346715872 7323808 7085136
18:11:50 79539 8192000000 10274702208 10725933056 1358959536 14.228 99 13 26 0 1368422736 6718912 2744288
18:14:13 5840 8192000000 10271711824 10713124864 1357190736 14.212 99 22 42 1 1369329200 7740544 4397920
18:15:21 43916 8192000000 10284240576 10732077056 1368705184 14.315 99 13 26 0 1385107584 13259504 3142896
18:17:06 79439 8192000000 10297212896 10748424192 1384627392 14.458 100 18 36 1 1408900752 19983952 4289408
18:19:31 63445 8192000000 10298464464 10726080512 1345099856 14.103 97 37 75 2 1370726816 17387552 8239408
18:21:43 64974 8192000000 10277665856 10743758848 1360027984 14.238 99 9 18 0 1381356384 19748880 1579520
18:23:48 90585 8192000000 10290948528 10736214016 1373280528 14.356 99 25 51 0 1386452432 7158416 6013488
18:26:42 67669 8192000000 10286748144 10744553472 1374760976 14.370 99 14 28 0 1386464208 8161200 3542032
18:29:07 25316 8192000000 10277024704 10730340352 1381950848 14.434 100 24 48 0 1398128384 11123568 5053968
18:30:33 55720 8192000000 10279629488 10736324608 1366422816 14.295 99 11 22 0 1379831840 10790640 2618384
18:32:55 61317 8192000000 10266122432 10721583104 1337377152 14.034 97 11 22 0 1347364288 7478928 2508208
18:35:00 51739 8192000000 10278684768 10741260288 1375121392 14.373 100 14 29 0 1391737328 13031632 3584304
18:37:37 26959 8192000000 10281318144 10745806848 1381041184 14.426 100 29 58 0 1406212576 18457504 6713888
18:39:44 63109 8192000000 10262238384 10725867520 1314413616 13.826 95 13 26 0 1334586576 17319152 2853808
18:42:06 82132 8192000000 10283950704 10742747136 1351850160 14.164 98 14 26 1 1369996128 15279328 2866640
18:44:28 30692 8192000000 10262729744 10711588864 1345599200 14.108 98 30 60 0 1363651920 11177312 6875408
18:46:15 70211 8192000000 10296419120 10739527680 1355678704 14.199 98 27 54 0 1376417488 14847472 5891312
18:48:26 8731 8192000000 10284760960 10716962816 1366609056 14.297 99 45 88 0 1385763552 9785568 9368928
18:49:35 89775 8192000000 10301934240 10766794752 1373074208 14.355 99 9 18 0 1392484672 17547632 1862832
18:52:05 89320 8192000000 10279156928 10742059008 1353345488 14.178 98 11 22 1 1372127216 16634080 2147648
18:54:35 30469 8192000000 10283418720 10726719488 1369306256 14.321 99 50 98 0 1388379072 7484192 11588624
18:56:14 79314 8192000000 10286640832 10749837312 1359106464 14.229 98 20 40 0 1382334480 18814768 4413248
18:58:36 80284 8192000000 10272849616 10735628288 1357852160 14.218 98 14 28 0 1372813024 11585328 3375536
19:00:57 66903 8192000000 10272412688 10729283584 1367920832 14.308 99 10 20 0 1381648448 11023312 2704304
19:03:33 65809 8192000000 10276008752 10744737792 1380770464 14.423 100 13 26 0 1394527616 10841856 2915296
19:05:49 33737 8192000000 10290713088 10743787520 1371568240 14.341 99 19 38 0 1388777856 12957664 4251952
19:07:25 62691 8192000000 10301378976 10749480960 1361507680 14.251 98 20 40 2 1380793920 14676944 4609296
19:09:29 92471 8192000000 10293215888 10740146176 1344732032 14.100 96 10 20 0 1355982384 9170432 2079920
19:12:02 84162 8192000000 10287851312 10750541824 1366621504 14.297 98 24 48 0 1384207344 12411056 5174784
19:14:33 54815 8192000000 10298690848 10750599168 1376153968 14.382 99 44 88 2 1398760576 13032304 9574304
19:16:38 104378 8192000000 10278661408 10734678016 1321033472 13.886 95 30 60 0 1339285440 11610048 6641920
19:19:26 110336 8192000000 10296353824 10749919232 1386274928 14.473 100 26 50 1 1405128352 13419408 5434016
19:22:21 31201 8192000000 10284173456 10739527680 1378549728 14.404 100 12 24 0 1398703424 16952736 3200960
19:25:00 66836 8192000000 10303409904 10766266368 1366850944 14.299 98 24 49 0 1397000864 24379024 5770896
19:27:19 53298 8192000000 10292131056 10735710208 1354561824 14.189 98 14 28 0 1374490368 17281376 2647168
19:29:13 77575 8192000000 10274245648 10731753472 1336543024 14.026 97 18 36 0 1351916416 11074272 4299120
19:31:33 35393 8192000000 10271454656 10721886208 1343875328 14.092 98 36 71 0 1359922688 7899968 8147392
19:33:09 66179 8192000000 10258859792 10702753792 1295975488 13.659 94 16 32 0 1311219072 12115008 3128576
19:35:16 73587 8192000000 10291352608 10739535872 1368612192 14.315 99 17 34 0 1385587776 13204384 3771200
19:37:33 60770 8192000000 9152502384 9397878784 307136320 3.613 23 4 8 0 311409264 3948208 324736

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 30-07-2022 19:39:21 to 30-07-2022 19:39:36.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-07-2022 19:39:37 to 30-07-2022 19:39:39.

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          237796
MON$OLDEST_ACTIVE               238724
MON$OLDEST_SNAPSHOT             238724
MON$NEXT_TRANSACTION            238724
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                       2025568
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:527: 
:528: mscs-test-161.budzdorov.ru    Sat Jul 30 19:38:38 2022
:529:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:530: 
--- end of diff output ---

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

30.07.22 19:39:40. 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
2022-07-30 19:39:42 No severe PSQL-related problems occured
30.07.22 19:39:43. End of report.