Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 28.11.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 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 16498860 kB
MemAvailable 31504368 kB
Buffers 0 kB
Cached 15140716 kB
SwapCached 0 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-11-28 19:37:57
DTS_END                         2021-11-28 19:37:57.0700
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/54260
TRN_ID                          244804
ATT_ID                          653
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: 9651.87 20754 1158224
customer order: creation 177.83 357 21340
customer order: refuse 14.92 376 1790
order to supplier: creation 46.47 1323 5576
order to supplier: removal 3.74 100985 449
invoice (draft): creation 46.76 3488 5611
invoice (draft): removal 56.15 804 6738
invoice accept: apply 43.10 17732 5172
invoice accept: cancel 51.39 845 6167
customer reserve: creation 3932.64 178 471917
customer reserve: removal 4973.24 59 596789
realization accept: apply 85.49 191 10259
realization accept: cancel 87.36 118 10483
payment from customer: creation 60.33 130 7239
payment from customer: removal 6.08 78 729
payment to supplier: creation 50.68 144 6081
payment to supplier: removal 3.41 83 409
service: total inventory turnovers 5.02 1044 602
service: total monetary turnovers 6.38 31 766
service: aggregate perf. data 0.86 18520 103
service: refresh index statistics 0.03 268585 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 28-11-2021 19:38:57 to 28-11-2021 19:38:59.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2334
TEST_TIME 2 2585
TEST_TIME 3 2398
TEST_TIME 4 2484
TEST_TIME 5 2930
TEST_TIME 6 3675
TEST_TIME 7 3809
TEST_TIME 8 3830
TEST_TIME 9 4304
TEST_TIME 10 4512
TEST_TIME 11 4857
TEST_TIME 12 5027
TEST_TIME 13 5556
TEST_TIME 14 5955
TEST_TIME 15 6134
TEST_TIME 16 6582
TEST_TIME 17 6790
TEST_TIME 18 7065
TEST_TIME 19 7706
TEST_TIME 20 7623
TEST_TIME 21 7688
TEST_TIME 22 7841
TEST_TIME 23 8622
TEST_TIME 24 8813
TEST_TIME 25 8954
TEST_TIME 26 9017
TEST_TIME 27 9469
TEST_TIME 28 10074
TEST_TIME 29 10929
TEST_TIME 30 10987
TEST_TIME 31 10775
TEST_TIME 32 10648
TEST_TIME 33 10710
TEST_TIME 34 10729
TEST_TIME 35 10807
TEST_TIME 36 10744
TEST_TIME 37 10610
TEST_TIME 38 10489
TEST_TIME 39 10768
TEST_TIME 40 10581
TEST_TIME 41 10370
TEST_TIME 42 10379
TEST_TIME 43 10344
TEST_TIME 44 10327
TEST_TIME 45 10192
TEST_TIME 46 10192
TEST_TIME 47 10129
TEST_TIME 48 10110
TEST_TIME 49 10187
TEST_TIME 50 10140
TEST_TIME 51 10102
TEST_TIME 52 10015
TEST_TIME 53 10030
TEST_TIME 54 9953
TEST_TIME 55 9884
TEST_TIME 56 9888
TEST_TIME 57 9823
TEST_TIME 58 9764
TEST_TIME 59 9762
TEST_TIME 60 9934
TEST_TIME 61 9987
TEST_TIME 62 9948
TEST_TIME 63 9949
TEST_TIME 64 9989
TEST_TIME 65 9977
TEST_TIME 66 10038
TEST_TIME 67 10113
TEST_TIME 68 10092
TEST_TIME 69 10217
TEST_TIME 70 10159
TEST_TIME 71 10277
TEST_TIME 72 10270
TEST_TIME 73 10208
TEST_TIME 74 10111
TEST_TIME 75 10108
TEST_TIME 76 10075
TEST_TIME 77 9991
TEST_TIME 78 9939
TEST_TIME 79 9911
TEST_TIME 80 9900
TEST_TIME 81 9880
TEST_TIME 82 9964
TEST_TIME 83 9894
TEST_TIME 84 9858
TEST_TIME 85 9818
TEST_TIME 86 9812
TEST_TIME 87 9808
TEST_TIME 88 9788
TEST_TIME 89 9749
TEST_TIME 90 9817
TEST_TIME 91 9807
TEST_TIME 92 9821
TEST_TIME 93 9825
TEST_TIME 94 9899
TEST_TIME 95 9865
TEST_TIME 96 9863
TEST_TIME 97 9897
TEST_TIME 98 9845
TEST_TIME 99 10030
TEST_TIME 100 9986
TEST_TIME 101 9892
TEST_TIME 102 9880
TEST_TIME 103 9833
TEST_TIME 104 9795
TEST_TIME 105 9802
TEST_TIME 106 9782
TEST_TIME 107 9757
TEST_TIME 108 9720
TEST_TIME 109 9708
TEST_TIME 110 9658
TEST_TIME 111 9664
TEST_TIME 112 9631
TEST_TIME 113 9627
TEST_TIME 114 9622
TEST_TIME 115 9632
TEST_TIME 116 9630
TEST_TIME 117 9621
TEST_TIME 118 9628
TEST_TIME 119 9657
TEST_TIME 120 9656

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 28-11-2021 19:39:01 to 28-11-2021 19:39:04.

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 1596008 1596008 0 0.00 0 24096 7 0 0
doc_list_biud 1596008 1596008 0 0.00 0 25595 28 0 0
sp_add_invoice_to_stock 5296 5172 124 2.34 54 188761 17733 0 124
sp_cancel_adding_invoice 6167 6167 0 0.00 5 65036 846 0 0
sp_cancel_client_order 1790 1790 0 0.00 4 12477 377 0 0
sp_cancel_customer_reserve 596789 596789 0 0.00 1 25401 60 0 0
sp_cancel_pay_from_customer 729 729 0 0.00 2 2704 78 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 2 4638 84 0 0
sp_cancel_supplier_invoice 6753 6738 15 0.22 2 14337 804 0 15
sp_cancel_supplier_order 449 449 0 0.00 6 1171992 100985 0 0
sp_cancel_write_off 10483 10483 0 0.00 1 19673 119 0 0
sp_client_order 21340 21340 0 0.00 4 74863 358 0 0
sp_customer_reserve 471917 471917 0 0.00 0 45537 178 0 0
sp_fill_shopping_cart 32527 32527 0 0.00 1 25609 39 0 0
sp_fill_shopping_cart_clo_res 471910 471910 0 0.00 0 23109 14 0 0
sp_get_clo_for_invoice 5165 5165 0 0.00 0 29247 285 0 0
sp_kill_cost_storno 17788 17788 0 0.00 0 7295 61 0 0
sp_kill_qstorno_ret_qs2qd 1211248 1211248 0 0.00 0 25352 32 0 0
sp_kill_qty_storno 637840 637840 0 0.00 0 25595 70 0 0
sp_lock_selected_doc 21367 21367 0 0.00 0 7929 6 0 0
sp_make_cost_storno 28744 28744 0 0.00 0 20471 53 0 0
sp_multiply_rows_for_pdistr 28744 28744 0 0.00 0 24063 34 0 0
sp_multiply_rows_for_qdistr 21340 21340 0 0.00 0 74850 166 0 0
sp_pay_from_customer 7239 7239 0 0.00 4 17470 131 0 0
sp_pay_to_supplier 6081 6081 0 0.00 4 24100 144 0 0
sp_payment_common 13320 13320 0 0.00 1 17415 33 0 0
sp_qd_handle_on_cancel_clo 1790 1790 0 0.00 0 12470 349 0 0
sp_qd_handle_on_invoice_upd_sts 11332 11332 0 0.00 0 15073 333 0 0
sp_qd_handle_on_reserve_upd_sts 10259 10259 0 0.00 0 25595 70 0 0
sp_reserve_write_off 10259 10259 0 0.00 6 26669 191 0 0
sp_supplier_invoice 5611 5611 0 0.00 6 85709 3488 0 0
sp_supplier_order 5576 5576 0 0.00 5 35853 1324 0 0
srv_aggregate_perf_data 103 103 0 0.00 3016 140837 18521 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2842 141704 43687 0 0
srv_find_qd_qs_mism 1109695 1109695 0 0.00 0 19658 6 0 0
srv_make_invnt_saldo 602 602 0 0.00 36 61584 1045 0 0
srv_make_money_saldo 766 766 0 0.00 1 4990 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 187449 375694 268585 0 0
t$perf-abend:sp_add_invoice_to_stock 124 124 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5172 5172 0 0.00 0 25758 157 0 0
t$perf-norm:sp_cancel_adding_invoice 458 458 0 0.00 0 5539 91 0 0
t$perf-norm:sp_cancel_client_order 1790 1790 0 0.00 0 2469 11 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 1288 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 729 729 0 0.00 0 626 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 1188 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 408 408 0 0.00 0 727 15 0 0
t$perf-norm:sp_cancel_supplier_order 449 449 0 0.00 0 18874 1515 0 0
t$perf-norm:sp_cancel_write_off 1037 1037 0 0.00 0 4963 8 0 0
t$perf-norm:sp_client_order 21340 21340 0 0.00 0 20444 21 0 0
t$perf-norm:sp_customer_reserve 1116 1116 0 0.00 0 5346 27 0 0
t$perf-norm:sp_pay_from_customer 7239 7239 0 0.00 0 6218 11 0 0
t$perf-norm:sp_pay_to_supplier 6081 6081 0 0.00 0 4677 8 0 0
t$perf-norm:sp_reserve_write_off 10259 10259 0 0.00 0 11518 11 0 0
t$perf-norm:sp_supplier_invoice 5611 5611 0 0.00 0 7610 20 0 0
t$perf-norm:sp_supplier_order 5576 5576 0 0.00 0 7498 18 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 720 28 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 700 18 0 0
t$perf-norm:srv_make_invnt_saldo 602 602 0 0.00 0 1482 24 0 0
t$perf-norm:srv_make_money_saldo 766 766 0 0.00 0 1849 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 6 3 0 0
v_add_invoice_to_stock 10578 10330 248 2.34 0 6722 19 0 248
v_all_customers 38608 38608 0 0.00 0 21 1 0 0
v_all_suppliers 31248 31248 0 0.00 0 21 1 0 0
v_all_wares 42680 42680 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 916 916 0 0.00 0 513 6 0 0
v_cancel_client_order 3580 3580 0 0.00 0 1392 3 0 0
v_cancel_customer_prepayment 1458 1458 0 0.00 0 2192 5 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 1087 4 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 3210 7 0 0
v_cancel_supplier_invoice 846 816 30 3.54 0 2175 18 0 30
v_cancel_supplier_order 898 898 0 0.00 0 4862 13 0 0
v_cancel_write_off 2074 2074 0 0.00 0 2239 12 0 0
v_max_id_clo_ord 5576 5576 0 0.00 0 4249 3 0 0
v_max_id_clo_res 1116 1116 0 0.00 0 2647 14 0 0
v_max_id_ord_sup 5611 5611 0 0.00 0 2350 3 0 0
v_max_non_paid_invoice 1644 1644 0 0.00 0 3208 7 0 0
v_max_non_paid_realizn 3918 3918 0 0.00 0 1570 4 0 0
v_min_id_clo_ord 5576 5576 0 0.00 0 8781 13 0 0
v_min_id_clo_res 1116 1116 0 0.00 0 6232 19 0 0
v_min_id_ord_sup 5611 5611 0 0.00 0 4085 6 0 0
v_min_non_paid_invoice 6081 6081 0 0.00 0 8533 18 0 0
v_min_non_paid_realizn 7239 7239 0 0.00 0 17407 29 0 0
v_our_firm 10714 10714 0 0.00 0 18 1 0 0
v_reserve_write_off 20518 20518 0 0.00 0 5641 6 0 0
x_lock_depdocs_on_canc_invoice 12497 12497 0 0.00 0 22642 118 0 0
x_lock_depdocs_on_canc_sup_ord 449 449 0 0.00 0 21324 347 0 0
x_make_qstorno_1000_1200 5576 5576 0 0.00 2 35578 1229 0 0
x_make_qstorno_1200_2000 5611 5611 0 0.00 2 85679 3359 0 0
x_make_qstorno_2100_3300 471917 471917 0 0.00 0 41880 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 28-11-2021 19:39:05 to 28-11-2021 19:39:09.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:00 4 8192000000 8801002560 8812990464 7015008 0.085 2 2 3 0 7156112 66144 74960
17:09:01 45321 8192000000 9938616576 10258485248 1015298976 11.027 100 91 182 0 1045300864 6548416 23453472
17:10:56 16900 8192000000 9970097104 10297331712 1050932544 11.370 100 92 184 0 1083777248 9344272 23500432
17:12:19 10053 8192000000 9995264016 10337050624 1082158496 11.668 100 71 142 0 1109158848 9989264 17011088
17:13:32 10350 8192000000 10047243024 10398060544 1133177904 12.151 100 83 166 1 1164395824 11712912 19505008
17:14:44 5563 8192000000 10078370528 10437709824 1168892320 12.486 100 86 172 0 1201388384 12614992 19881072
17:15:55 7015 8192000000 10124360304 10493214720 1208534496 12.856 100 81 160 1 1239180048 12720000 17925552
17:17:05 3177 8192000000 10155870592 10528915456 1241158208 13.157 100 83 165 0 1272136224 12637344 18340672
17:18:10 3028 8192000000 10184181248 10562174976 1265934560 13.384 100 81 162 1 1297655856 14712976 17008320
17:19:14 1481 8192000000 10216629424 10601308160 1296490496 13.663 100 93 189 4 1330392480 13013584 20888400
17:20:16 8602 8192000000 10184314512 10595782656 1300834368 13.703 100 22 44 0 1312019792 6059952 5125472
17:21:26 6106 8192000000 10207142800 10615427072 1312866832 13.812 100 31 62 1 1326877376 6919872 7090672
17:22:36 12889 8192000000 10201258112 10624495616 1322826960 13.902 100 15 30 0 1332864608 5994976 4042672
17:23:50 75751 8192000000 10224295296 10646507520 1329265280 13.961 100 42 84 1 1345105200 5296176 10543744
17:26:06 5278 8192000000 10234662736 10652172288 1335703120 14.019 100 37 75 0 1351529312 7199808 8626384
17:27:12 11609 8192000000 10211450976 10630569984 1304142528 13.733 98 27 54 0 1316068048 5413216 6512304
17:28:26 15048 8192000000 10172281872 10576666624 1227388496 13.030 94 23 46 4 1238909184 5497424 6023264
17:29:43 11957 8192000000 10141308032 10554597376 1174380576 12.538 91 26 52 1 1185531168 4743040 6407552
17:30:55 7694 8192000000 10148447024 10555678720 1239718048 13.144 98 34 68 5 1253746464 6467792 7560624
17:32:05 7830 8192000000 10160255152 10563579904 1255493424 13.289 99 22 44 1 1266065728 5347136 5225168
17:33:16 71651 8192000000 10174301888 10586529792 1278965904 13.504 99 23 46 1 1289602512 5632864 5003744
17:35:28 6773 8192000000 10200944864 10611310592 1303688624 13.729 100 31 62 0 1317055904 6010224 7357056
17:36:37 7828 8192000000 10201043664 10618802176 1303923760 13.731 99 36 71 1 1318276080 5497792 8854528
17:37:45 18770 8192000000 10214048864 10630443008 1319323120 13.871 100 14 28 1 1327566496 5609568 2633808
17:39:10 13786 8192000000 10256727376 10665742336 1346667008 14.117 100 64 124 1 1371086560 10067728 14351824
17:40:30 57145 8192000000 10274898704 10689994752 1356127072 14.203 100 53 106 1 1381928768 14351600 11450096
17:42:30 30985 8192000000 10293786720 10713542656 1374785280 14.370 100 37 73 1 1402804592 19309696 8709616
17:44:05 18010 8192000000 10309342736 10731487232 1389420304 14.501 100 39 76 3 1418831024 19562592 9848128
17:45:25 13296 8192000000 10327953808 10746114048 1396295136 14.562 100 59 115 1 1434640320 25306736 13038448
17:46:40 9429 8192000000 10334408480 10760605696 1407867824 14.665 100 45 89 0 1445370528 26278800 11223904
17:47:53 14847 8192000000 10333335536 10761330688 1405590864 14.645 100 44 88 0 1445467888 28423104 11453920
17:49:14 10022 8192000000 10350946960 10773078016 1411227200 14.695 100 63 125 3 1464455168 38074240 15153728
17:50:27 7601 8192000000 10358448560 10784124928 1411470400 14.697 100 55 110 2 1464446112 38289552 14686160
17:51:40 5090 8192000000 10364504496 10789052416 1424042016 14.809 100 65 128 1 1471724240 32371456 15310768
17:52:51 3825 8192000000 10367225888 10781028352 1402527424 14.617 99 90 180 0 1463486688 39243504 21715760
17:53:58 3979 8192000000 10380777168 10793984000 1416999824 14.746 100 75 150 3 1479790384 44015168 18775392
17:55:07 8639 8192000000 10363434592 10784821248 1388723808 14.494 98 65 126 2 1451383392 46864736 15794848
17:56:20 4538 8192000000 10367840880 10786394112 1403290080 14.624 99 58 116 1 1471945088 53190704 15464304
17:57:35 4128 8192000000 10351323872 10755960832 1382064256 14.435 100 77 154 0 1448811760 47228448 19519056
17:58:45 2839 8192000000 10358761264 10773815296 1379245392 14.410 99 84 169 2 1454705968 52632736 22827840
17:59:53 6854 8192000000 10356519088 10775625728 1382210992 14.436 100 54 108 0 1462469200 65174512 15083696
18:01:03 6960 8192000000 10336543648 10757361664 1377433888 14.394 99 49 100 2 1438221680 48132688 12655104
18:02:23 25521 8192000000 10335706656 10769969152 1386795280 14.477 100 30 59 0 1443801776 48897280 8109216
18:03:52 14941 8192000000 10342391568 10788925440 1373611296 14.359 100 23 46 1 1446417088 65497888 7307904
18:05:09 41131 8192000000 10314142320 10751963136 1347402944 14.124 99 23 46 2 1413622768 60317344 5902480
18:06:53 60303 8192000000 10271983952 10728787968 1349990080 14.147 99 7 14 0 1375403040 23939920 1473040
18:08:54 34729 8192000000 10293416624 10733801472 1355634384 14.198 98 39 78 0 1373045424 8299472 9111568
18:10:47 71330 8192000000 10307358208 10762452992 1375391808 14.375 99 9 18 0 1391279904 14264784 1623312
18:12:59 46037 8192000000 10298167584 10743513088 1371158960 14.337 99 18 36 0 1382852784 7798832 3894992
18:14:54 99390 8192000000 10292239920 10747416576 1347616560 14.126 97 20 40 0 1375013424 22516352 4880512
18:17:36 98924 8192000000 10290248064 10723270656 1324744640 13.920 96 44 88 2 1345663968 9984272 10935056
18:20:16 35091 8192000000 10302135072 10746527744 1384170928 14.454 100 46 93 0 1408023824 13504848 10348048
18:21:53 56234 8192000000 10287085600 10738466816 1360747552 14.244 98 19 38 1 1375558336 10309344 4501440
18:23:51 46178 8192000000 10286768448 10743201792 1373567312 14.359 99 13 26 0 1384585776 8304496 2713968
18:25:38 67510 8192000000 10284446352 10744131584 1362053936 14.256 98 24 48 0 1384872400 16948528 5869936
18:27:50 45423 8192000000 10291545872 10753282048 1373485440 14.358 99 18 33 1 1395338048 17426288 4426320
18:29:36 56779 8192000000 10289745392 10751143936 1356407024 14.205 97 13 26 0 1374793520 14938832 3447664
18:33:00 41484 8192000000 10270659088 10725072896 1347898592 14.129 98 22 44 0 1364202992 11244400 5060000
18:34:42 63951 8192000000 10260324288 10715467776 1315299712 13.834 95 9 18 0 1331117088 14023312 1794064
18:36:47 107987 8192000000 10281741248 10749542400 1317421088 13.853 96 11 22 1 1346403392 26297664 2684640
18:39:36 62156 8192000000 10284125856 10735112192 1347321808 14.123 98 23 44 2 1363144592 10761024 5061760
18:41:42 17757 8192000000 10280730112 10725752832 1345927120 14.111 98 32 62 1 1360450704 7527776 6995808
18:43:00 30183 8192000000 10280389920 10735235072 1360350656 14.241 99 44 88 1 1385693216 15169008 10173552
18:44:43 56502 8192000000 10287090944 10734546944 1317381408 13.853 96 14 28 0 1345507488 24793280 3332800
18:46:43 67265 8192000000 10297534336 10744311808 1362402128 14.259 99 24 48 0 1381476480 13577344 5497008
18:48:58 93586 8192000000 10275802960 10707156992 1296500832 13.663 94 7 14 1 1308566432 10667584 1398016
18:51:32 110101 8192000000 10285080048 10740137984 1373271488 14.356 100 30 60 0 1394151264 14274848 6604928
18:54:23 57475 8192000000 10289517280 10737565696 1365837280 14.290 98 36 70 1 1383220928 9181744 8201904
18:56:46 46502 8192000000 10290540320 10752454656 1369997232 14.327 98 22 44 0 1387990496 12296720 5696544
18:58:33 59747 8192000000 10286264944 10744713216 1368599104 14.314 98 20 40 0 1390457504 16675152 5183248
19:01:19 47713 8192000000 10273778848 10740899840 1376671664 14.387 100 16 32 0 1397131632 16735408 3724560
19:03:26 38388 8192000000 10274979248 10728542208 1338766432 14.046 97 13 26 0 1348967936 6809664 3391840
19:05:21 34983 8192000000 10281035408 10727342080 1349833200 14.146 98 19 38 1 1363058944 8900304 4325440
19:07:01 49739 8192000000 10251079520 10704572416 1310648976 13.792 96 21 40 1 1326780352 11439280 4692096
19:08:51 39230 8192000000 10264473696 10701762560 1331275296 13.979 97 31 62 1 1346599360 8342352 6981712
19:10:31 62234 8192000000 10296067312 10738622464 1354963680 14.192 98 16 32 0 1371732288 13142704 3625904
19:12:35 45360 8192000000 10309116080 10753241088 1382852224 14.442 100 35 71 0 1399923312 9482016 7589072
19:14:23 89779 8192000000 10312871920 10771038208 1332573648 13.990 95 18 37 0 1354553584 17538016 4441920
19:17:29 141704 8192000000 10283442480 10747043840 1369783952 14.325 99 24 49 0 1384658160 9102240 5771968
19:20:51 25883 8192000000 10294491968 10743214080 1380091760 14.417 100 38 76 0 1397267568 8722688 8453120
19:22:48 42795 8192000000 10251918464 10711527424 1328119216 13.950 97 21 42 0 1339776064 6906640 4750208
19:24:46 35712 8192000000 10262226784 10717888512 1339865552 14.056 98 17 34 1 1354859168 11547280 3446336
19:26:32 61700 8192000000 10267490272 10724818944 1357710912 14.217 99 16 32 0 1370275824 8863968 3700944
19:28:55 97633 8192000000 10242013520 10696175616 1242081344 13.165 91 3 6 0 1255122992 12625680 415968
19:31:39 41725 8192000000 10250108272 10698338304 1312542000 13.809 98 21 42 0 1327314672 10127504 4645168
19:33:22 51579 8192000000 10280750368 10728161280 1357371248 14.214 100 11 22 1 1372947808 12783872 2792688
19:35:46 41379 8192000000 10276675904 10729975808 1342072288 14.076 98 17 34 1 1362288384 16136400 4079696
19:37:41 37141 8192000000 9147750208 9400721408 307922768 3.622 23 2 4 0 312773840 4506640 344432

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 28-11-2021 19:39:11 to 28-11-2021 19:39:26.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 124 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 28-11-2021 19:39:27 to 28-11-2021 19:39:29.

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          245338
MON$OLDEST_ACTIVE               245339
MON$OLDEST_SNAPSHOT             245339
MON$NEXT_TRANSACTION            245339
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                       2053792
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.33540 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.8.33540 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.8.33540 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.8.33540 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 ---
--- end of diff output ---

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

28.11.21 19:39:30. 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-11-28 19:39:32 No severe PSQL-related problems occured
28.11.21 19:39:34. End of report.