Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.05.2022 01: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 16361604 kB
MemAvailable 31371800 kB
Buffers 0 kB
Cached 15328164 kB
SwapCached 2544 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-05-26 01:38:17
DTS_END                         2022-05-26 01:38:17.3160
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/33184
TRN_ID                          241370
ATT_ID                          711
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: 9331.75 20473 1119810
customer order: creation 173.13 388 20775
customer order: refuse 14.08 334 1689
order to supplier: creation 44.43 1357 5331
order to supplier: removal 3.49 111039 419
invoice (draft): creation 45.25 3386 5430
invoice (draft): removal 52.81 868 6337
invoice accept: apply 41.61 18411 4993
invoice accept: cancel 48.20 796 5784
customer reserve: creation 3857.41 180 462889
customer reserve: removal 4760.81 64 571297
realization accept: apply 82.25 185 9870
realization accept: cancel 80.74 118 9689
payment from customer: creation 58.75 123 7050
payment from customer: removal 5.98 78 717
payment to supplier: creation 47.43 129 5691
payment to supplier: removal 3.44 67 413
service: total inventory turnovers 4.54 1353 545
service: total monetary turnovers 6.64 41 797
service: aggregate perf. data 0.75 25439 90
service: refresh index statistics 0.03 245105 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-05-2022 01:39:18 to 26-05-2022 01:39:20.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3450
TEST_TIME 2 5695
TEST_TIME 3 5456
TEST_TIME 4 5655
TEST_TIME 5 6502
TEST_TIME 6 6715
TEST_TIME 7 6455
TEST_TIME 8 6627
TEST_TIME 9 6585
TEST_TIME 10 6854
TEST_TIME 11 7010
TEST_TIME 12 6862
TEST_TIME 13 6865
TEST_TIME 14 7236
TEST_TIME 15 7314
TEST_TIME 16 7544
TEST_TIME 17 7462
TEST_TIME 18 7925
TEST_TIME 19 8764
TEST_TIME 20 8951
TEST_TIME 21 9031
TEST_TIME 22 8967
TEST_TIME 23 9204
TEST_TIME 24 9272
TEST_TIME 25 9451
TEST_TIME 26 9461
TEST_TIME 27 10493
TEST_TIME 28 11364
TEST_TIME 29 11449
TEST_TIME 30 11077
TEST_TIME 31 10827
TEST_TIME 32 10931
TEST_TIME 33 10713
TEST_TIME 34 10592
TEST_TIME 35 10486
TEST_TIME 36 10329
TEST_TIME 37 10194
TEST_TIME 38 10110
TEST_TIME 39 10081
TEST_TIME 40 10010
TEST_TIME 41 9935
TEST_TIME 42 9811
TEST_TIME 43 9827
TEST_TIME 44 9689
TEST_TIME 45 9662
TEST_TIME 46 9587
TEST_TIME 47 9441
TEST_TIME 48 9465
TEST_TIME 49 9500
TEST_TIME 50 9404
TEST_TIME 51 9393
TEST_TIME 52 9332
TEST_TIME 53 9373
TEST_TIME 54 9558
TEST_TIME 55 9597
TEST_TIME 56 9538
TEST_TIME 57 9614
TEST_TIME 58 9630
TEST_TIME 59 9600
TEST_TIME 60 9668
TEST_TIME 61 9643
TEST_TIME 62 9705
TEST_TIME 63 9567
TEST_TIME 64 9515
TEST_TIME 65 9466
TEST_TIME 66 9378
TEST_TIME 67 9334
TEST_TIME 68 9289
TEST_TIME 69 9372
TEST_TIME 70 9313
TEST_TIME 71 9387
TEST_TIME 72 9337
TEST_TIME 73 9381
TEST_TIME 74 9383
TEST_TIME 75 9461
TEST_TIME 76 9468
TEST_TIME 77 9418
TEST_TIME 78 9505
TEST_TIME 79 9488
TEST_TIME 80 9497
TEST_TIME 81 9510
TEST_TIME 82 9414
TEST_TIME 83 9404
TEST_TIME 84 9336
TEST_TIME 85 9324
TEST_TIME 86 9252
TEST_TIME 87 9231
TEST_TIME 88 9310
TEST_TIME 89 9248
TEST_TIME 90 9300
TEST_TIME 91 9285
TEST_TIME 92 9282
TEST_TIME 93 9237
TEST_TIME 94 9315
TEST_TIME 95 9385
TEST_TIME 96 9410
TEST_TIME 97 9453
TEST_TIME 98 9407
TEST_TIME 99 9409
TEST_TIME 100 9506
TEST_TIME 101 9471
TEST_TIME 102 9496
TEST_TIME 103 9558
TEST_TIME 104 9493
TEST_TIME 105 9458
TEST_TIME 106 9400
TEST_TIME 107 9417
TEST_TIME 108 9407
TEST_TIME 109 9379
TEST_TIME 110 9364
TEST_TIME 111 9340
TEST_TIME 112 9299
TEST_TIME 113 9260
TEST_TIME 114 9246
TEST_TIME 115 9287
TEST_TIME 116 9268
TEST_TIME 117 9297
TEST_TIME 118 9374
TEST_TIME 119 9396
TEST_TIME 120 9333

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 26-05-2022 01:39:21 to 26-05-2022 01:39:25.

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 1548344 1548344 0 0.00 0 24553 6 0 0
doc_list_biud 1548342 1548342 0 0.00 0 56075 30 0 0
sp_add_invoice_to_stock 5102 4993 109 2.13 21 186083 18411 0 109
sp_cancel_adding_invoice 5784 5784 0 0.00 5 45590 796 0 0
sp_cancel_client_order 1689 1689 0 0.00 4 10720 335 0 0
sp_cancel_customer_reserve 571297 571297 0 0.00 1 56076 65 0 0
sp_cancel_pay_from_customer 717 717 0 0.00 3 9352 78 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 2 4582 68 0 0
sp_cancel_supplier_invoice 6347 6337 10 0.15 2 23878 869 0 10
sp_cancel_supplier_order 419 419 0 0.00 10 1139084 111039 0 0
sp_cancel_write_off 9689 9689 0 0.00 1 11996 118 0 0
sp_client_order 20775 20775 0 0.00 3 24745 389 0 0
sp_customer_reserve 462889 462889 0 0.00 0 54437 181 0 0
sp_fill_shopping_cart 31504 31504 0 0.00 1 43045 39 0 0
sp_fill_shopping_cart_clo_res 462881 462881 0 0.00 0 28185 14 0 0
sp_get_clo_for_invoice 4985 4985 0 0.00 0 29119 334 0 0
sp_kill_cost_storno 16603 16603 0 0.00 0 9345 61 0 0
sp_kill_qstorno_ret_qs2qd 1159039 1159039 0 0.00 0 56072 35 0 0
sp_kill_qty_storno 610069 610069 0 0.00 0 56075 75 0 0
sp_lock_selected_doc 20634 20634 0 0.00 0 7661 4 0 0
sp_make_cost_storno 27596 27596 0 0.00 0 24501 58 0 0
sp_multiply_rows_for_pdistr 27596 27596 0 0.00 0 17913 28 0 0
sp_multiply_rows_for_qdistr 20775 20775 0 0.00 0 21019 178 0 0
sp_pay_from_customer 7050 7050 0 0.00 4 17919 124 0 0
sp_pay_to_supplier 5691 5691 0 0.00 4 13571 130 0 0
sp_payment_common 12740 12740 0 0.00 2 16053 32 0 0
sp_qd_handle_on_cancel_clo 1689 1689 0 0.00 0 10714 299 0 0
sp_qd_handle_on_invoice_upd_sts 10768 10768 0 0.00 0 22375 336 0 0
sp_qd_handle_on_reserve_upd_sts 9870 9870 0 0.00 0 20204 61 0 0
sp_reserve_write_off 9870 9870 0 0.00 6 20407 185 0 0
sp_supplier_invoice 5430 5430 0 0.00 6 65795 3387 0 0
sp_supplier_order 5331 5331 0 0.00 4 43124 1358 0 0
srv_aggregate_perf_data 90 90 0 0.00 2216 810337 25440 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1823 115532 46583 0 0
srv_find_qd_qs_mism 1072290 1072290 0 0.00 0 16775 5 0 0
srv_make_invnt_saldo 545 545 0 0.00 10 180385 1354 0 0
srv_make_money_saldo 797 797 0 0.00 0 15588 42 0 0
srv_recalc_idx_stat 4 4 0 0.00 155754 345935 245105 0 0
t$perf-abend:sp_add_invoice_to_stock 109 109 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4993 4993 0 0.00 0 24441 179 0 0
t$perf-norm:sp_cancel_adding_invoice 434 434 0 0.00 1 23620 144 0 0
t$perf-norm:sp_cancel_client_order 1689 1689 0 0.00 0 14587 21 0 0
t$perf-norm:sp_cancel_customer_reserve 672 672 0 0.00 0 3037 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 717 717 0 0.00 0 571 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 431 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 394 394 0 0.00 0 562 9 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 27704 1626 0 0
t$perf-norm:sp_cancel_write_off 1043 1043 0 0.00 0 2510 11 0 0
t$perf-norm:sp_client_order 20775 20775 0 0.00 0 10497 17 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 21711 36 0 0
t$perf-norm:sp_pay_from_customer 7050 7050 0 0.00 0 3574 9 0 0
t$perf-norm:sp_pay_to_supplier 5691 5691 0 0.00 0 16991 15 0 0
t$perf-norm:sp_reserve_write_off 9870 9870 0 0.00 0 22734 13 0 0
t$perf-norm:sp_supplier_invoice 5430 5430 0 0.00 0 22646 21 0 0
t$perf-norm:sp_supplier_order 5331 5331 0 0.00 0 9961 17 0 0
t$perf-norm:srv_aggregate_perf_data 90 90 0 0.00 0 891 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 190 5 0 0
t$perf-norm:srv_make_invnt_saldo 545 545 0 0.00 0 2169 28 0 0
t$perf-norm:srv_make_money_saldo 797 797 0 0.00 0 2194 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 3 0 0
v_add_invoice_to_stock 10184 9966 218 2.14 0 6910 18 0 218
v_all_customers 37499 37499 0 0.00 0 13 1 0 0
v_all_suppliers 29570 29570 0 0.00 0 13 1 0 0
v_all_wares 41550 41550 0 0.00 0 126 1 0 0
v_cancel_adding_invoice 868 868 0 0.00 0 2501 8 0 0
v_cancel_client_order 3378 3378 0 0.00 0 6947 10 0 0
v_cancel_customer_prepayment 1434 1434 0 0.00 0 2895 5 0 0
v_cancel_customer_reserve 1344 1344 0 0.00 0 1688 4 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 4534 9 0 0
v_cancel_supplier_invoice 808 788 20 2.47 0 2700 18 0 20
v_cancel_supplier_order 838 838 0 0.00 0 1541 5 0 0
v_cancel_write_off 2086 2086 0 0.00 0 6582 26 0 0
v_max_id_clo_ord 5329 5329 0 0.00 0 785 1 0 0
v_max_id_clo_res 998 998 0 0.00 0 3864 21 0 0
v_max_id_ord_sup 5400 5400 0 0.00 0 9885 6 0 0
v_max_non_paid_invoice 1634 1634 0 0.00 0 4893 7 0 0
v_max_non_paid_realizn 3911 3911 0 0.00 0 5401 5 0 0
v_min_id_clo_ord 5329 5329 0 0.00 0 42968 14 0 0
v_min_id_clo_res 998 998 0 0.00 0 6045 11 0 0
v_min_id_ord_sup 5400 5400 0 0.00 0 9777 9 0 0
v_min_non_paid_invoice 5690 5690 0 0.00 0 6986 19 0 0
v_min_non_paid_realizn 7049 7049 0 0.00 0 12765 21 0 0
v_our_firm 10328 10328 0 0.00 0 15 1 0 0
v_reserve_write_off 19740 19740 0 0.00 0 6058 8 0 0
x_lock_depdocs_on_canc_invoice 11727 11727 0 0.00 0 12148 112 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 21082 414 0 0
x_make_qstorno_1000_1200 5331 5331 0 0.00 2 35898 1277 0 0
x_make_qstorno_1200_2000 5430 5430 0 0.00 2 65732 3239 0 0
x_make_qstorno_2100_3300 462889 462889 0 0.00 0 54414 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-05-2022 01:39:26 to 26-05-2022 01:39:30.

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
23:08:21 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:22 28430 8192000000 9905474640 10217283584 990946416 10.791 100 84 168 0 1018428128 5646080 21835632
23:10:54 22768 8192000000 9964119392 10292002816 1049696384 11.358 100 89 178 0 1080872624 8880720 22295520
23:12:20 5156 8192000000 9997836640 10332946432 1081300848 11.660 100 86 172 0 1112811280 10077424 21433008
23:13:33 15526 8192000000 10056463648 10406961152 1142203408 12.236 100 82 164 0 1172687632 11115728 19368496
23:14:52 12859 8192000000 10104405488 10463051776 1186317920 12.649 100 90 180 0 1219811120 12840512 20652688
23:16:07 5632 8192000000 10128975904 10499952640 1217960496 12.943 100 73 146 0 1247803872 13392496 16450880
23:17:15 7630 8192000000 10160818656 10540855296 1254328672 13.278 100 78 157 0 1284417680 12623936 17465072
23:18:25 1671 8192000000 10207418720 10590945280 1289661040 13.601 100 93 186 1 1324116848 13373792 21082016
23:19:27 1144 8192000000 10220652800 10605350912 1301511776 13.709 100 97 194 2 1336963024 13524144 21927104
23:20:29 7273 8192000000 10202396976 10608717824 1305815632 13.748 100 27 51 2 1321996896 11005136 5176128
23:21:37 9372 8192000000 10192891296 10615574528 1316087696 13.841 100 18 36 0 1327264096 7117440 4058960
23:22:47 5524 8192000000 10212790064 10628706304 1324205968 13.915 100 26 53 1 1336885104 6707472 5971664
23:23:54 36551 8192000000 10220138080 10640744448 1328342688 13.952 100 25 50 0 1340211632 6061296 5807648
23:25:32 6900 8192000000 10229310512 10648805376 1332469520 13.989 100 34 68 1 1347286640 7157472 7659648
23:26:39 7039 8192000000 10214855696 10631454720 1318720256 13.865 100 21 43 0 1329562368 5902096 4940016
23:27:48 63083 8192000000 10196264272 10614497280 1262909632 13.357 95 37 74 2 1277091952 5196864 8985456
23:29:54 5721 8192000000 10161814992 10570911744 1228458128 13.040 95 23 45 2 1239483120 6308272 4716720
23:31:00 6762 8192000000 10145432656 10539917312 1228583008 13.041 98 38 76 1 1243267056 5452688 9231360
23:32:09 12101 8192000000 10152114320 10557509632 1243982848 13.183 98 37 74 2 1258842368 5203488 9656032
23:33:21 29100 8192000000 10168149872 10579353600 1256665344 13.299 98 20 38 2 1266653552 5025760 4962448
23:34:52 5062 8192000000 10185229296 10599170048 1299374480 13.690 100 23 46 0 1310180912 5294560 5511872
23:36:01 11124 8192000000 10193572400 10611793920 1306836784 13.757 100 31 60 3 1321380800 7047104 7496912
23:37:13 5564 8192000000 10217365120 10628034560 1319682128 13.874 100 21 41 0 1328924000 5299840 3942032
23:38:19 61565 8192000000 10240292064 10652106752 1324313984 13.916 99 41 82 1 1340698336 7449984 8934368
23:40:24 12727 8192000000 10273108096 10689413120 1359620448 14.234 100 46 90 1 1383000176 13827904 9551824
23:41:44 20641 8192000000 10309888032 10724765696 1379342800 14.411 100 70 136 0 1413176720 18482912 15351008
23:43:09 11042 8192000000 10310202752 10732695552 1392900944 14.532 100 47 94 0 1424089744 19707616 11481184
23:44:24 15814 8192000000 10314379584 10734575616 1392439696 14.528 100 51 102 2 1427422656 23218128 11764832
23:45:48 12838 8192000000 10322255744 10740015104 1394141104 14.543 100 65 128 1 1432765584 24171968 14452512
23:47:05 11465 8192000000 10341756368 10762579968 1410730048 14.690 100 55 110 1 1451620288 27318064 13572176
23:48:21 8161 8192000000 10335487664 10758897664 1393902608 14.541 99 63 127 0 1438517792 29662928 14952256
23:49:37 6842 8192000000 10336307504 10752446464 1385016432 14.461 99 67 135 1 1436646432 35323840 16306160
23:50:47 5788 8192000000 10350574560 10762612736 1403817040 14.629 100 71 142 0 1455390528 34476288 17097200
23:51:57 2294 8192000000 10313626176 10720727040 1356153808 14.203 98 74 148 0 1412844688 36564016 20126864
23:53:06 4678 8192000000 10348000480 10761539584 1396193232 14.561 100 64 127 0 1452928864 40756288 15979344
23:54:17 3259 8192000000 10363004464 10768957440 1390125504 14.507 100 89 179 2 1464583120 51272096 23185520
23:55:25 1821 8192000000 10365082496 10775744512 1392647312 14.529 100 73 145 2 1468157040 55040928 20468800
23:56:35 2461 8192000000 10356451776 10762887168 1377656560 14.396 100 80 160 2 1459328096 59848576 21822960
23:57:43 14663 8192000000 10344460144 10776514560 1397659952 14.574 100 49 98 1 1453377168 42339888 13377328
23:59:44 8972 8192000000 10331584880 10753204224 1366922016 14.299 98 36 72 0 1415828144 39114048 9792080
00:01:04 22349 8192000000 10341817072 10778345472 1374411424 14.367 98 35 70 0 1432845264 48745136 9688704
00:03:15 20712 8192000000 10333211200 10763247616 1370086016 14.328 100 35 70 2 1433402144 53134928 10181200
00:04:47 26690 8192000000 10325350464 10760900608 1347336480 14.124 99 31 62 0 1424616224 67804704 9475040
00:06:15 24563 8192000000 10267217696 10707902464 1304484992 13.736 97 19 38 3 1349854672 39938704 5430976
00:07:40 81933 8192000000 10287193328 10719633408 1359486560 14.233 100 38 78 4 1377205616 9112400 8606656
00:10:05 6152 8192000000 10289454416 10728493056 1370202672 14.329 100 43 86 0 1395032000 15406816 9422512
00:11:19 72118 8192000000 10271617472 10721923072 1373246512 14.356 100 22 44 3 1385218896 7114560 4857824
00:13:32 78909 8192000000 10283183280 10734014464 1356962400 14.210 98 15 30 0 1370704384 10554368 3187616
00:16:30 46708 8192000000 10299718176 10750164992 1378273680 14.401 99 15 29 0 1394961712 13194368 3493664
00:18:31 75108 8192000000 10303729904 10762960896 1393650784 14.538 100 18 36 0 1412749344 14498720 4599840
00:21:02 71602 8192000000 10287124832 10758246400 1392035888 14.524 100 18 36 0 1407935536 11876576 4023072
00:23:14 30183 8192000000 10288677088 10758479872 1394958000 14.550 100 27 52 0 1411090336 10365920 5766416
00:24:45 104373 8192000000 10301388368 10768248832 1408111888 14.667 100 9 18 0 1420361088 10031472 2217728
00:27:39 53504 8192000000 10307133936 10775855104 1401155024 14.605 100 9 18 0 1415667936 12267072 2245840
00:29:34 28012 8192000000 10308394448 10771480576 1407732176 14.664 100 12 25 0 1420715200 10381312 2601712
00:31:02 62829 8192000000 10316012336 10783408128 1390484784 14.510 98 14 28 1 1410398560 16460528 3453248
00:33:14 69723 8192000000 10309853152 10767740928 1350780576 14.154 96 16 31 2 1366422320 11844752 3796992
00:35:36 81429 8192000000 10297598912 10753236992 1359748992 14.235 97 20 40 0 1379622880 15249072 4624816
00:38:10 43469 8192000000 10290263200 10739650560 1353141744 14.176 98 28 56 0 1371127040 12059984 5925312
00:39:57 65902 8192000000 10276446720 10738233344 1328745840 13.956 96 27 54 0 1348666240 13585104 6335296
00:42:04 60356 8192000000 10290186000 10740236288 1381031344 14.426 100 28 57 0 1395428000 8289824 6106832
00:44:05 60850 8192000000 10282925120 10745462784 1377681904 14.396 99 8 17 0 1389667872 10219600 1766368
00:46:06 87956 8192000000 10265281920 10724421632 1289941744 13.604 93 7 14 1 1304841152 13382240 1517168
00:48:35 65396 8192000000 10256138784 10708287488 1296336384 13.662 95 25 50 3 1313879968 12145584 5398000
00:50:41 78332 8192000000 10236632528 10692853760 1272165216 13.441 94 20 40 1 1288071280 11108400 4797664
00:52:59 59264 8192000000 10251074640 10704379904 1316791360 13.848 98 9 18 0 1337449008 18367312 2290336
00:55:03 36909 8192000000 10265783552 10698182656 1323386512 13.907 98 34 68 1 1339988256 8928992 7672752
00:56:44 51374 8192000000 10256741600 10695831552 1309680448 13.783 97 15 30 0 1322946768 9712928 3553392
00:58:38 28546 8192000000 10281036768 10722648064 1332932144 13.994 98 35 70 0 1355917088 15540208 7444736
01:00:13 96590 8192000000 10265209152 10712285184 1337217728 14.032 98 26 53 1 1352979744 10343696 5418320
01:02:51 29826 8192000000 10276599472 10706989056 1325430192 13.926 97 25 50 1 1342163712 11225408 5508112
01:04:23 79153 8192000000 10285523072 10739605504 1366441888 14.295 99 17 34 0 1384310032 13941072 3927072
01:06:42 56347 8192000000 10261681664 10724810752 1360084416 14.238 99 9 18 0 1373508048 11172064 2251568
01:08:55 59043 8192000000 10297903600 10754039808 1388579248 14.493 100 16 32 1 1399838016 7768784 3489984
01:10:54 74093 8192000000 10301972624 10764398592 1373599120 14.359 98 18 35 0 1396018432 18224624 4194688
01:13:27 84729 8192000000 10289396496 10746306560 1353549568 14.179 97 14 28 1 1366081632 9343920 3188144
01:15:52 47342 8192000000 10321663344 10760368128 1387491280 14.483 99 27 54 1 1401543872 8107584 5945008
01:17:43 49706 8192000000 10330126480 10781831168 1405263264 14.642 100 38 76 2 1429190560 15663712 8263584
01:19:39 97450 8192000000 10307504592 10768744448 1352981952 14.174 96 22 44 0 1369483360 11513440 4987968
01:22:17 115532 8192000000 10284726768 10738974720 1338703552 14.046 96 25 50 1 1350718208 6694848 5319808
01:25:14 60954 8192000000 10282968608 10742800384 1380835760 14.424 99 18 36 1 1393585696 8405392 4344544
01:27:15 57185 8192000000 10265742800 10721591296 1322084224 13.896 95 11 22 0 1331320208 6824144 2411840
01:29:30 55624 8192000000 10264573536 10725560320 1353357312 14.178 98 17 34 0 1363686048 6617776 3710960
01:33:12 48293 8192000000 10298344656 10763927552 1393249216 14.535 100 18 36 0 1415648736 17378480 5021040
01:35:04 61187 8192000000 10287842384 10747867136 1340844064 14.065 97 21 42 0 1366504896 20358000 5302832
01:37:07 61295 8192000000 10240959936 10685771776 1258026032 13.312 92 17 34 0 1270832128 9117344 3688752

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-05-2022 01:39:32 to 26-05-2022 01:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 109 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 26-05-2022 01:39:48 to 26-05-2022 01:39:49.

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          238829
MON$OLDEST_ACTIVE               241480
MON$OLDEST_SNAPSHOT             241480
MON$NEXT_TRANSACTION            241480
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                       2040864
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.33596 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33596 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33596 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33596 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 ---
:49: 
:50: mscs-test-161.budzdorov.ru    Thu May 26 01:38:21 2022
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Thu May 26 01:38:22 2022
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:56: 
--- end of diff output ---

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

26.05.22 01:39:50. 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-05-26 01:39:53 No severe PSQL-related problems occured
26.05.22 01:39:54. End of report.