Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.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 16033808 kB
MemAvailable 30987872 kB
Buffers 0 kB
Cached 15596296 kB
SwapCached 2592 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-09 19:37:45
DTS_END                         2021-08-09 19:37:45.2360
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(139:30) ==> SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51502
TRN_ID                          252188
ATT_ID                          736
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: 9474.08 19422 1136889
customer order: creation 184.44 341 22133
customer order: refuse 16.47 358 1976
order to supplier: creation 49.43 1259 5932
order to supplier: removal 3.54 98916 425
invoice (draft): creation 48.98 3505 5878
invoice (draft): removal 53.13 814 6376
invoice accept: apply 44.60 17947 5352
invoice accept: cancel 47.56 876 5707
customer reserve: creation 4069.64 178 488357
customer reserve: removal 4649.88 59 557986
realization accept: apply 90.84 176 10901
realization accept: cancel 76.72 123 9207
payment from customer: creation 64.18 112 7702
payment from customer: removal 6.02 78 722
payment to supplier: creation 52.03 118 6243
payment to supplier: removal 3.58 123 429
service: total inventory turnovers 4.93 1176 592
service: total monetary turnovers 7.30 41 876
service: aggregate perf. data 0.76 23407 91
service: refresh index statistics 0.03 238830 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 09-08-2021 19:38:34 to 09-08-2021 19:38:36.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1684
TEST_TIME 2 2576
TEST_TIME 3 3777
TEST_TIME 4 4089
TEST_TIME 5 4331
TEST_TIME 6 4682
TEST_TIME 7 5429
TEST_TIME 8 5263
TEST_TIME 9 5431
TEST_TIME 10 5778
TEST_TIME 11 6396
TEST_TIME 12 6838
TEST_TIME 13 6902
TEST_TIME 14 6817
TEST_TIME 15 6923
TEST_TIME 16 7483
TEST_TIME 17 7759
TEST_TIME 18 7821
TEST_TIME 19 8059
TEST_TIME 20 8029
TEST_TIME 21 8386
TEST_TIME 22 8362
TEST_TIME 23 8544
TEST_TIME 24 8961
TEST_TIME 25 9319
TEST_TIME 26 9492
TEST_TIME 27 9797
TEST_TIME 28 9540
TEST_TIME 29 9839
TEST_TIME 30 10939
TEST_TIME 31 10884
TEST_TIME 32 10699
TEST_TIME 33 10759
TEST_TIME 34 10832
TEST_TIME 35 10665
TEST_TIME 36 10608
TEST_TIME 37 10578
TEST_TIME 38 10465
TEST_TIME 39 10351
TEST_TIME 40 10190
TEST_TIME 41 10079
TEST_TIME 42 10070
TEST_TIME 43 10072
TEST_TIME 44 9928
TEST_TIME 45 9877
TEST_TIME 46 9858
TEST_TIME 47 9828
TEST_TIME 48 9783
TEST_TIME 49 9922
TEST_TIME 50 9972
TEST_TIME 51 9909
TEST_TIME 52 9911
TEST_TIME 53 9773
TEST_TIME 54 9669
TEST_TIME 55 9639
TEST_TIME 56 9614
TEST_TIME 57 9558
TEST_TIME 58 9532
TEST_TIME 59 9553
TEST_TIME 60 9567
TEST_TIME 61 9540
TEST_TIME 62 9625
TEST_TIME 63 9712
TEST_TIME 64 9697
TEST_TIME 65 9737
TEST_TIME 66 9678
TEST_TIME 67 9738
TEST_TIME 68 9812
TEST_TIME 69 9802
TEST_TIME 70 9811
TEST_TIME 71 9810
TEST_TIME 72 9822
TEST_TIME 73 9809
TEST_TIME 74 9713
TEST_TIME 75 9732
TEST_TIME 76 9649
TEST_TIME 77 9607
TEST_TIME 78 9575
TEST_TIME 79 9514
TEST_TIME 80 9504
TEST_TIME 81 9482
TEST_TIME 82 9433
TEST_TIME 83 9381
TEST_TIME 84 9360
TEST_TIME 85 9413
TEST_TIME 86 9430
TEST_TIME 87 9450
TEST_TIME 88 9425
TEST_TIME 89 9460
TEST_TIME 90 9458
TEST_TIME 91 9534
TEST_TIME 92 9552
TEST_TIME 93 9499
TEST_TIME 94 9554
TEST_TIME 95 9621
TEST_TIME 96 9666
TEST_TIME 97 9628
TEST_TIME 98 9640
TEST_TIME 99 9647
TEST_TIME 100 9553
TEST_TIME 101 9561
TEST_TIME 102 9526
TEST_TIME 103 9510
TEST_TIME 104 9458
TEST_TIME 105 9477
TEST_TIME 106 9431
TEST_TIME 107 9388
TEST_TIME 108 9373
TEST_TIME 109 9368
TEST_TIME 110 9343
TEST_TIME 111 9343
TEST_TIME 112 9365
TEST_TIME 113 9364
TEST_TIME 114 9404
TEST_TIME 115 9367
TEST_TIME 116 9382
TEST_TIME 117 9395
TEST_TIME 118 9445
TEST_TIME 119 9436
TEST_TIME 120 9477

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-08-2021 19:38:38 to 09-08-2021 19:38:41.

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 1588990 1588990 0 0.00 0 23748 6 0 0
doc_list_biud 1588990 1588990 0 0.00 0 27466 27 0 0
sp_add_invoice_to_stock 5456 5352 104 1.90 34 152192 17947 0 104
sp_cancel_adding_invoice 5707 5707 0 0.00 5 64741 877 0 0
sp_cancel_client_order 1976 1976 0 0.00 4 17470 359 0 0
sp_cancel_customer_reserve 557986 557986 0 0.00 1 23758 60 0 0
sp_cancel_pay_from_customer 722 722 0 0.00 2 5206 79 0 0
sp_cancel_pay_to_supplier 429 429 0 0.00 3 8189 124 0 0
sp_cancel_supplier_invoice 6388 6376 12 0.18 2 27475 814 0 12
sp_cancel_supplier_order 425 425 0 0.00 7 1221110 98916 0 0
sp_cancel_write_off 9207 9207 0 0.00 1 19108 123 0 0
sp_client_order 22133 22133 0 0.00 3 35555 341 0 0
sp_customer_reserve 488357 488357 0 0.00 0 77045 179 0 0
sp_fill_shopping_cart 33928 33928 0 0.00 1 12361 37 0 0
sp_fill_shopping_cart_clo_res 488354 488354 0 0.00 0 30707 12 0 0
sp_get_clo_for_invoice 5348 5348 0 0.00 0 30367 312 0 0
sp_kill_cost_storno 16065 16065 0 0.00 0 5442 57 0 0
sp_kill_qstorno_ret_qs2qd 1131980 1131980 0 0.00 0 27462 32 0 0
sp_kill_qty_storno 597926 597926 0 0.00 0 27465 71 0 0
sp_lock_selected_doc 22508 22508 0 0.00 0 8176 6 0 0
sp_make_cost_storno 30194 30194 0 0.00 0 12737 48 0 0
sp_multiply_rows_for_pdistr 30194 30194 0 0.00 0 6074 27 0 0
sp_multiply_rows_for_qdistr 22133 22133 0 0.00 0 34582 154 0 0
sp_pay_from_customer 7702 7702 0 0.00 4 12893 113 0 0
sp_pay_to_supplier 6243 6243 0 0.00 4 10109 119 0 0
sp_payment_common 13945 13945 0 0.00 1 12883 25 0 0
sp_qd_handle_on_cancel_clo 1976 1976 0 0.00 0 17461 330 0 0
sp_qd_handle_on_invoice_upd_sts 11055 11055 0 0.00 0 18056 347 0 0
sp_qd_handle_on_reserve_upd_sts 10901 10901 0 0.00 0 13593 60 0 0
sp_reserve_write_off 10901 10901 0 0.00 6 13609 176 0 0
sp_supplier_invoice 5878 5878 0 0.00 7 90601 3506 0 0
sp_supplier_order 5932 5932 0 0.00 5 25442 1259 0 0
srv_aggregate_perf_data 91 91 0 0.00 2087 456925 23408 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1362 114709 43110 0 0
srv_find_qd_qs_mism 1085918 1085918 0 0.00 0 23747 5 0 0
srv_make_invnt_saldo 592 592 0 0.00 33 67839 1177 0 0
srv_make_money_saldo 876 876 0 0.00 1 11034 41 0 0
srv_recalc_idx_stat 4 4 0 0.00 136122 314036 238831 0 0
t$perf-abend:sp_add_invoice_to_stock 104 104 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5352 5352 0 0.00 0 8035 154 0 0
t$perf-norm:sp_cancel_adding_invoice 465 465 0 0.00 0 2048 90 0 0
t$perf-norm:sp_cancel_client_order 1976 1976 0 0.00 0 2807 14 0 0
t$perf-norm:sp_cancel_customer_reserve 709 709 0 0.00 0 2397 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 722 722 0 0.00 0 1439 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 429 429 0 0.00 0 324 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 3790 18 0 0
t$perf-norm:sp_cancel_supplier_order 425 425 0 0.00 0 27763 1483 0 0
t$perf-norm:sp_cancel_write_off 1117 1117 0 0.00 0 2772 9 0 0
t$perf-norm:sp_client_order 22133 22133 0 0.00 0 8155 17 0 0
t$perf-norm:sp_customer_reserve 1041 1041 0 0.00 0 3033 12 0 0
t$perf-norm:sp_pay_from_customer 7702 7702 0 0.00 0 6148 11 0 0
t$perf-norm:sp_pay_to_supplier 6243 6243 0 0.00 0 5501 10 0 0
t$perf-norm:sp_reserve_write_off 10901 10901 0 0.00 0 4129 11 0 0
t$perf-norm:sp_supplier_invoice 5878 5878 0 0.00 0 7898 19 0 0
t$perf-norm:sp_supplier_order 5932 5932 0 0.00 0 7398 22 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 120 3 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1200 27 0 0
t$perf-norm:srv_make_invnt_saldo 592 592 0 0.00 0 583 24 0 0
t$perf-norm:srv_make_money_saldo 876 876 0 0.00 0 764 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10904 10696 208 1.90 0 10447 21 0 208
v_all_customers 39730 39730 0 0.00 0 120 1 0 0
v_all_suppliers 32308 32308 0 0.00 0 67 1 0 0
v_all_wares 44258 44258 0 0.00 0 40 1 0 0
v_cancel_adding_invoice 930 930 0 0.00 0 1733 9 0 0
v_cancel_client_order 3952 3952 0 0.00 0 3649 7 0 0
v_cancel_customer_prepayment 1444 1444 0 0.00 0 1624 5 0 0
v_cancel_customer_reserve 1418 1418 0 0.00 0 2005 6 0 0
v_cancel_payment_to_supplier 858 858 0 0.00 0 3615 13 0 0
v_cancel_supplier_invoice 856 832 24 2.80 0 9203 27 0 24
v_cancel_supplier_order 850 850 0 0.00 0 7366 16 0 0
v_cancel_write_off 2234 2234 0 0.00 0 3238 15 0 0
v_max_id_clo_ord 5932 5932 0 0.00 0 515 1 0 0
v_max_id_clo_res 1041 1041 0 0.00 0 2119 16 0 0
v_max_id_ord_sup 5867 5867 0 0.00 0 7858 8 0 0
v_max_non_paid_invoice 1888 1888 0 0.00 0 4261 5 0 0
v_max_non_paid_realizn 4439 4439 0 0.00 0 2826 4 0 0
v_min_id_clo_ord 5932 5932 0 0.00 0 7295 7 0 0
v_min_id_clo_res 1041 1041 0 0.00 0 2658 7 0 0
v_min_id_ord_sup 5867 5867 0 0.00 0 4087 5 0 0
v_min_non_paid_invoice 6243 6243 0 0.00 0 5183 15 0 0
v_min_non_paid_realizn 7702 7702 0 0.00 0 12878 14 0 0
v_our_firm 11054 11054 0 0.00 0 257 1 0 0
v_reserve_write_off 21802 21802 0 0.00 0 10660 8 0 0
x_lock_depdocs_on_canc_invoice 11667 11667 0 0.00 0 23520 115 0 0
x_lock_depdocs_on_canc_sup_ord 425 425 0 0.00 0 3292 291 0 0
x_make_qstorno_1000_1200 5932 5932 0 0.00 1 25434 1175 0 0
x_make_qstorno_1200_2000 5878 5878 0 0.00 3 90572 3374 0 0
x_make_qstorno_2100_3300 488357 488357 0 0.00 0 77044 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-08-2021 19:38:42 to 09-08-2021 19:38:46.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:07:48 2 8192000000 8798783728 8809381888 5422784 0.066 1 1 2 0 5540832 55488 62560
17:08:50 24841 8192000000 10394995104 10715521024 1434937264 14.905 100 85 170 1 1468299392 6037360 27324768
17:10:20 14524 8192000000 10453762384 10784083968 1503493536 15.507 100 92 183 1 1539709600 7539360 28676704
17:11:42 18986 8192000000 10504632000 10843144192 1553090736 15.937 100 87 174 0 1589596880 9461408 27044736
17:13:07 14507 8192000000 10594373120 10954391552 1643845856 16.712 100 79 158 0 1677765344 10650832 23268656
17:14:25 9634 8192000000 10654777552 11024609280 1709075472 17.261 100 81 162 0 1745912848 13209408 23627968
17:15:37 6233 8192000000 10697378448 11075145728 1755404816 17.646 100 74 148 2 1788209536 11925264 20879456
17:16:45 3625 8192000000 10742227792 11123449856 1798973728 18.005 100 83 163 1 1837003088 14825632 23203728
17:17:50 2121 8192000000 10795074944 11183976448 1858095984 18.488 100 88 178 0 1895013152 11420000 25497168
17:18:54 1913 8192000000 10822968496 11212808192 1884547040 18.702 100 86 172 2 1919994304 11107024 24340240
17:19:57 3961 8192000000 10803015232 11223027712 1889141280 18.739 100 47 94 1 1912039264 9983600 12914384
17:21:03 7188 8192000000 10800685216 11233062912 1893560400 18.774 100 24 48 2 1906795104 6537008 6697696
17:22:12 7652 8192000000 10802464480 11240349696 1900342912 18.829 100 20 40 1 1912553120 5988032 6222176
17:23:21 54183 8192000000 10830825808 11260547072 1909736128 18.905 100 28 56 0 1923791760 4934096 9121536
17:25:16 6475 8192000000 10846609792 11274579968 1925297168 19.029 100 39 78 2 1943601504 7449392 10854944
17:26:26 7196 8192000000 10833239808 11268603904 1886133776 18.715 98 28 56 3 1901121856 6703488 8284592
17:27:33 9711 8192000000 10808690176 11217989632 1846469200 18.393 98 18 36 1 1857345440 5809696 5066544
17:28:43 17200 8192000000 10755293936 11181793280 1788877968 17.923 95 21 42 0 1800367856 5946368 5543520
17:30:04 8860 8192000000 10753500464 11178233856 1794893840 17.972 97 21 40 3 1806134800 4958208 6282752
17:31:14 6047 8192000000 10720281056 11136294912 1760660400 17.690 97 29 56 1 1774225104 5447696 8117008
17:32:22 68090 8192000000 10725807776 11154173952 1791594256 17.945 98 25 50 0 1804601568 6086880 6920432
17:34:35 7626 8192000000 10772552480 11200401408 1858951824 18.495 100 26 50 4 1871805808 5451312 7402672
17:35:42 6579 8192000000 10781002608 11210551296 1865886688 18.551 100 20 38 1 1876741008 4963056 5891264
17:36:49 6785 8192000000 10784145728 11223109632 1873321936 18.611 100 15 29 2 1887439920 9893632 4224352
17:37:57 83769 8192000000 10842760944 11269357568 1909498288 18.903 100 38 76 3 1925900080 5321808 11079984
17:40:27 5091 8192000000 10886903472 11309785088 1945279392 19.189 100 59 118 0 1974871920 13026736 16565792
17:41:44 15776 8192000000 10898359792 11328561152 1957522560 19.286 100 43 86 0 1985420096 15707648 12189888
17:43:06 14615 8192000000 10910288848 11336781824 1968720160 19.375 100 52 102 0 1999155408 15383440 15051808
17:44:28 22297 8192000000 10897764576 11334688768 1951942560 19.242 99 61 120 4 1988746736 19290784 17513392
17:46:03 7620 8192000000 10911882448 11339542528 1951935008 19.242 99 67 135 2 1994010448 22459792 19615648
17:47:15 4577 8192000000 10953399760 11373498368 1988958160 19.536 100 71 139 0 2036857936 27053712 20846064
17:48:24 1534 8192000000 10951670800 11360833536 1980120672 19.466 100 89 179 4 2031627632 26310544 25196416
17:49:31 2621 8192000000 10959826688 11380011008 1992271936 19.562 100 83 165 0 2045877568 29028128 24577504
17:50:37 8282 8192000000 10923739200 11346292736 1959701072 19.304 100 69 138 1 2011118112 30005200 21411840
17:51:48 1359 8192000000 10935011248 11341197312 1949243216 19.220 100 91 181 1 2013974160 36144992 28585952
17:52:55 5374 8192000000 10922253296 11333627904 1925476096 19.031 99 80 158 0 1993428304 43474848 24477360
17:54:08 6673 8192000000 10879720608 11301396480 1912724640 18.929 100 66 132 0 1968035152 34609536 20700976
17:55:24 1672 8192000000 10900577632 11317567488 1917545904 18.967 99 60 118 1 1979162464 43053824 18562736
17:56:34 5317 8192000000 10927476672 11344420864 1940839616 19.153 100 64 126 0 2002987472 42060384 20087472
17:57:48 1625 8192000000 10958787824 11360940032 1958984960 19.298 100 91 181 1 2035818384 48653600 28179824
17:58:54 4883 8192000000 10948675840 11371659264 1963033296 19.330 100 50 100 1 2026529104 47453680 16042128
18:00:05 14118 8192000000 10941656992 11375370240 1941806656 19.161 99 33 66 1 2005362240 52039328 11516256
18:01:25 9153 8192000000 10944978112 11380518912 1967248128 19.364 100 40 78 2 2025582496 44221824 14112544
18:02:45 6544 8192000000 10954286496 11396882432 1973028384 19.409 100 51 102 2 2037496416 47843136 16624896
18:03:57 17487 8192000000 10944123904 11406430208 1978505120 19.453 100 29 58 0 2033719488 45428272 9786096
18:05:31 28258 8192000000 10948387200 11406426112 1978398240 19.452 100 25 51 0 2035173776 47659936 9115600
18:07:11 49683 8192000000 10889090512 11343122432 1909805536 18.905 97 9 18 1 1935199568 22813072 2580960
18:09:01 44941 8192000000 10904961936 11343458304 1944931520 19.186 99 24 47 0 1960896576 9326960 6638096
18:10:48 79620 8192000000 10910137456 11386486784 1973129376 19.410 100 4 8 0 1985911552 12104816 677360
18:13:08 54700 8192000000 10935582800 11373436928 1990878240 19.551 100 39 79 2 2010903472 9004608 11020624
18:15:09 23871 8192000000 10935110704 11381317632 1971024352 19.394 99 24 46 2 1990509296 11832080 7652864
18:16:39 93331 8192000000 10938531280 11384336384 1987836144 19.527 100 31 61 1 2003769120 7188672 8744304
18:19:18 57016 8192000000 10946876608 11399020544 1960497712 19.310 98 26 52 0 1981638656 13137232 8003712
18:21:18 58578 8192000000 10934929424 11397734400 2001593008 19.635 100 25 50 1 2021161248 12553744 7014496
18:23:19 24022 8192000000 10944851776 11416899584 1995493440 19.587 99 26 52 0 2017982400 14852144 7636816
18:24:49 20755 8192000000 10949577232 11398684672 1982774080 19.487 99 52 105 3 2014430912 14352240 17304592
18:26:12 76560 8192000000 10894980976 11391135744 1949677344 19.224 99 14 28 1 1971363392 17471008 4215040
18:28:29 28074 8192000000 10880054880 11353702400 1924978784 19.027 98 9 18 1 1940984928 13609280 2396864
18:30:26 75563 8192000000 10897041280 11359805440 1942472752 19.166 98 26 52 4 1961303136 10882080 7948304
18:32:43 41890 8192000000 10871976112 11361239040 1929685520 19.064 98 3 6 0 1941497488 11094240 717728
18:34:31 68534 8192000000 10852234048 11331461120 1831149600 18.269 93 18 36 1 1846213232 9848608 5215024
18:37:05 43669 8192000000 10841937984 11309989888 1894522272 18.782 97 12 24 0 1910337008 10987136 4827600
18:38:50 80309 8192000000 10873426832 11347329024 1902445840 18.846 97 15 30 0 1922628848 15418720 4764288
18:41:51 18717 8192000000 10890581712 11344871424 1938527104 19.135 99 35 70 1 1957097072 8927440 9642528
18:43:09 27876 8192000000 10893644768 11351781376 1906485024 18.878 97 31 61 1 1928576944 13676608 8415312
18:44:42 55174 8192000000 10875631616 11346276352 1862818672 18.526 95 21 42 0 1889001840 19835792 6347376
18:46:38 82657 8192000000 10855472704 11320659968 1785089248 17.891 91 24 48 0 1801731680 10333904 6308528
18:49:01 114709 8192000000 10874775264 11359342592 1943796016 19.177 100 12 24 0 1964698736 17311680 3591040
18:52:31 63773 8192000000 10880659664 11353722880 1932913392 19.090 99 21 41 0 1951532832 12550080 6069360
18:54:38 72166 8192000000 10852751552 11338477568 1933746176 19.097 99 12 24 0 1946016368 8472080 3798112
18:56:50 62007 8192000000 10888713856 11360563200 1963784128 19.336 100 21 42 1 1978148912 7675424 6689360
18:58:56 61323 8192000000 10885302256 11362660352 1946333632 19.197 99 5 10 0 1955322720 7699664 1289424
19:01:10 79517 8192000000 10921217616 11394973696 1930504496 19.071 97 19 38 0 1948933616 13138272 5290848
19:03:32 55691 8192000000 10889209824 11372650496 1940792784 19.153 98 15 30 2 1954495968 9556064 4147120
19:05:48 45241 8192000000 10900570400 11367862272 1943240144 19.173 98 33 66 1 1967111552 14989440 8881968
19:07:33 44987 8192000000 10927084704 11401760768 1991413088 19.555 100 24 48 2 2020472320 22001696 7057536
19:09:29 76061 8192000000 10912331216 11376877568 1880751376 18.671 94 21 41 3 1899620976 12435280 6434320
19:11:45 64902 8192000000 10904615648 11386560512 1946314208 19.197 98 19 38 0 1964221168 12444496 5462464
19:13:53 83778 8192000000 10895144704 11362336768 1855047888 18.463 93 13 26 0 1871427680 12634672 3745120
19:16:17 82097 8192000000 10891511744 11377270784 1938947392 19.138 99 6 12 0 1962835856 21900368 1988096
19:18:55 51567 8192000000 10885341888 11369705472 1938018720 19.131 99 18 36 0 1963479904 19783504 5677680
19:20:57 58620 8192000000 10896756032 11376336896 1971829424 19.400 100 10 20 2 1986497904 11356160 3312320
19:22:56 61401 8192000000 10885135792 11358863360 1948134896 19.212 99 11 21 0 1959603008 8525648 2942464
19:25:07 22397 8192000000 10862663712 11352256512 1930433440 19.070 99 8 16 1 1946597760 14183696 1980624
19:27:13 72001 8192000000 10873305344 11357392896 1903018224 18.851 97 14 28 3 1921691744 14096288 4577232
19:29:29 47904 8192000000 10884318512 11350859776 1909867616 18.906 98 20 40 0 1923333200 7628816 5836768
19:31:19 47895 8192000000 10872076944 11356737536 1931179600 19.076 99 13 26 2 1945047280 10513104 3354576
19:33:11 51810 8192000000 10873460080 11347435520 1862741792 18.526 95 30 59 1 1883584144 12574128 8268224
19:35:06 108005 8192000000 10879263200 11358171136 1904687168 18.864 98 17 34 0 1924934624 14803808 5443648

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-08-2021 19:38:48 to 09-08-2021 19:39:03.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 104 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-08-2021 19:39:04 to 09-08-2021 19:39:06.

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          251264
MON$OLDEST_ACTIVE               252311
MON$OLDEST_SNAPSHOT             252311
MON$NEXT_TRANSACTION            252311
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                       2056288
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.33487 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33487 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33487 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33487 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 ---
:443: 
:444: mscs-test-161.budzdorov.ru    Mon Aug  9 19:37:48 2021
:445:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:446: 
:447: 
:448: mscs-test-161.budzdorov.ru    Mon Aug  9 19:37:59 2021
:449:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:450: 
:451: 
:452: mscs-test-161.budzdorov.ru    Mon Aug  9 19:38:00 2021
:453:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:454: 
--- end of diff output ---

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

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