Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.03.2023 19: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 15504808 kB
MemAvailable 30412500 kB
Buffers 0 kB
Cached 16186812 kB
SwapCached 3088 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.11
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2023-03-11 19:38:40
DTS_END                         2023-03-11 19:38:40.3330
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_PAY_TO_SUPPLIER(26:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/59132
TRN_ID                          243626
ATT_ID                          720
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: 9038.08 20972 1084569
customer order: creation 172.91 355 20749
customer order: refuse 15.04 401 1805
order to supplier: creation 46.45 1149 5574
order to supplier: removal 3.31 88270 397
invoice (draft): creation 46.27 3277 5552
invoice (draft): removal 50.48 685 6057
invoice accept: apply 41.74 17950 5009
invoice accept: cancel 45.56 774 5467
customer reserve: creation 3886.69 177 466403
customer reserve: removal 4433.75 51 532050
realization accept: apply 82.50 152 9900
realization accept: cancel 83.01 109 9961
payment from customer: creation 59.95 123 7194
payment from customer: removal 5.48 92 657
payment to supplier: creation 49.11 123 5893
payment to supplier: removal 3.37 95 404
service: total inventory turnovers 4.86 1041 583
service: total monetary turnovers 6.65 21 798
service: aggregate perf. data 0.93 16453 112
service: refresh index statistics 0.03 288144 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-03-2023 19:39:45 to 11-03-2023 19:39:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3060
TEST_TIME 2 3335
TEST_TIME 3 2661
TEST_TIME 4 3708
TEST_TIME 5 4412
TEST_TIME 6 4232
TEST_TIME 7 4071
TEST_TIME 8 4349
TEST_TIME 9 4342
TEST_TIME 10 4392
TEST_TIME 11 4638
TEST_TIME 12 4837
TEST_TIME 13 5304
TEST_TIME 14 5870
TEST_TIME 15 6371
TEST_TIME 16 7260
TEST_TIME 17 7693
TEST_TIME 18 7920
TEST_TIME 19 8158
TEST_TIME 20 8353
TEST_TIME 21 8614
TEST_TIME 22 8602
TEST_TIME 23 8930
TEST_TIME 24 9567
TEST_TIME 25 9569
TEST_TIME 26 9412
TEST_TIME 27 9838
TEST_TIME 28 9880
TEST_TIME 29 9584
TEST_TIME 30 9340
TEST_TIME 31 9726
TEST_TIME 32 9599
TEST_TIME 33 9588
TEST_TIME 34 9568
TEST_TIME 35 9466
TEST_TIME 36 9313
TEST_TIME 37 9286
TEST_TIME 38 9176
TEST_TIME 39 9129
TEST_TIME 40 9148
TEST_TIME 41 9006
TEST_TIME 42 8952
TEST_TIME 43 9015
TEST_TIME 44 8926
TEST_TIME 45 8948
TEST_TIME 46 8869
TEST_TIME 47 8950
TEST_TIME 48 9010
TEST_TIME 49 9097
TEST_TIME 50 9110
TEST_TIME 51 9052
TEST_TIME 52 9169
TEST_TIME 53 9273
TEST_TIME 54 9366
TEST_TIME 55 9357
TEST_TIME 56 9351
TEST_TIME 57 9312
TEST_TIME 58 9404
TEST_TIME 59 9269
TEST_TIME 60 9215
TEST_TIME 61 9139
TEST_TIME 62 9139
TEST_TIME 63 9122
TEST_TIME 64 9050
TEST_TIME 65 8988
TEST_TIME 66 8973
TEST_TIME 67 8873
TEST_TIME 68 8900
TEST_TIME 69 8955
TEST_TIME 70 9001
TEST_TIME 71 8998
TEST_TIME 72 9016
TEST_TIME 73 9072
TEST_TIME 74 9151
TEST_TIME 75 9153
TEST_TIME 76 9127
TEST_TIME 77 9138
TEST_TIME 78 9137
TEST_TIME 79 9174
TEST_TIME 80 9226
TEST_TIME 81 9286
TEST_TIME 82 9225
TEST_TIME 83 9136
TEST_TIME 84 9124
TEST_TIME 85 9145
TEST_TIME 86 9132
TEST_TIME 87 9062
TEST_TIME 88 9054
TEST_TIME 89 9035
TEST_TIME 90 8988
TEST_TIME 91 8961
TEST_TIME 92 8986
TEST_TIME 93 8980
TEST_TIME 94 9026
TEST_TIME 95 9072
TEST_TIME 96 9039
TEST_TIME 97 9074
TEST_TIME 98 9110
TEST_TIME 99 9109
TEST_TIME 100 9126
TEST_TIME 101 9183
TEST_TIME 102 9175
TEST_TIME 103 9164
TEST_TIME 104 9201
TEST_TIME 105 9260
TEST_TIME 106 9276
TEST_TIME 107 9195
TEST_TIME 108 9137
TEST_TIME 109 9129
TEST_TIME 110 9103
TEST_TIME 111 9066
TEST_TIME 112 9008
TEST_TIME 113 9031
TEST_TIME 114 8999
TEST_TIME 115 8985
TEST_TIME 116 8967
TEST_TIME 117 9002
TEST_TIME 118 9043
TEST_TIME 119 9026
TEST_TIME 120 9039

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 11-03-2023 19:39:48 to 11-03-2023 19:39:52.

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 1514662 1514662 0 0.00 0 48994 6 0 0
doc_list_biud 1514662 1514662 0 0.00 0 50111 23 0 0
sp_add_invoice_to_stock 5116 5009 107 2.09 40 186100 17950 0 107
sp_cancel_adding_invoice 5467 5467 0 0.00 4 51929 774 0 0
sp_cancel_client_order 1805 1805 0 0.00 4 18628 401 0 0
sp_cancel_customer_reserve 532050 532050 0 0.00 0 48945 52 0 0
sp_cancel_pay_from_customer 657 657 0 0.00 3 14354 92 0 0
sp_cancel_pay_to_supplier 404 404 0 0.00 2 7924 95 0 0
sp_cancel_supplier_invoice 6066 6057 9 0.14 1 49762 685 0 9
sp_cancel_supplier_order 397 397 0 0.00 7 848747 88271 0 0
sp_cancel_write_off 9961 9961 0 0.00 1 15829 110 0 0
sp_client_order 20749 20749 0 0.00 3 39386 356 0 0
sp_customer_reserve 466403 466403 0 0.00 0 50273 177 0 0
sp_fill_shopping_cart 31873 31873 0 0.00 1 22528 41 0 0
sp_fill_shopping_cart_clo_res 466402 466402 0 0.00 0 19512 16 0 0
sp_get_clo_for_invoice 5008 5008 0 0.00 0 35942 357 0 0
sp_kill_cost_storno 16489 16489 0 0.00 0 21192 49 0 0
sp_kill_qstorno_ret_qs2qd 1080515 1080515 0 0.00 0 49543 28 0 0
sp_kill_qty_storno 570645 570645 0 0.00 0 50111 62 0 0
sp_lock_selected_doc 20655 20655 0 0.00 0 7883 4 0 0
sp_make_cost_storno 27995 27995 0 0.00 0 14415 57 0 0
sp_multiply_rows_for_pdistr 27995 27995 0 0.00 0 9548 27 0 0
sp_multiply_rows_for_qdistr 20749 20749 0 0.00 0 15036 170 0 0
sp_pay_from_customer 7194 7194 0 0.00 4 22978 124 0 0
sp_pay_to_supplier 5893 5893 0 0.00 4 10970 124 0 0
sp_payment_common 13087 13087 0 0.00 1 10667 30 0 0
sp_qd_handle_on_cancel_clo 1805 1805 0 0.00 0 18617 365 0 0
sp_qd_handle_on_invoice_upd_sts 10475 10475 0 0.00 0 50109 326 0 0
sp_qd_handle_on_reserve_upd_sts 9900 9900 0 0.00 0 13424 43 0 0
sp_reserve_write_off 9900 9900 0 0.00 6 16602 153 0 0
sp_supplier_invoice 5552 5552 0 0.00 5 133103 3278 0 0
sp_supplier_order 5574 5574 0 0.00 5 45515 1150 0 0
srv_aggregate_perf_data 112 112 0 0.00 2343 131063 16453 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 964 116366 44459 0 0
srv_find_qd_qs_mism 1035581 1035581 0 0.00 0 48993 5 0 0
srv_make_invnt_saldo 583 583 0 0.00 23 44651 1042 0 0
srv_make_money_saldo 798 798 0 0.00 0 876 22 0 0
srv_recalc_idx_stat 4 4 0 0.00 155938 476124 288145 0 0
t$perf-abend:sp_add_invoice_to_stock 107 107 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 5009 5009 0 0.00 0 14940 152 0 0
t$perf-norm:sp_cancel_adding_invoice 424 424 0 0.00 0 1851 73 0 0
t$perf-norm:sp_cancel_client_order 1805 1805 0 0.00 0 5757 15 0 0
t$perf-norm:sp_cancel_customer_reserve 663 663 0 0.00 0 664 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 657 657 0 0.00 0 1377 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 404 404 0 0.00 0 700 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 4109 27 0 0
t$perf-norm:sp_cancel_supplier_order 397 397 0 0.00 0 23048 1359 0 0
t$perf-norm:sp_cancel_write_off 1003 1003 0 0.00 0 1144 5 0 0
t$perf-norm:sp_client_order 20749 20749 0 0.00 0 6027 14 0 0
t$perf-norm:sp_customer_reserve 1028 1028 0 0.00 0 3180 7 0 0
t$perf-norm:sp_pay_from_customer 7194 7194 0 0.00 0 3240 7 0 0
t$perf-norm:sp_pay_to_supplier 5893 5893 0 0.00 0 14321 11 0 0
t$perf-norm:sp_reserve_write_off 9900 9900 0 0.00 0 9796 12 0 0
t$perf-norm:sp_supplier_invoice 5552 5552 0 0.00 0 3342 12 0 0
t$perf-norm:sp_supplier_order 5574 5574 0 0.00 0 4883 14 0 0
t$perf-norm:srv_aggregate_perf_data 112 112 0 0.00 0 886 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 48 2 0 0
t$perf-norm:srv_make_invnt_saldo 583 583 0 0.00 0 2285 16 0 0
t$perf-norm:srv_make_money_saldo 798 798 0 0.00 0 7851 23 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 1177 298 0 0
v_add_invoice_to_stock 10226 10012 214 2.09 0 20595 20 0 214
v_all_customers 37950 37950 0 0.00 0 21 0 0 0
v_all_suppliers 30894 30894 0 0.00 0 21 1 0 0
v_all_wares 41498 41498 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 848 848 0 0.00 0 3035 16 0 0
v_cancel_client_order 3610 3610 0 0.00 0 15608 10 0 0
v_cancel_customer_prepayment 1314 1314 0 0.00 0 13507 15 0 0
v_cancel_customer_reserve 1326 1326 0 0.00 0 662 3 0 0
v_cancel_payment_to_supplier 808 808 0 0.00 0 878 5 0 0
v_cancel_supplier_invoice 810 792 18 2.22 0 2414 20 0 18
v_cancel_supplier_order 794 794 0 0.00 0 13511 25 0 0
v_cancel_write_off 2006 2006 0 0.00 0 15656 25 0 0
v_max_id_clo_ord 5573 5573 0 0.00 0 631 1 0 0
v_max_id_clo_res 1028 1028 0 0.00 0 4717 24 0 0
v_max_id_ord_sup 5551 5551 0 0.00 0 4561 3 0 0
v_max_non_paid_invoice 1570 1570 0 0.00 0 1242 3 0 0
v_max_non_paid_realizn 3680 3680 0 0.00 0 5840 8 0 0
v_min_id_clo_ord 5573 5573 0 0.00 0 8906 10 0 0
v_min_id_clo_res 1028 1028 0 0.00 0 2020 10 0 0
v_min_id_ord_sup 5551 5551 0 0.00 0 8366 8 0 0
v_min_non_paid_invoice 5893 5893 0 0.00 0 10664 21 0 0
v_min_non_paid_realizn 7194 7194 0 0.00 0 7470 23 0 0
v_our_firm 10576 10576 0 0.00 0 16 1 0 0
v_reserve_write_off 19800 19800 0 0.00 0 16562 7 0 0
x_lock_depdocs_on_canc_invoice 11128 11128 0 0.00 0 34315 117 0 0
x_lock_depdocs_on_canc_sup_ord 397 397 0 0.00 0 16125 346 0 0
x_make_qstorno_1000_1200 5574 5574 0 0.00 1 45463 1076 0 0
x_make_qstorno_1200_2000 5552 5552 0 0.00 2 112469 3119 0 0
x_make_qstorno_2100_3300 466403 466403 0 0.00 0 50271 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-03-2023 19:39:53 to 11-03-2023 19:39:57.

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:44 20 8192000000 8824202000 8844517376 24210336 0.294 9 5 6 0 24485264 103456 171472
17:09:45 25666 8192000000 9921770032 10236395520 1005861968 10.935 100 85 170 0 1033999872 6198256 21939648
17:11:16 22521 8192000000 9965459904 10291421184 1044655488 11.309 100 89 178 1 1075889504 8552896 22681120
17:12:41 12737 8192000000 10001530560 10334322688 1081179632 11.659 100 87 174 0 1112801472 10438736 21183104
17:13:59 13207 8192000000 10035814656 10387009536 1123138752 12.057 100 77 154 0 1152288416 10821504 18328160
17:15:17 5800 8192000000 10084937424 10443689984 1171771520 12.513 100 85 170 1 1204337056 13149904 19415632
17:16:29 3723 8192000000 10120548784 10491768832 1212898624 12.896 100 78 156 0 1243729312 14006416 16824272
17:17:35 2423 8192000000 10156363968 10529759232 1247437584 13.215 100 85 170 1 1279653920 13073232 19143104
17:18:40 1642 8192000000 10186269616 10570125312 1274928384 13.467 100 82 164 1 1306611808 13277216 18406208
17:19:43 1545 8192000000 10207436048 10593288192 1293717728 13.638 100 82 162 1 1329263040 17587936 17957376
17:20:47 869 8192000000 10224689984 10613620736 1306014880 13.750 100 84 165 1 1336832352 12021616 18795856
17:21:49 7962 8192000000 10205559488 10614091776 1314322512 13.825 100 35 71 0 1329117088 7624880 7169696
17:23:04 7312 8192000000 10199945840 10621706240 1319979264 13.877 100 16 32 1 1329288208 6291504 3017440
17:24:12 14194 8192000000 10203724736 10628587520 1324820544 13.920 100 9 19 0 1332447408 5569152 2057712
17:25:27 41927 8192000000 10221136112 10647736320 1328297152 13.952 100 20 40 0 1342618656 9972544 4348960
17:27:11 5660 8192000000 10226307888 10650685440 1335910240 14.021 100 34 69 0 1349748416 5805040 8033136
17:28:20 5918 8192000000 10210774288 10629062656 1312118544 13.805 99 23 46 0 1323413408 5875040 5419824
17:29:27 48147 8192000000 10184300544 10587668480 1228754240 13.043 94 28 56 1 1240331072 5658208 5918624
17:31:20 3702 8192000000 10154629296 10559848448 1248256704 13.222 98 29 54 0 1260821808 6914944 5650160
17:32:24 6810 8192000000 10130949648 10527002624 1219512240 12.957 98 23 47 0 1229714880 4807216 5395424
17:33:32 11868 8192000000 10156727312 10561667072 1266549168 13.390 100 13 26 1 1274767968 5200880 3017920
17:34:49 53606 8192000000 10167968944 10581520384 1277666528 13.492 99 30 60 0 1290149728 5610352 6872848
17:36:44 4692 8192000000 10195023600 10606518272 1302686496 13.720 100 18 37 2 1311189568 4827712 3675360
17:37:53 5027 8192000000 10203462624 10617679872 1310312528 13.789 100 17 34 0 1319628784 5500992 3815264
17:39:01 11736 8192000000 10257345744 10664267776 1343690640 14.091 100 36 72 1 1356912048 5511664 7709744
17:40:17 19852 8192000000 10274997344 10686951424 1356920816 14.210 100 45 87 0 1378415280 11769376 9725088
17:41:43 62909 8192000000 10283795776 10710986752 1368110448 14.310 100 37 74 3 1394111600 16858256 9142896
17:43:57 46046 8192000000 10296667376 10723221504 1374511792 14.367 99 34 68 0 1401327520 18641264 8174464
17:45:59 9865 8192000000 10324234608 10744176640 1399446752 14.590 100 56 107 0 1436791632 24850016 12494864
17:47:16 8046 8192000000 10332289520 10755956736 1405081184 14.640 100 48 96 3 1441117168 24478272 11557712
17:48:29 6184 8192000000 10349829696 10764185600 1409192640 14.677 100 68 136 0 1455545568 29659712 16693216
17:49:41 11854 8192000000 10343467024 10771472384 1414258368 14.722 100 42 83 2 1454516128 29898832 10358928
17:50:58 3209 8192000000 10340911040 10762870784 1403201328 14.623 100 58 116 0 1452088768 34157568 14729872
17:52:04 3682 8192000000 10345286832 10758406144 1381492064 14.430 99 79 157 0 1441446880 39721216 20233600
17:53:14 4539 8192000000 10324447232 10742276096 1377276592 14.392 100 66 131 0 1430148832 36549376 16322864
17:54:21 961 8192000000 10337713984 10744467456 1374927280 14.371 100 91 176 0 1440958928 43933056 22098592
17:55:27 18342 8192000000 10295415520 10725752832 1368293744 14.312 100 29 58 0 1405921760 29209216 8418800
17:56:50 10858 8192000000 10294050880 10719952896 1356896128 14.209 99 34 68 0 1395605152 29210640 9498384
17:58:53 6104 8192000000 10292374464 10714701824 1349160800 14.140 99 48 95 1 1392393744 31798368 11434576
18:00:05 27725 8192000000 10272602320 10708062208 1348158256 14.131 99 18 36 0 1376073728 23072672 4842800
18:01:36 26900 8192000000 10286199648 10716438528 1328713424 13.956 98 21 42 0 1366630096 32956400 4960272
18:03:04 18703 8192000000 10264947680 10688909312 1324819840 13.920 99 25 50 0 1350714416 19615296 6279280
18:04:24 14743 8192000000 10278558256 10707382272 1354366144 14.187 100 22 44 0 1379463552 19714672 5382736
18:05:43 43382 8192000000 10274525296 10710884352 1342380496 14.079 99 14 28 0 1364318304 18273504 3664304
18:07:53 80605 8192000000 10276440416 10712797184 1354197664 14.185 99 45 91 2 1382734672 16967056 11569952
18:10:22 16116 8192000000 10269888112 10705494016 1331910800 13.984 97 40 77 3 1344663168 5801680 6950688
18:11:41 51610 8192000000 10248739088 10703650816 1342724224 14.082 99 6 12 0 1357137856 13165296 1248336
18:13:33 7202 8192000000 10254274528 10709639168 1359717376 14.235 100 3 6 0 1370107952 10013168 377408
18:14:41 30847 8192000000 10281642608 10724732928 1372229616 14.347 100 26 52 0 1385906864 8081984 5595264
18:16:32 65644 8192000000 10288425568 10735341568 1381370112 14.429 100 18 36 1 1399509232 14131296 4007824
18:18:39 21227 8192000000 10292375184 10747297792 1386782928 14.477 100 17 34 0 1402736512 11398848 4554736
18:20:03 81243 8192000000 10310261328 10766299136 1381334816 14.428 99 28 56 0 1407260720 19458240 6467664
18:22:25 60251 8192000000 10306365296 10755538944 1399831376 14.593 100 12 24 0 1423356448 21017808 2507264
18:24:57 53947 8192000000 10318612592 10778927104 1408012688 14.666 100 18 36 0 1431910400 19377568 4520144
18:26:54 44060 8192000000 10315453296 10770657280 1413661984 14.716 100 12 24 0 1428329456 12140960 2526512
18:28:40 29775 8192000000 10332374896 10779873280 1403893488 14.630 99 36 73 1 1423656144 12318624 7444032
18:30:11 59912 8192000000 10318582672 10782355456 1395298944 14.553 99 16 32 0 1413041600 13863520 3879136
18:32:19 33282 8192000000 10315495392 10776326144 1384544992 14.457 98 14 28 0 1401995904 14133296 3317616
18:33:53 44623 8192000000 10317839872 10765336576 1397643376 14.574 99 19 38 0 1413059248 11076192 4339680
18:35:40 39621 8192000000 10298321776 10748321792 1352400064 14.169 97 28 56 0 1372102592 13596112 6106416
18:37:23 63180 8192000000 10288375632 10732380160 1366869424 14.299 98 25 50 2 1380846672 8326192 5651056
18:39:28 24363 8192000000 10296629552 10737569792 1379469600 14.412 99 44 89 1 1397345632 8831264 9044768
18:40:53 36236 8192000000 10286610512 10747621376 1396110944 14.560 100 13 26 0 1405587200 7179376 2296880
18:42:32 69375 8192000000 10294586880 10751262720 1379145536 14.409 99 18 36 0 1391930592 8581840 4203216
18:45:04 76331 8192000000 10277986176 10738302976 1353323056 14.177 97 20 40 0 1378416656 20528896 4564704
18:47:22 50250 8192000000 10263674672 10711842816 1324299408 13.916 96 16 30 0 1345019712 17222864 3497440
18:49:16 36741 8192000000 10277303872 10721271808 1356224448 14.203 99 41 82 1 1380149616 14401104 9524064
18:50:52 79920 8192000000 10269969792 10704678912 1319548112 13.873 97 18 36 0 1342383216 18505216 4329888
18:53:13 70170 8192000000 10243097776 10673717248 1235537328 13.105 92 19 38 0 1249436288 8986336 4912624
18:55:29 33142 8192000000 10237598720 10672652288 1306033024 13.750 98 29 58 2 1320428976 8530624 5865328
18:57:07 64244 8192000000 10252211024 10703114240 1325660432 13.928 99 36 72 1 1350028144 16586016 7781696
18:59:12 116365 8192000000 10253823600 10703687680 1332370592 13.989 99 9 18 0 1346646848 12421584 1854672
19:02:09 80028 8192000000 10272502496 10720223232 1352749728 14.172 100 30 56 0 1379119168 19898640 6470800
19:04:30 62111 8192000000 10248488832 10700652544 1353832256 14.182 99 12 24 0 1364117808 7619152 2666400
19:06:32 70247 8192000000 10262698000 10728443904 1368672304 14.315 100 10 20 0 1379840704 8896896 2271504
19:08:47 69432 8192000000 10270834048 10724765696 1354810464 14.191 98 17 34 1 1365446608 7069568 3566576
19:11:07 92339 8192000000 10283272576 10734866432 1331836160 13.984 96 12 24 0 1347820272 13875168 2108944
19:13:40 49505 8192000000 10292095424 10743054336 1362291824 14.258 98 15 31 0 1377182592 11537040 3353728
19:15:32 78377 8192000000 10308710608 10770243584 1395744496 14.557 100 16 33 1 1411824496 12699072 3380928
19:18:03 31932 8192000000 10313285600 10757685248 1375500112 14.376 98 27 52 0 1393807648 12375936 5931600
19:19:36 69812 8192000000 10298532784 10748461056 1357301872 14.213 97 9 18 0 1374704160 15528848 1873440
19:21:47 15872 8192000000 10293427968 10718138368 1339194352 14.050 97 54 108 0 1362112784 11414272 11504160
19:23:07 71261 8192000000 10291445456 10752802816 1351258928 14.159 98 21 43 1 1370682272 15003616 4419728
19:25:23 98662 8192000000 10288870752 10734989312 1373143904 14.355 99 25 48 1 1388510096 9963888 5402304
19:29:14 97791 8192000000 10285729952 10742800384 1368482768 14.313 98 7 14 0 1380494272 10252992 1758512
19:32:57 69449 8192000000 10275862528 10726621184 1327067584 13.941 96 17 34 1 1341034576 9718688 4248304
19:35:09 56990 8192000000 10265268448 10718793728 1347529808 14.125 98 19 36 0 1360750384 9042400 4178176
19:37:08 35556 8192000000 10251648432 10692521984 1313758752 13.820 97 21 42 0 1326968416 8377088 4832576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-03-2023 19:39:59 to 11-03-2023 19:40:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 107 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 11-03-2023 19:40:15 to 11-03-2023 19:40:17.

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          242565
MON$OLDEST_ACTIVE               243736
MON$OLDEST_SNAPSHOT             243736
MON$NEXT_TRANSACTION            243736
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                       2001024
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33666 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33666 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33666 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33666 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 ---
:31: 
:32: mscs-test-161.budzdorov.ru    Sat Mar 11 19:38:41 2023
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Sat Mar 11 19:38:57 2023
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:38: 
--- end of diff output ---

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

11.03.23 19:40:18. 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
2023-03-11 19:40:21 No severe PSQL-related problems occured
11.03.23 19:40:22. End of report.