Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.08.2021 19:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16096388 kB
MemAvailable 30938256 kB
Buffers 0 kB
Cached 15551348 kB
SwapCached 3640 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-20 19:37:49
DTS_END                         2021-08-20 19:37:49.0760
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(141:26) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/55818
TRN_ID                          242939
ATT_ID                          722
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: 9193.60 20950 1103232
customer order: creation 170.66 358 20479
customer order: refuse 14.91 382 1789
order to supplier: creation 44.78 1399 5374
order to supplier: removal 3.57 103654 428
invoice (draft): creation 45.73 3718 5488
invoice (draft): removal 54.23 811 6507
invoice accept: apply 40.07 17788 4808
invoice accept: cancel 49.23 768 5908
customer reserve: creation 3684.15 176 442098
customer reserve: removal 4793.32 60 575198
realization accept: apply 81.94 211 9833
realization accept: cancel 84.92 114 10190
payment from customer: creation 56.94 119 6833
payment from customer: removal 5.60 81 672
payment to supplier: creation 48.22 157 5786
payment to supplier: removal 3.15 68 378
service: total inventory turnovers 4.81 1236 577
service: total monetary turnovers 6.54 53 785
service: aggregate perf. data 0.81 20311 97
service: refresh index statistics 0.03 267531 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-08-2021 19:38:41 to 20-08-2021 19:38:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1231
TEST_TIME 2 2541
TEST_TIME 3 2874
TEST_TIME 4 3988
TEST_TIME 5 4656
TEST_TIME 6 4682
TEST_TIME 7 5088
TEST_TIME 8 5290
TEST_TIME 9 5431
TEST_TIME 10 5370
TEST_TIME 11 5605
TEST_TIME 12 5877
TEST_TIME 13 6387
TEST_TIME 14 6268
TEST_TIME 15 6392
TEST_TIME 16 6535
TEST_TIME 17 6662
TEST_TIME 18 7080
TEST_TIME 19 7516
TEST_TIME 20 7403
TEST_TIME 21 7632
TEST_TIME 22 8236
TEST_TIME 23 8280
TEST_TIME 24 8370
TEST_TIME 25 8606
TEST_TIME 26 8894
TEST_TIME 27 8947
TEST_TIME 28 9848
TEST_TIME 29 10599
TEST_TIME 30 10797
TEST_TIME 31 10858
TEST_TIME 32 10686
TEST_TIME 33 10435
TEST_TIME 34 10287
TEST_TIME 35 10143
TEST_TIME 36 10049
TEST_TIME 37 9924
TEST_TIME 38 9858
TEST_TIME 39 10214
TEST_TIME 40 10046
TEST_TIME 41 9922
TEST_TIME 42 9957
TEST_TIME 43 9808
TEST_TIME 44 9717
TEST_TIME 45 9606
TEST_TIME 46 9465
TEST_TIME 47 9504
TEST_TIME 48 9400
TEST_TIME 49 9369
TEST_TIME 50 9301
TEST_TIME 51 9179
TEST_TIME 52 9113
TEST_TIME 53 9111
TEST_TIME 54 9138
TEST_TIME 55 9188
TEST_TIME 56 9071
TEST_TIME 57 9084
TEST_TIME 58 8953
TEST_TIME 59 8879
TEST_TIME 60 8953
TEST_TIME 61 8888
TEST_TIME 62 8890
TEST_TIME 63 8911
TEST_TIME 64 8954
TEST_TIME 65 9069
TEST_TIME 66 9042
TEST_TIME 67 9097
TEST_TIME 68 9172
TEST_TIME 69 9096
TEST_TIME 70 9191
TEST_TIME 71 9180
TEST_TIME 72 9184
TEST_TIME 73 9215
TEST_TIME 74 9245
TEST_TIME 75 9284
TEST_TIME 76 9194
TEST_TIME 77 9181
TEST_TIME 78 9137
TEST_TIME 79 9103
TEST_TIME 80 9118
TEST_TIME 81 9141
TEST_TIME 82 9102
TEST_TIME 83 9074
TEST_TIME 84 9054
TEST_TIME 85 9017
TEST_TIME 86 9018
TEST_TIME 87 9013
TEST_TIME 88 9020
TEST_TIME 89 9044
TEST_TIME 90 9014
TEST_TIME 91 9060
TEST_TIME 92 9060
TEST_TIME 93 9098
TEST_TIME 94 9147
TEST_TIME 95 9145
TEST_TIME 96 9217
TEST_TIME 97 9233
TEST_TIME 98 9232
TEST_TIME 99 9265
TEST_TIME 100 9266
TEST_TIME 101 9302
TEST_TIME 102 9327
TEST_TIME 103 9255
TEST_TIME 104 9243
TEST_TIME 105 9178
TEST_TIME 106 9165
TEST_TIME 107 9157
TEST_TIME 108 9118
TEST_TIME 109 9126
TEST_TIME 110 9112
TEST_TIME 111 9092
TEST_TIME 112 9036
TEST_TIME 113 9105
TEST_TIME 114 9111
TEST_TIME 115 9102
TEST_TIME 116 9142
TEST_TIME 117 9106
TEST_TIME 118 9166
TEST_TIME 119 9152
TEST_TIME 120 9195

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-08-2021 19:38:44 to 20-08-2021 19:38:48.

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 1514232 1514232 0 0.00 0 24585 6 0 0
doc_list_biud 1514232 1514232 0 0.00 0 32324 29 0 0
sp_add_invoice_to_stock 4936 4808 128 2.59 45 163790 17789 0 128
sp_cancel_adding_invoice 5908 5908 0 0.00 6 85491 769 0 0
sp_cancel_client_order 1789 1789 0 0.00 4 25883 382 0 0
sp_cancel_customer_reserve 575198 575198 0 0.00 1 26187 61 0 0
sp_cancel_pay_from_customer 672 672 0 0.00 2 5052 82 0 0
sp_cancel_pay_to_supplier 378 378 0 0.00 3 2891 69 0 0
sp_cancel_supplier_invoice 6516 6507 9 0.13 2 28118 812 0 9
sp_cancel_supplier_order 428 428 0 0.00 11 1112340 103654 0 0
sp_cancel_write_off 10190 10190 0 0.00 1 11401 115 0 0
sp_client_order 20479 20479 0 0.00 3 20625 359 0 0
sp_customer_reserve 442098 442098 0 0.00 0 106099 176 0 0
sp_fill_shopping_cart 31314 31314 0 0.00 1 13625 41 0 0
sp_fill_shopping_cart_clo_res 442089 442089 0 0.00 0 21121 14 0 0
sp_get_clo_for_invoice 4799 4799 0 0.00 0 21223 324 0 0
sp_kill_cost_storno 17148 17148 0 0.00 0 24576 58 0 0
sp_kill_qstorno_ret_qs2qd 1167521 1167521 0 0.00 0 28030 33 0 0
sp_kill_qty_storno 614652 614652 0 0.00 0 32324 71 0 0
sp_lock_selected_doc 20421 20421 0 0.00 0 6092 4 0 0
sp_make_cost_storno 27251 27251 0 0.00 0 18718 57 0 0
sp_multiply_rows_for_pdistr 27251 27251 0 0.00 0 13966 30 0 0
sp_multiply_rows_for_qdistr 20479 20479 0 0.00 0 16173 146 0 0
sp_pay_from_customer 6833 6833 0 0.00 4 13382 119 0 0
sp_pay_to_supplier 5786 5786 0 0.00 5 14458 158 0 0
sp_payment_common 12619 12619 0 0.00 1 12950 29 0 0
sp_qd_handle_on_cancel_clo 1789 1789 0 0.00 0 25877 354 0 0
sp_qd_handle_on_invoice_upd_sts 10707 10707 0 0.00 0 32322 333 0 0
sp_qd_handle_on_reserve_upd_sts 9833 9833 0 0.00 0 26141 80 0 0
sp_reserve_write_off 9833 9833 0 0.00 6 26193 211 0 0
sp_supplier_invoice 5488 5488 0 0.00 8 81147 3719 0 0
sp_supplier_order 5374 5374 0 0.00 5 44427 1400 0 0
srv_aggregate_perf_data 97 97 0 0.00 2072 244477 20312 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1537 134563 47679 0 0
srv_find_qd_qs_mism 1057620 1057620 0 0.00 0 15183 5 0 0
srv_make_invnt_saldo 577 577 0 0.00 28 171833 1237 0 0
srv_make_money_saldo 785 785 0 0.00 0 11973 54 0 0
srv_recalc_idx_stat 4 4 0 0.00 144283 444541 267531 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4808 4808 0 0.00 0 9914 168 0 0
t$perf-norm:sp_cancel_adding_invoice 405 405 0 0.00 0 4027 134 0 0
t$perf-norm:sp_cancel_client_order 1789 1789 0 0.00 0 7676 25 0 0
t$perf-norm:sp_cancel_customer_reserve 690 690 0 0.00 0 6859 33 0 0
t$perf-norm:sp_cancel_pay_from_customer 672 672 0 0.00 0 743 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 378 378 0 0.00 0 1893 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 436 436 0 0.00 0 4597 31 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 24084 1759 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 1873 9 0 0
t$perf-norm:sp_client_order 20479 20479 0 0.00 0 6244 18 0 0
t$perf-norm:sp_customer_reserve 980 980 0 0.00 0 1169 7 0 0
t$perf-norm:sp_pay_from_customer 6835 6835 0 0.00 0 13378 17 0 0
t$perf-norm:sp_pay_to_supplier 5786 5786 0 0.00 0 7019 11 0 0
t$perf-norm:sp_reserve_write_off 9833 9833 0 0.00 0 16650 17 0 0
t$perf-norm:sp_supplier_invoice 5488 5488 0 0.00 0 6809 22 0 0
t$perf-norm:sp_supplier_order 5374 5374 0 0.00 0 5585 18 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 331 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 483 11 0 0
t$perf-norm:srv_make_invnt_saldo 577 577 0 0.00 0 1568 26 0 0
t$perf-norm:srv_make_money_saldo 785 785 0 0.00 0 1743 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 3 0 0
v_add_invoice_to_stock 9844 9588 256 2.60 0 14790 27 0 256
v_all_customers 36820 36820 0 0.00 0 24 1 0 0
v_all_suppliers 29982 29982 0 0.00 0 18 1 0 0
v_all_wares 40938 40938 0 0.00 0 15 1 0 0
v_cancel_adding_invoice 810 810 0 0.00 0 2010 10 0 0
v_cancel_client_order 3578 3578 0 0.00 0 9656 6 0 0
v_cancel_customer_prepayment 1344 1344 0 0.00 0 2441 10 0 0
v_cancel_customer_reserve 1380 1380 0 0.00 0 2560 12 0 0
v_cancel_payment_to_supplier 756 756 0 0.00 0 2854 7 0 0
v_cancel_supplier_invoice 890 872 18 2.02 0 2453 16 0 18
v_cancel_supplier_order 856 856 0 0.00 0 6104 14 0 0
v_cancel_write_off 1998 1998 0 0.00 0 2336 14 0 0
v_max_id_clo_ord 5369 5369 0 0.00 0 231 1 0 0
v_max_id_clo_res 980 980 0 0.00 0 2955 13 0 0
v_max_id_ord_sup 5476 5476 0 0.00 0 4656 6 0 0
v_max_non_paid_invoice 1640 1640 0 0.00 0 705 2 0 0
v_max_non_paid_realizn 3766 3766 0 0.00 0 871 3 0 0
v_min_id_clo_ord 5369 5369 0 0.00 0 1970 5 0 0
v_min_id_clo_res 980 980 0 0.00 0 7135 16 0 0
v_min_id_ord_sup 5476 5476 0 0.00 0 6220 5 0 0
v_min_non_paid_invoice 5786 5786 0 0.00 0 10639 19 0 0
v_min_non_paid_realizn 6833 6833 0 0.00 0 7259 13 0 0
v_our_firm 10252 10252 0 0.00 0 15 1 0 0
v_reserve_write_off 19660 19660 0 0.00 0 13427 10 0 0
x_lock_depdocs_on_canc_invoice 11979 11979 0 0.00 0 16089 126 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 7161 343 0 0
x_make_qstorno_1000_1200 5374 5374 0 0.00 2 44399 1313 0 0
x_make_qstorno_1200_2000 5488 5488 0 0.00 3 81114 3572 0 0
x_make_qstorno_2100_3300 442098 442098 0 0.00 0 106037 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-08-2021 19:38:49 to 20-08-2021 19:38:53.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:07:52 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:53 36054 8192000000 10411412640 10736025600 1443977376 14.985 100 82 164 0 1476048896 5868560 26202960
17:10:36 15462 8192000000 10451591248 10785705984 1506223712 15.530 100 89 178 0 1543725376 8606016 28895648
17:11:55 14153 8192000000 10488414784 10834309120 1544868448 15.866 100 83 166 2 1580669728 9647488 26153792
17:13:15 16729 8192000000 10578307120 10935988224 1621106800 16.519 100 78 156 1 1655579664 11125968 23346896
17:14:37 7044 8192000000 10661958048 11030364160 1715392256 17.314 100 82 163 3 1753061520 14000512 23668752
17:15:48 5568 8192000000 10710681776 11091988480 1774342944 17.803 100 85 169 1 1811723936 12815520 24565472
17:16:55 1242 8192000000 10759900128 11139358720 1816691888 18.151 100 88 176 1 1855575728 13754944 25128896
17:17:58 1795 8192000000 10810378192 11202813952 1870691616 18.590 100 93 185 2 1909708864 13325504 25691744
17:19:01 10932 8192000000 10825422720 11222327296 1883022736 18.690 100 70 140 3 1911723280 7466720 21233824
17:20:13 9410 8192000000 10803272848 11234181120 1894846064 18.785 100 28 57 1 1908907296 6492640 7568592
17:21:23 8805 8192000000 10816205312 11249360896 1908770640 18.897 100 15 30 1 1918487856 5731104 3986112
17:22:32 9570 8192000000 10837562928 11267092480 1919446096 18.982 100 29 58 3 1933050144 5550208 8053840
17:23:42 11690 8192000000 10863066480 11279052800 1926627184 19.040 100 56 112 3 1948247584 5194288 16426112
17:24:54 5183 8192000000 10843146752 11275653120 1923826624 19.017 100 39 78 0 1942926464 7268432 11831408
17:26:00 8596 8192000000 10827864016 11258343424 1882391680 18.684 98 18 36 0 1893320304 4932304 5996320
17:27:11 11085 8192000000 10788777024 11214458880 1828620336 18.248 97 23 46 1 1840932704 5375728 6936640
17:28:27 20541 8192000000 10765987792 11199627264 1800991552 18.022 96 19 37 1 1811175808 5315664 4868592
17:29:48 5652 8192000000 10734902208 11163676672 1804815904 18.053 98 25 50 1 1818885072 7194704 6874464
17:30:57 8508 8192000000 10708914896 11117481984 1761379760 17.696 98 23 46 3 1773417392 5054400 6983232
17:32:06 9516 8192000000 10724434912 11148705792 1796063296 17.982 99 15 30 0 1805584480 5539072 3982112
17:33:16 19819 8192000000 10743563088 11168874496 1832447248 18.279 100 47 94 1 1851814192 4763488 14603456
17:34:39 11378 8192000000 10767726704 11193487360 1854994464 18.463 100 29 57 1 1869666704 5920352 8751888
17:35:55 13285 8192000000 10776939216 11213066240 1865050848 18.544 100 21 42 2 1881510032 10226576 6232608
17:37:10 68535 8192000000 10830359552 11250393088 1895070304 18.787 100 25 51 0 1907971216 5084496 7816416
17:39:26 22278 8192000000 10865472208 11292897280 1935592704 19.112 100 54 108 0 1961110896 9913424 15604768
17:40:52 25016 8192000000 10874040416 11310424064 1946329344 19.197 100 39 78 1 1970840736 13047184 11464208
17:42:28 30476 8192000000 10902590480 11337580544 1958736224 19.296 100 34 68 0 1987673280 17669856 11267200
17:44:09 6182 8192000000 10910207328 11339194368 1951253600 19.236 99 69 138 1 1992182784 19702032 21227152
17:45:18 17964 8192000000 10880469072 11321528320 1937828608 19.129 99 35 70 0 1968368816 20285312 10254896
17:46:55 10620 8192000000 10928403088 11355811840 1976899248 19.440 100 61 122 1 2019638672 23707296 19032128
17:48:13 3608 8192000000 10944425776 11376005120 1985629776 19.509 100 55 108 0 2030889312 29589312 15670224
17:49:22 13779 8192000000 10931107392 11358806016 1944666336 19.184 98 52 104 2 1995219328 34782704 15770288
17:50:40 3409 8192000000 10921906240 11343249408 1949841168 19.225 99 68 135 1 2000245824 30057616 20347040
17:51:52 6877 8192000000 10905229216 11323727872 1940371568 19.150 100 68 133 2 1994254128 32441200 21441360
17:53:04 1535 8192000000 10928568976 11341230080 1954558416 19.263 100 75 151 0 2013501264 36636144 22306704
17:54:13 5907 8192000000 10916550832 11330658304 1917046736 18.963 99 68 136 2 1981640048 43276848 21316464
17:55:27 3874 8192000000 10944603904 11365470208 1946823392 19.201 99 72 142 0 2015354432 46545440 21985600
17:56:36 5792 8192000000 10949057104 11371716608 1959252528 19.300 100 62 124 2 2030274192 51147024 19874640
17:57:49 3218 8192000000 10991992256 11406557184 1987184480 19.522 100 85 164 4 2072384880 58703584 26496816
17:58:58 1731 8192000000 11009267504 11426123776 1999028112 19.615 100 85 168 0 2086825072 61414224 26382736
18:00:04 10277 8192000000 10964739392 11412611072 1995234496 19.585 100 36 73 2 2056545872 49378368 11933008
18:01:19 14971 8192000000 10968432816 11415879680 1986598224 19.517 100 48 96 2 2060895040 58806656 15490160
18:02:43 18569 8192000000 10955316096 11410788352 1962071200 19.323 99 25 50 0 2025705424 54577664 9056560
18:04:30 24809 8192000000 10943142400 11401093120 1954222496 19.260 100 18 36 0 2024291744 62045296 8023952
18:05:58 56437 8192000000 10909066736 11368505344 1942530352 19.167 99 19 38 0 1998558672 49065440 6962880
18:07:55 57913 8192000000 10887792736 11350548480 1898839312 18.817 97 8 15 2 1918243824 17345840 2058672
18:10:37 55400 8192000000 10883597216 11344064512 1954270080 19.260 100 16 32 1 1967310160 8360800 4679280
18:12:40 39696 8192000000 10913532288 11379011584 1980697952 19.470 100 17 34 2 2004485696 18531200 5256544
18:14:30 123427 8192000000 10899736576 11380756480 1948606288 19.215 98 14 28 0 1981207120 27828736 4772096
18:17:57 54944 8192000000 10890776048 11346362368 1951730848 19.240 99 30 61 1 1968387776 7865856 8791072
18:19:58 46210 8192000000 10919942544 11374161920 1983553024 19.493 100 43 82 3 2002721920 7254640 11914256
18:21:45 56263 8192000000 10910415472 11381768192 1975802624 19.431 99 25 47 1 1994039120 10164144 8072352
18:24:14 77470 8192000000 10917675952 11388329984 1993347760 19.570 100 21 42 2 2009927088 10715104 5864224
18:26:36 79076 8192000000 10932145520 11396960256 1995939936 19.591 100 23 46 0 2010497152 7917232 6639984
18:29:00 37206 8192000000 10926075552 11393855488 1983829008 19.495 99 43 85 0 2013475648 16965104 12681536
18:30:38 37667 8192000000 10920078288 11407683584 2001833344 19.637 100 21 42 1 2024321600 16069920 6418336
18:33:32 46488 8192000000 10897653440 11382726656 1982978976 19.488 100 9 18 0 2000652112 15145920 2527216
18:35:29 80832 8192000000 10896499584 11369631744 1929812048 19.065 97 15 30 1 1945406848 10865648 4729152
18:37:50 96793 8192000000 10872220352 11325841408 1810262080 18.098 91 20 40 0 1823982704 8643376 5077248
18:40:30 55976 8192000000 10874187072 11346489344 1898185968 18.812 97 10 20 0 1915000576 14151520 2663088
18:42:27 48749 8192000000 10842517296 11294269440 1807712112 18.077 94 12 24 1 1822256352 11217872 3326368
18:44:32 92221 8192000000 10855133920 11332112384 1861303008 18.514 97 29 58 0 1885705728 16183648 8219072
18:47:19 55889 8192000000 10877375648 11354484736 1933268976 19.093 99 14 28 1 1953623472 16165808 4188688
18:50:12 78619 8192000000 10882075136 11357315072 1900796304 18.833 97 20 40 0 1918870144 12482496 5591344
18:52:35 83444 8192000000 10865119648 11331629056 1935970640 19.115 99 22 43 3 1950918432 8185200 6762592
18:55:22 51879 8192000000 10901924864 11362443264 1947934864 19.210 99 39 78 1 1968357920 9324656 11098400
18:57:16 59504 8192000000 10876390144 11360243712 1931066144 19.075 98 14 28 1 1947056512 11847200 4143168
18:59:16 80227 8192000000 10883926768 11340238848 1886218320 18.715 96 14 28 0 1900245008 9775616 4251072
19:02:07 48032 8192000000 10839356720 11309391872 1894399184 18.781 98 9 18 0 1907625760 10721280 2505296
19:03:56 39722 8192000000 10851605600 11330142208 1901133936 18.835 98 8 16 0 1913676944 10540928 2002080
19:05:41 46305 8192000000 10885843440 11365937152 1971273968 19.396 100 27 54 3 1987093440 7896368 7923104
19:07:34 50536 8192000000 10920072848 11394207744 1994755776 19.581 100 17 34 2 2009047312 9750640 4540896
19:09:25 66097 8192000000 10923312576 11396849664 1976749264 19.439 99 19 38 1 1994892352 12516224 5626864
19:11:40 64033 8192000000 10937160880 11396509696 1981039808 19.473 99 22 45 1 2004485872 17229552 6216512
19:13:50 96647 8192000000 10941721792 11409219584 1961429088 19.317 98 18 36 1 1979886256 13711584 4745584
19:16:30 61121 8192000000 10943845120 11405201408 1965737824 19.352 98 20 40 0 1986253024 15009904 5505296
19:18:33 69146 8192000000 10903564912 11372658688 1878869904 18.656 94 11 22 1 1894345136 12650752 2824480
19:20:44 125415 8192000000 10915283920 11384909824 1936254080 19.117 98 19 36 0 1949964384 8569184 5141120
19:24:18 49474 8192000000 10888822352 11367915520 1949029152 19.219 99 15 30 0 1968174944 14854976 4290816
19:26:08 81541 8192000000 10888340224 11362570240 1895776816 18.792 96 15 30 0 1913763232 13396800 4589616
19:28:32 79158 8192000000 10865975968 11337551872 1886234496 18.715 96 15 30 0 1905066480 14254784 4577200
19:30:58 134563 8192000000 10885965008 11354791936 1876094176 18.634 96 15 30 1 1893632992 13155056 4383760
19:34:21 54621 8192000000 10882454384 11356123136 1923550320 19.015 98 26 52 0 1945433712 14930720 6952672
19:36:16 9528 8192000000 10877500992 11332694016 1939795344 19.145 100 47 94 1 1963556160 10114832 13645984
19:37:27 28262 8192000000 9113362176 9349689344 276310224 3.262 15 5 9 0 282579120 5021136 1247760

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 20-08-2021 19:38:55 to 20-08-2021 19:39:09.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-08-2021 19:39:10 to 20-08-2021 19:39:12.

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          243313
MON$OLDEST_ACTIVE               243314
MON$OLDEST_SNAPSHOT             243314
MON$NEXT_TRANSACTION            243314
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                       2044064
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 ---
:183: 
:184: mscs-test-161.budzdorov.ru    Fri Aug 20 19:38:00 2021
:185:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:186: 
:187: 
:188: mscs-test-161.budzdorov.ru    Fri Aug 20 19:38:03 2021
:189:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:190: 
:191: 
:192: mscs-test-161.budzdorov.ru    Fri Aug 20 19:38:03 2021
:193:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:194: 
--- end of diff output ---

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

20.08.21 19:39:13. 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-20 19:39:16 No severe PSQL-related problems occured
20.08.21 19:39:17. End of report.