Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.04.2022 22:39
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 16424904 kB
MemAvailable 31513908 kB
Buffers 0 kB
Cached 15313800 kB
SwapCached 2712 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.10
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-04-19 22:38:12
DTS_END                         2022-04-19 22:38:12.3200
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/48792
TRN_ID                          250041
ATT_ID                          679
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: 9524.42 20435 1142931
customer order: creation 181.66 310 21799
customer order: refuse 15.13 315 1815
order to supplier: creation 48.11 1296 5773
order to supplier: removal 3.32 98185 398
invoice (draft): creation 47.42 3428 5690
invoice (draft): removal 53.94 753 6473
invoice accept: apply 43.40 18146 5208
invoice accept: cancel 48.49 803 5819
customer reserve: creation 3984.64 180 478157
customer reserve: removal 4791.56 53 574987
realization accept: apply 87.67 187 10520
realization accept: cancel 84.08 100 10090
payment from customer: creation 61.10 116 7332
payment from customer: removal 6.47 102 776
payment to supplier: creation 50.43 117 6051
payment to supplier: removal 3.93 75 472
service: total inventory turnovers 5.10 928 612
service: total monetary turnovers 7.13 33 856
service: aggregate perf. data 0.83 22144 99
service: refresh index statistics 0.03 261423 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 19-04-2022 22:39:36 to 19-04-2022 22:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2499
TEST_TIME 2 3543
TEST_TIME 3 3436
TEST_TIME 4 4174
TEST_TIME 5 4578
TEST_TIME 6 4496
TEST_TIME 7 4825
TEST_TIME 8 5027
TEST_TIME 9 5439
TEST_TIME 10 5703
TEST_TIME 11 6331
TEST_TIME 12 6332
TEST_TIME 13 6734
TEST_TIME 14 7131
TEST_TIME 15 7369
TEST_TIME 16 7705
TEST_TIME 17 8203
TEST_TIME 18 8110
TEST_TIME 19 8468
TEST_TIME 20 9221
TEST_TIME 21 9253
TEST_TIME 22 9617
TEST_TIME 23 9738
TEST_TIME 24 9536
TEST_TIME 25 9974
TEST_TIME 26 9943
TEST_TIME 27 10667
TEST_TIME 28 10464
TEST_TIME 29 10719
TEST_TIME 30 10559
TEST_TIME 31 10500
TEST_TIME 32 10495
TEST_TIME 33 10411
TEST_TIME 34 10486
TEST_TIME 35 10314
TEST_TIME 36 10119
TEST_TIME 37 9965
TEST_TIME 38 10114
TEST_TIME 39 9999
TEST_TIME 40 9948
TEST_TIME 41 9856
TEST_TIME 42 9844
TEST_TIME 43 9711
TEST_TIME 44 9766
TEST_TIME 45 9938
TEST_TIME 46 9829
TEST_TIME 47 9676
TEST_TIME 48 9722
TEST_TIME 49 9785
TEST_TIME 50 9692
TEST_TIME 51 9674
TEST_TIME 52 9613
TEST_TIME 53 9739
TEST_TIME 54 9737
TEST_TIME 55 9698
TEST_TIME 56 9791
TEST_TIME 57 9786
TEST_TIME 58 9869
TEST_TIME 59 9854
TEST_TIME 60 9835
TEST_TIME 61 9859
TEST_TIME 62 9878
TEST_TIME 63 10015
TEST_TIME 64 10022
TEST_TIME 65 9973
TEST_TIME 66 9929
TEST_TIME 67 9866
TEST_TIME 68 9891
TEST_TIME 69 9789
TEST_TIME 70 9773
TEST_TIME 71 9719
TEST_TIME 72 9786
TEST_TIME 73 9721
TEST_TIME 74 9709
TEST_TIME 75 9622
TEST_TIME 76 9541
TEST_TIME 77 9564
TEST_TIME 78 9574
TEST_TIME 79 9658
TEST_TIME 80 9622
TEST_TIME 81 9613
TEST_TIME 82 9696
TEST_TIME 83 9695
TEST_TIME 84 9669
TEST_TIME 85 9718
TEST_TIME 86 9766
TEST_TIME 87 9850
TEST_TIME 88 9819
TEST_TIME 89 9788
TEST_TIME 90 9895
TEST_TIME 91 9826
TEST_TIME 92 9763
TEST_TIME 93 9758
TEST_TIME 94 9701
TEST_TIME 95 9678
TEST_TIME 96 9633
TEST_TIME 97 9625
TEST_TIME 98 9613
TEST_TIME 99 9562
TEST_TIME 100 9519
TEST_TIME 101 9509
TEST_TIME 102 9488
TEST_TIME 103 9492
TEST_TIME 104 9470
TEST_TIME 105 9472
TEST_TIME 106 9486
TEST_TIME 107 9461
TEST_TIME 108 9448
TEST_TIME 109 9475
TEST_TIME 110 9482
TEST_TIME 111 9493
TEST_TIME 112 9533
TEST_TIME 113 9504
TEST_TIME 114 9662
TEST_TIME 115 9628
TEST_TIME 116 9672
TEST_TIME 117 9619
TEST_TIME 118 9578
TEST_TIME 119 9605
TEST_TIME 120 9531

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 19-04-2022 22:39:40 to 19-04-2022 22:39:43.

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 1587348 1587348 0 0.00 0 38731 6 0 0
doc_list_biud 1587348 1587348 0 0.00 0 58813 25 0 0
sp_add_invoice_to_stock 5327 5208 119 2.23 31 176366 18147 0 119
sp_cancel_adding_invoice 5819 5819 0 0.00 5 68285 804 0 0
sp_cancel_client_order 1815 1815 0 0.00 4 6934 316 0 0
sp_cancel_customer_reserve 574987 574987 0 0.00 1 58815 53 0 0
sp_cancel_pay_from_customer 776 776 0 0.00 3 6142 102 0 0
sp_cancel_pay_to_supplier 472 472 0 0.00 3 2697 76 0 0
sp_cancel_supplier_invoice 6482 6473 9 0.13 2 12058 754 0 9
sp_cancel_supplier_order 398 398 0 0.00 5 928420 98185 0 0
sp_cancel_write_off 10090 10090 0 0.00 1 8808 100 0 0
sp_client_order 21799 21799 0 0.00 3 23431 310 0 0
sp_customer_reserve 478157 478157 0 0.00 0 104879 181 0 0
sp_fill_shopping_cart 33246 33246 0 0.00 1 11291 34 0 0
sp_fill_shopping_cart_clo_res 478143 478143 0 0.00 0 47586 13 0 0
sp_get_clo_for_invoice 5194 5194 0 0.00 0 25180 299 0 0
sp_kill_cost_storno 17157 17157 0 0.00 0 9398 52 0 0
sp_kill_qstorno_ret_qs2qd 1166935 1166935 0 0.00 0 58810 29 0 0
sp_kill_qty_storno 615296 615296 0 0.00 0 58813 63 0 0
sp_lock_selected_doc 21748 21748 0 0.00 0 8630 7 0 0
sp_make_cost_storno 29097 29097 0 0.00 0 38722 55 0 0
sp_multiply_rows_for_pdistr 29097 29097 0 0.00 0 9287 31 0 0
sp_multiply_rows_for_qdistr 21799 21799 0 0.00 0 23417 133 0 0
sp_pay_from_customer 7332 7332 0 0.00 4 10165 117 0 0
sp_pay_to_supplier 6051 6051 0 0.00 5 12014 117 0 0
sp_payment_common 13383 13383 0 0.00 1 11809 32 0 0
sp_qd_handle_on_cancel_clo 1815 1815 0 0.00 0 6926 292 0 0
sp_qd_handle_on_invoice_upd_sts 11013 11013 0 0.00 0 22860 328 0 0
sp_qd_handle_on_reserve_upd_sts 10520 10520 0 0.00 0 17918 58 0 0
sp_reserve_write_off 10520 10520 0 0.00 6 20635 188 0 0
sp_supplier_invoice 5690 5690 0 0.00 6 102118 3429 0 0
sp_supplier_order 5773 5773 0 0.00 5 33099 1296 0 0
srv_aggregate_perf_data 99 99 0 0.00 2459 289961 22145 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1377 149901 47080 0 0
srv_find_qd_qs_mism 1094020 1094020 0 0.00 0 10611 5 0 0
srv_make_invnt_saldo 612 612 0 0.00 53 32617 928 0 0
srv_make_money_saldo 856 856 0 0.00 1 6434 34 0 0
srv_recalc_idx_stat 4 4 0 0.00 163169 470031 261424 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 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5208 5208 0 0.00 0 11316 164 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 0 3641 95 0 0
t$perf-norm:sp_cancel_client_order 1815 1815 0 0.00 0 2422 8 0 0
t$perf-norm:sp_cancel_customer_reserve 725 725 0 0.00 0 817 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 776 776 0 0.00 0 3904 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 472 472 0 0.00 0 4593 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 436 436 0 0.00 0 1944 19 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 35178 1777 0 0
t$perf-norm:sp_cancel_write_off 1002 1002 0 0.00 0 2403 5 0 0
t$perf-norm:sp_client_order 21799 21799 0 0.00 0 9082 19 0 0
t$perf-norm:sp_customer_reserve 1068 1068 0 0.00 0 3502 10 0 0
t$perf-norm:sp_pay_from_customer 7332 7332 0 0.00 0 4878 9 0 0
t$perf-norm:sp_pay_to_supplier 6051 6051 0 0.00 0 5492 10 0 0
t$perf-norm:sp_reserve_write_off 10520 10520 0 0.00 0 7243 11 0 0
t$perf-norm:sp_supplier_invoice 5690 5690 0 0.00 0 7505 19 0 0
t$perf-norm:sp_supplier_order 5773 5773 0 0.00 0 7355 19 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 1669 18 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 2696 48 0 0
t$perf-norm:srv_make_invnt_saldo 612 612 0 0.00 0 701 21 0 0
t$perf-norm:srv_make_money_saldo 856 856 0 0.00 0 1340 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10626 10388 238 2.23 0 6958 20 0 238
v_all_customers 39198 39198 0 0.00 0 44 1 0 0
v_all_suppliers 31622 31622 0 0.00 0 28 1 0 0
v_all_wares 43598 43598 0 0.00 0 121 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 400 6 0 0
v_cancel_client_order 3630 3630 0 0.00 0 4409 6 0 0
v_cancel_customer_prepayment 1552 1552 0 0.00 0 3696 14 0 0
v_cancel_customer_reserve 1450 1450 0 0.00 0 2577 9 0 0
v_cancel_payment_to_supplier 944 944 0 0.00 0 1087 5 0 0
v_cancel_supplier_invoice 890 872 18 2.02 0 1009 15 0 18
v_cancel_supplier_order 796 796 0 0.00 0 3409 11 0 0
v_cancel_write_off 2004 2004 0 0.00 0 7006 17 0 0
v_max_id_clo_ord 5773 5773 0 0.00 0 680 1 0 0
v_max_id_clo_res 1068 1068 0 0.00 0 4005 16 0 0
v_max_id_ord_sup 5672 5672 0 0.00 0 3879 7 0 0
v_max_non_paid_invoice 1685 1685 0 0.00 0 157 2 0 0
v_max_non_paid_realizn 4094 4094 0 0.00 0 1174 4 0 0
v_min_id_clo_ord 5773 5773 0 0.00 0 2806 6 0 0
v_min_id_clo_res 1068 1068 0 0.00 0 18979 22 0 0
v_min_id_ord_sup 5672 5672 0 0.00 0 4265 4 0 0
v_min_non_paid_invoice 6051 6051 0 0.00 0 11807 25 0 0
v_min_non_paid_realizn 7332 7332 0 0.00 0 9829 26 0 0
v_our_firm 10876 10876 0 0.00 0 22 1 0 0
v_reserve_write_off 21040 21040 0 0.00 0 6964 7 0 0
x_lock_depdocs_on_canc_invoice 11856 11856 0 0.00 0 18871 123 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 14062 416 0 0
x_make_qstorno_1000_1200 5773 5773 0 0.00 2 33090 1213 0 0
x_make_qstorno_1200_2000 5690 5690 0 0.00 2 100812 3317 0 0
x_make_qstorno_2100_3300 478157 478157 0 0.00 0 104878 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-04-2022 22:39:44 to 19-04-2022 22:39:48.

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
20:08:15 3 8192000000 8800051344 8811614208 6310704 0.076 2 1 2 0 6410336 56848 42784
20:09:17 39697 8192000000 9905440624 10212806656 979117152 10.676 100 91 182 0 1008607120 5974736 23515232
20:11:02 14582 8192000000 9926598464 10244100096 1011704608 10.992 100 87 174 0 1046810464 12644352 22461504
20:12:26 14781 8192000000 9975197328 10307395584 1063180608 11.487 100 84 168 3 1092655664 8630208 20844848
20:13:46 11756 8192000000 10034659408 10382188544 1126141680 12.085 100 84 166 1 1156571712 10149312 20280720
20:15:04 10944 8192000000 10095160624 10457628672 1179721200 12.588 100 79 158 2 1213854656 16163296 17970160
20:16:18 5086 8192000000 10137800544 10509811712 1231122016 13.064 100 83 164 2 1262330464 13206144 18002304
20:17:24 3114 8192000000 10173828432 10547957760 1261017888 13.339 100 90 180 0 1294586144 13359552 20208704
20:18:29 1710 8192000000 10192505696 10571464704 1278037744 13.495 100 93 187 2 1312448272 13659616 20750912
20:19:33 1464 8192000000 10212164096 10599411712 1294314528 13.644 100 80 161 0 1326608224 14567936 17725760
20:20:35 2866 8192000000 10196242848 10598793216 1298736560 13.684 100 49 98 1 1320062128 10728976 10596592
20:21:42 4825 8192000000 10199329872 10608832512 1313988832 13.822 100 34 67 0 1327864224 6286528 7588864
20:22:51 5998 8192000000 10196509168 10612662272 1315855984 13.839 100 37 76 2 1329470992 5552800 8062208
20:24:00 48826 8192000000 10205545904 10625978368 1319662608 13.874 100 28 53 1 1331836096 6114784 6058704
20:25:49 6869 8192000000 10216906832 10637205504 1328185952 13.951 100 21 42 1 1338834928 5786784 4862192
20:26:58 9207 8192000000 10211455472 10628788224 1311629328 13.801 99 21 42 1 1322667024 6346960 4690736
20:28:07 15782 8192000000 10172120864 10571366400 1236653568 13.115 95 24 46 1 1247763008 5912976 5196464
20:29:27 7924 8192000000 10187128768 10602450944 1299630640 13.692 100 20 40 1 1309869600 5605184 4633776
20:30:42 9123 8192000000 10167529920 10568339456 1239377312 13.141 97 20 40 1 1249624272 5405376 4841584
20:31:52 13936 8192000000 10134316160 10534838272 1200488192 12.781 96 13 26 0 1208416192 5210272 2717728
20:33:10 71385 8192000000 10161773552 10573889536 1251283936 13.250 98 28 56 0 1263159232 4922576 6952720
20:35:21 5942 8192000000 10182549376 10593906688 1290578448 13.609 100 38 76 2 1306319792 7337664 8403680
20:36:28 7091 8192000000 10207408000 10612801536 1308179680 13.770 100 17 34 1 1316404848 4753888 3471280
20:37:40 11474 8192000000 10207994944 10624626688 1317282848 13.852 100 22 44 2 1331832640 9323792 5226000
20:38:58 75084 8192000000 10252979008 10664329216 1344424144 14.097 100 35 70 0 1362175968 8894096 8857728
20:41:18 19245 8192000000 10278089456 10696835072 1363640816 14.270 100 41 82 0 1385342864 12349472 9352576
20:42:41 18640 8192000000 10298086784 10716078080 1379834896 14.415 100 58 114 1 1411423232 18232832 13355504
20:44:09 8101 8192000000 10316481632 10737852416 1395086496 14.551 100 56 112 0 1427671936 19783952 12801488
20:45:24 11147 8192000000 10321046560 10744037376 1400192464 14.597 100 54 108 1 1434762672 21748864 12821344
20:46:42 8182 8192000000 10314014480 10733592576 1383875328 14.451 99 61 122 0 1421607344 23176880 14555136
20:47:54 4482 8192000000 10320117568 10731098112 1387160640 14.481 100 72 143 4 1428216560 24018624 17037296
20:49:06 6042 8192000000 10328872752 10744205312 1394107664 14.543 100 60 119 1 1437293616 28562992 14622960
20:50:15 3890 8192000000 10322484624 10732797952 1384292656 14.455 100 71 142 2 1430471520 29029392 17149472
20:51:23 4286 8192000000 10342171664 10744209408 1378682736 14.405 99 79 158 0 1434901376 36734416 19484224
20:52:33 1374 8192000000 10336690896 10741551104 1380162224 14.418 100 89 178 1 1444788048 43228896 21396928
20:53:38 3331 8192000000 10337674688 10744958976 1377185008 14.391 99 89 178 1 1436789744 37811312 21793424
20:54:46 2403 8192000000 10316506512 10720305152 1354838192 14.191 99 67 136 1 1416864608 44390224 17636192
20:55:51 4384 8192000000 10329345584 10736132096 1372666480 14.351 100 88 177 1 1434706832 39801856 22238496
20:57:00 2846 8192000000 10339474144 10747465728 1376545984 14.386 100 84 164 2 1443963856 46158688 21259184
20:58:08 10796 8192000000 10338469136 10760912896 1383484144 14.448 100 47 94 0 1442609840 46456400 12669296
20:59:28 24714 8192000000 10323627168 10756304896 1375613584 14.377 99 43 86 0 1422964336 35704352 11646400
21:00:58 24431 8192000000 10306508272 10744553472 1354706400 14.190 98 28 56 0 1397993408 35567392 7719616
21:02:25 26545 8192000000 10296256368 10727882752 1326227120 13.933 97 22 44 0 1372846352 40548080 6071152
21:03:58 17299 8192000000 10293077264 10720657408 1343884816 14.092 99 34 68 1 1384625360 32218496 8522048
21:05:18 18297 8192000000 10287420752 10739548160 1372491968 14.349 100 21 41 1 1399681552 21284576 5905008
21:06:37 53965 8192000000 10286308416 10733785088 1375082832 14.373 100 40 80 2 1402040512 17428144 9529536
21:08:59 53261 8192000000 10299175568 10737201152 1368130736 14.310 99 16 31 0 1386848304 15213152 3504416
21:11:03 38833 8192000000 10307137552 10750377984 1386119648 14.471 99 44 85 1 1406881008 10222752 10538608
21:13:06 141232 8192000000 10313540784 10749792256 1356603808 14.207 97 35 70 1 1373580192 9163248 7813136
21:16:36 103854 8192000000 10308576944 10750324736 1380046480 14.417 99 15 30 0 1394430224 11257952 3125792
21:19:27 25479 8192000000 10305454848 10758651904 1384999232 14.461 99 19 38 0 1404620128 14891024 4729872
21:21:14 32568 8192000000 10325161872 10774978560 1391873328 14.523 99 38 77 0 1421255632 20383552 8998752
21:22:47 149901 8192000000 10292949072 10752266240 1327309472 13.943 94 10 20 0 1350715584 20797312 2608800
21:26:19 44720 8192000000 10306761936 10755457024 1368213664 14.311 99 21 42 0 1388356880 15187008 4956208
21:28:04 56577 8192000000 10270873680 10733309952 1356314576 14.204 98 15 30 0 1374238768 14493184 3431008
21:30:12 68303 8192000000 10301933120 10747080704 1352803472 14.173 97 21 42 0 1374397792 16733552 4860768
21:32:30 50591 8192000000 10267919904 10712039424 1301725712 13.711 95 19 38 1 1319139040 12727632 4685696
21:34:37 63642 8192000000 10275661520 10736680960 1349004816 14.139 99 9 18 0 1365762992 14548144 2210032
21:36:46 62660 8192000000 10284581600 10735566848 1339701840 14.055 97 14 28 0 1357920656 15520944 2697872
21:38:51 32757 8192000000 10296737664 10739494912 1369506352 14.323 99 28 56 0 1385232048 9370304 6355392
21:40:29 85900 8192000000 10284480320 10749685760 1341564160 14.072 97 32 63 0 1370003536 21086176 7353200
21:42:56 62987 8192000000 10283536000 10727104512 1320999776 13.886 95 20 39 0 1335357264 9781392 4576096
21:45:07 96048 8192000000 10266821472 10729058304 1335516320 14.017 97 23 46 1 1353766464 12325200 5924944
21:47:47 6618 8192000000 10285663424 10730639360 1358974912 14.228 99 47 95 1 1382212400 12175872 11061616
21:48:55 70935 8192000000 10277352208 10737594368 1372625216 14.351 100 12 24 0 1390506320 15356384 2524720
21:51:06 31145 8192000000 10284150000 10750214144 1375641808 14.378 100 12 24 0 1391583728 13281776 2660144
21:52:41 92823 8192000000 10280583760 10731286528 1368711824 14.316 99 18 36 0 1385617408 12540464 4365120
21:55:44 51823 8192000000 10284788016 10759397376 1368946592 14.318 99 9 17 1 1394210656 22990912 2273152
21:57:37 71661 8192000000 10269923408 10727411712 1358501760 14.224 99 7 14 0 1379541456 19512768 1526928
21:59:53 76829 8192000000 10292961136 10752421888 1374191584 14.365 99 23 46 1 1395847760 16625952 5030224
22:02:11 93169 8192000000 10290161344 10753798144 1357921152 14.219 98 16 32 0 1381180272 19438176 3820944
22:04:45 79439 8192000000 10306795184 10773704704 1388966384 14.497 100 14 28 0 1419231056 27054768 3209904
22:07:08 15888 8192000000 10303609424 10752397312 1387280080 14.482 99 30 58 1 1400929408 6682624 6966704
22:08:25 72472 8192000000 10265747520 10710675456 1298687056 13.683 93 11 22 0 1311832032 10104592 3040384
22:10:38 95735 8192000000 10280472288 10737299456 1346807568 14.119 97 23 46 0 1360722480 8858368 5056544
22:13:15 61009 8192000000 10255780288 10715054080 1342687424 14.082 97 19 36 1 1353615152 7014800 3912928
22:15:27 65755 8192000000 10270210736 10722697216 1336531488 14.026 97 21 41 0 1347916992 6455984 4929520
22:17:37 105191 8192000000 10265148704 10729766912 1335177504 14.014 97 11 21 0 1350433776 12769264 2487008
22:20:24 60448 8192000000 10274501136 10742067200 1362251680 14.258 99 12 24 0 1377759408 12738368 2769360
22:22:25 24446 8192000000 10264665728 10713563136 1349955696 14.147 99 32 64 3 1369463904 11761200 7747008
22:23:50 39730 8192000000 10267160256 10716540928 1362614592 14.261 100 27 54 2 1378894112 10258624 6020896
22:25:30 27995 8192000000 10262909456 10704248832 1314126544 13.823 96 22 44 0 1328925040 9834624 4963872
22:26:58 61330 8192000000 10271606192 10722099200 1352283088 14.168 99 16 32 1 1366062208 10139328 3639792
22:28:59 39060 8192000000 10292419632 10741239808 1361520000 14.251 100 39 78 0 1392101808 21956320 8625488
22:30:39 57656 8192000000 10283612032 10732339200 1334397840 14.007 97 26 53 0 1356452272 15703008 6351424
22:32:48 74050 8192000000 10299112048 10747629568 1362051536 14.256 99 13 26 2 1378332688 13348432 2932720
22:35:02 77579 8192000000 10273912272 10729701376 1344703920 14.100 97 15 29 0 1359638944 11387696 3547328
22:37:32 41497 8192000000 9352760368 9654951936 507822912 5.837 37 4 8 0 513562336 5056560 682864

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 19-04-2022 22:39:50 to 19-04-2022 22:40:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 119 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 19-04-2022 22:40:06 to 19-04-2022 22:40:08.

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          250375
MON$OLDEST_ACTIVE               250376
MON$OLDEST_SNAPSHOT             250376
MON$NEXT_TRANSACTION            250376
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                       2052768
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33577 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33577 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33577 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33577 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 ---
:680: 
:681: mscs-test-161.budzdorov.ru    Tue Apr 19 22:38:17 2022
:682:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:683: 
--- end of diff output ---

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

19.04.22 22:40:09. 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-04-19 22:40:11 No severe PSQL-related problems occured
19.04.22 22:40:13. End of report.