Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.03.2023 16: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 15262264 kB
MemAvailable 30414372 kB
Buffers 0 kB
Cached 16425560 kB
SwapCached 3084 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-01 16:38:43
DTS_END                         2023-03-01 16:38:43.8830
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/44870
TRN_ID                          247018
ATT_ID                          712
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: 9290.24 23773 1114829
customer order: creation 179.89 356 21587
customer order: refuse 14.66 327 1759
order to supplier: creation 46.98 1272 5637
order to supplier: removal 3.42 112426 410
invoice (draft): creation 46.81 3246 5617
invoice (draft): removal 53.15 901 6378
invoice accept: apply 42.09 18216 5051
invoice accept: cancel 47.47 824 5696
customer reserve: creation 3903.23 180 468387
customer reserve: removal 4651.58 65 558189
realization accept: apply 87.28 177 10473
realization accept: cancel 79.24 109 9509
payment from customer: creation 61.66 115 7399
payment from customer: removal 5.84 91 701
payment to supplier: creation 50.44 138 6053
payment to supplier: removal 3.58 87 430
service: total inventory turnovers 5.18 1114 622
service: total monetary turnovers 6.82 31 818
service: aggregate perf. data 0.92 18771 110
service: refresh index statistics 0.03 317008 3

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-03-2023 16:39:51 to 01-03-2023 16:39:54.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3726
TEST_TIME 2 2084
TEST_TIME 3 2859
TEST_TIME 4 3314
TEST_TIME 5 4135
TEST_TIME 6 4349
TEST_TIME 7 4562
TEST_TIME 8 4582
TEST_TIME 9 4474
TEST_TIME 10 4620
TEST_TIME 11 4929
TEST_TIME 12 4999
TEST_TIME 13 5388
TEST_TIME 14 5793
TEST_TIME 15 6137
TEST_TIME 16 6087
TEST_TIME 17 6405
TEST_TIME 18 6581
TEST_TIME 19 6549
TEST_TIME 20 6987
TEST_TIME 21 7532
TEST_TIME 22 7993
TEST_TIME 23 8060
TEST_TIME 24 8282
TEST_TIME 25 8604
TEST_TIME 26 9183
TEST_TIME 27 9639
TEST_TIME 28 10421
TEST_TIME 29 10656
TEST_TIME 30 10501
TEST_TIME 31 10684
TEST_TIME 32 10955
TEST_TIME 33 10667
TEST_TIME 34 10440
TEST_TIME 35 10337
TEST_TIME 36 10269
TEST_TIME 37 10112
TEST_TIME 38 10119
TEST_TIME 39 10056
TEST_TIME 40 9914
TEST_TIME 41 9956
TEST_TIME 42 10023
TEST_TIME 43 9908
TEST_TIME 44 9826
TEST_TIME 45 9723
TEST_TIME 46 9569
TEST_TIME 47 9568
TEST_TIME 48 9541
TEST_TIME 49 9457
TEST_TIME 50 9414
TEST_TIME 51 9283
TEST_TIME 52 9307
TEST_TIME 53 9292
TEST_TIME 54 9299
TEST_TIME 55 9302
TEST_TIME 56 9326
TEST_TIME 57 9310
TEST_TIME 58 9340
TEST_TIME 59 9533
TEST_TIME 60 9523
TEST_TIME 61 9606
TEST_TIME 62 9555
TEST_TIME 63 9639
TEST_TIME 64 9689
TEST_TIME 65 9733
TEST_TIME 66 9675
TEST_TIME 67 9703
TEST_TIME 68 9605
TEST_TIME 69 9549
TEST_TIME 70 9498
TEST_TIME 71 9467
TEST_TIME 72 9447
TEST_TIME 73 9387
TEST_TIME 74 9362
TEST_TIME 75 9276
TEST_TIME 76 9348
TEST_TIME 77 9481
TEST_TIME 78 9453
TEST_TIME 79 9494
TEST_TIME 80 9464
TEST_TIME 81 9486
TEST_TIME 82 9499
TEST_TIME 83 9507
TEST_TIME 84 9562
TEST_TIME 85 9529
TEST_TIME 86 9550
TEST_TIME 87 9510
TEST_TIME 88 9544
TEST_TIME 89 9616
TEST_TIME 90 9511
TEST_TIME 91 9499
TEST_TIME 92 9452
TEST_TIME 93 9466
TEST_TIME 94 9399
TEST_TIME 95 9357
TEST_TIME 96 9339
TEST_TIME 97 9367
TEST_TIME 98 9348
TEST_TIME 99 9443
TEST_TIME 100 9458
TEST_TIME 101 9472
TEST_TIME 102 9450
TEST_TIME 103 9432
TEST_TIME 104 9432
TEST_TIME 105 9450
TEST_TIME 106 9446
TEST_TIME 107 9509
TEST_TIME 108 9483
TEST_TIME 109 9474
TEST_TIME 110 9416
TEST_TIME 111 9346
TEST_TIME 112 9339
TEST_TIME 113 9313
TEST_TIME 114 9268
TEST_TIME 115 9302
TEST_TIME 116 9321
TEST_TIME 117 9303
TEST_TIME 118 9310
TEST_TIME 119 9302
TEST_TIME 120 9292

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-03-2023 16:39:55 to 01-03-2023 16:39:59.

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 1548776 1548776 0 0.00 0 40333 6 0 0
doc_list_biud 1548757 1548757 0 0.00 0 55078 30 0 0
sp_add_invoice_to_stock 5245 5051 194 3.69 44 201662 18217 0 194
sp_cancel_adding_invoice 5696 5696 0 0.00 3 56987 825 0 0
sp_cancel_client_order 1759 1759 0 0.00 4 14509 327 0 0
sp_cancel_customer_reserve 558189 558189 0 0.00 0 55083 65 0 0
sp_cancel_pay_from_customer 701 701 0 0.00 3 9110 92 0 0
sp_cancel_pay_to_supplier 430 430 0 0.00 3 6439 88 0 0
sp_cancel_supplier_invoice 6394 6378 16 0.25 1 12504 902 0 16
sp_cancel_supplier_order 410 410 0 0.00 8 1347138 112426 0 0
sp_cancel_write_off 9509 9509 0 0.00 1 18860 109 0 0
sp_client_order 21587 21587 0 0.00 3 29079 356 0 0
sp_customer_reserve 468387 468387 0 0.00 0 82734 181 0 0
sp_fill_shopping_cart 32837 32837 0 0.00 1 30656 38 0 0
sp_fill_shopping_cart_clo_res 468386 468386 0 0.00 0 16842 13 0 0
sp_get_clo_for_invoice 5050 5050 0 0.00 0 26453 297 0 0
sp_kill_cost_storno 16336 16336 0 0.00 0 9103 55 0 0
sp_kill_qstorno_ret_qs2qd 1132675 1132675 0 0.00 0 54934 36 0 0
sp_kill_qty_storno 597455 597455 0 0.00 0 55077 76 0 0
sp_lock_selected_doc 21417 21417 0 0.00 0 9378 4 0 0
sp_make_cost_storno 28975 28975 0 0.00 0 40323 54 0 0
sp_multiply_rows_for_pdistr 28959 28959 0 0.00 0 13322 26 0 0
sp_multiply_rows_for_qdistr 21587 21587 0 0.00 0 17732 179 0 0
sp_pay_from_customer 7399 7399 0 0.00 4 15433 115 0 0
sp_pay_to_supplier 6053 6053 0 0.00 4 18840 138 0 0
sp_payment_common 13443 13443 0 0.00 1 14959 31 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 14501 298 0 0
sp_qd_handle_on_invoice_upd_sts 10742 10742 0 0.00 0 44217 346 0 0
sp_qd_handle_on_reserve_upd_sts 10468 10468 0 0.00 0 39618 59 0 0
sp_reserve_write_off 10473 10473 0 0.00 6 39642 178 0 0
sp_supplier_invoice 5617 5617 0 0.00 8 105594 3247 0 0
sp_supplier_order 5637 5637 0 0.00 4 41848 1272 0 0
srv_aggregate_perf_data 110 110 0 0.00 2584 303264 18771 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1629 131150 43906 0 0
srv_find_qd_qs_mism 1065821 1065821 0 0.00 0 30603 5 0 0
srv_make_invnt_saldo 622 622 0 0.00 31 101091 1114 0 0
srv_make_money_saldo 818 818 0 0.00 1 2463 32 0 0
srv_recalc_idx_stat 3 3 0 0.00 165439 429215 317009 0 0
t$perf-abend:sp_add_invoice_to_stock 194 194 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 16 16 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5051 5051 0 0.00 0 18522 151 0 0
t$perf-norm:sp_cancel_adding_invoice 425 425 0 0.00 0 8790 122 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 9353 16 0 0
t$perf-norm:sp_cancel_customer_reserve 732 732 0 0.00 0 4227 23 0 0
t$perf-norm:sp_cancel_pay_from_customer 701 701 0 0.00 0 805 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 430 430 0 0.00 0 1435 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 430 430 0 0.00 0 1130 17 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 29187 1800 0 0
t$perf-norm:sp_cancel_write_off 1016 1016 0 0.00 0 10094 22 0 0
t$perf-norm:sp_client_order 21587 21587 0 0.00 0 16359 23 0 0
t$perf-norm:sp_customer_reserve 1067 1067 0 0.00 0 9462 19 0 0
t$perf-norm:sp_pay_from_customer 7399 7399 0 0.00 0 7141 13 0 0
t$perf-norm:sp_pay_to_supplier 6053 6053 0 0.00 0 5610 11 0 0
t$perf-norm:sp_reserve_write_off 10473 10473 0 0.00 0 11740 15 0 0
t$perf-norm:sp_supplier_invoice 5617 5617 0 0.00 0 15715 20 0 0
t$perf-norm:sp_supplier_order 5637 5637 0 0.00 0 10001 18 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 2044 22 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 8 1 0 0
t$perf-norm:srv_make_invnt_saldo 622 622 0 0.00 0 3930 24 0 0
t$perf-norm:srv_make_money_saldo 818 818 0 0.00 0 1141 20 0 0
t$perf-norm:srv_recalc_idx_stat 3 3 0 0.00 1 11 5 0 0
v_add_invoice_to_stock 10478 10090 388 3.70 0 8669 22 0 388
v_all_customers 39004 39004 0 0.00 0 36 1 0 0
v_all_suppliers 31588 31588 0 0.00 0 233 1 0 0
v_all_wares 43174 43174 0 0.00 0 23 1 0 0
v_cancel_adding_invoice 850 850 0 0.00 0 7428 20 0 0
v_cancel_client_order 3518 3518 0 0.00 0 3958 6 0 0
v_cancel_customer_prepayment 1402 1402 0 0.00 0 2868 13 0 0
v_cancel_customer_reserve 1464 1464 0 0.00 0 6367 15 0 0
v_cancel_payment_to_supplier 860 860 0 0.00 0 6420 18 0 0
v_cancel_supplier_invoice 892 860 32 3.58 0 4353 22 0 32
v_cancel_supplier_order 820 820 0 0.00 0 3593 12 0 0
v_cancel_write_off 2032 2032 0 0.00 0 7802 24 0 0
v_max_id_clo_ord 5637 5637 0 0.00 0 922 1 0 0
v_max_id_clo_res 1067 1067 0 0.00 0 3019 19 0 0
v_max_id_ord_sup 5613 5613 0 0.00 0 4166 5 0 0
v_max_non_paid_invoice 1501 1501 0 0.00 0 3254 9 0 0
v_max_non_paid_realizn 3937 3937 0 0.00 0 7712 6 0 0
v_min_id_clo_ord 5637 5637 0 0.00 0 3634 9 0 0
v_min_id_clo_res 1067 1067 0 0.00 0 6867 20 0 0
v_min_id_ord_sup 5613 5613 0 0.00 0 27621 17 0 0
v_min_non_paid_invoice 6045 6045 0 0.00 0 9463 21 0 0
v_min_non_paid_realizn 7396 7396 0 0.00 0 14102 26 0 0
v_our_firm 11090 11090 0 0.00 0 200 1 0 0
v_reserve_write_off 20936 20936 0 0.00 0 4176 7 0 0
x_lock_depdocs_on_canc_invoice 11644 11644 0 0.00 0 10507 107 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 6735 351 0 0
x_make_qstorno_1000_1200 5637 5637 0 0.00 1 41812 1196 0 0
x_make_qstorno_1200_2000 5617 5617 0 0.00 1 105449 3105 0 0
x_make_qstorno_2100_3300 468387 468387 0 0.00 0 82733 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-03-2023 16:40:00 to 01-03-2023 16:40:04.

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
14:08:47 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
14:09:48 26175 8192000000 9888613440 10197200896 971195856 10.598 100 89 178 0 1000895120 5789152 23910112
14:11:18 20512 8192000000 9940928720 10263306240 1022283920 11.094 100 85 171 0 1051955472 7730304 21941248
14:12:43 22752 8192000000 9990878592 10324148224 1069340992 11.546 100 82 164 0 1099194432 8992144 20861296
14:14:08 9620 8192000000 10037158352 10382098432 1121306032 12.039 100 78 157 1 1150195776 10234496 18655248
14:15:20 5873 8192000000 10075588736 10432643072 1162002032 12.422 100 76 151 0 1191188128 12011328 17174768
14:16:29 3605 8192000000 10108480624 10474717184 1202896976 12.803 100 86 169 0 1234883184 13638720 18347488
14:17:35 3163 8192000000 10153493664 10525564928 1236530864 13.114 100 88 177 0 1269443120 14112368 18799888
14:18:41 1734 8192000000 10184583632 10561929216 1263430752 13.361 100 91 182 2 1297581328 14251600 19898976
14:19:44 840 8192000000 10206186816 10587648000 1301647472 13.710 100 92 183 1 1334700864 11850368 21203024
14:20:47 47919 8192000000 10196197104 10611802112 1312370912 13.808 100 38 76 0 1328865232 8230944 8263376
14:22:36 5033 8192000000 10205734880 10620391424 1320831792 13.884 100 31 62 0 1333456064 5517328 7106944
14:23:41 8498 8192000000 10221774528 10640302080 1331697552 13.982 100 20 39 1 1341532912 5629200 4206160
14:24:50 11286 8192000000 10227070784 10649231360 1339563696 14.053 100 15 30 2 1352280336 9474560 3242080
14:26:06 21919 8192000000 10246013232 10657058816 1344410384 14.097 100 61 120 1 1365279888 6226688 14642816
14:27:30 6415 8192000000 10236147664 10658205696 1335421232 14.016 100 26 52 1 1356390336 15670416 5298688
14:28:37 8311 8192000000 10189843408 10608594944 1275667632 13.473 97 29 58 2 1288050912 5797120 6586160
14:29:46 56274 8192000000 10186418368 10603024384 1272003376 13.440 97 19 38 0 1282596096 5685776 4906944
14:31:52 7287 8192000000 10128589056 10525704192 1192541712 12.707 95 36 72 0 1207871440 5436960 9892768
14:33:01 5992 8192000000 10127569024 10527555584 1239581856 13.142 100 23 45 0 1250154832 5328480 5244496
14:34:10 40766 8192000000 10155610432 10569510912 1273795680 13.456 100 30 60 0 1286278800 5594432 6888688
14:36:00 6528 8192000000 10195152496 10600316928 1288981568 13.595 100 27 54 0 1305351824 10517440 5852816
14:37:08 7970 8192000000 10199136640 10613911552 1306064880 13.750 100 18 36 0 1319388656 9365328 3958448
14:38:18 15094 8192000000 10217971376 10638041088 1328220496 13.951 100 10 20 0 1339022960 8923184 1879280
14:39:36 124645 8192000000 10257929376 10679537664 1354657568 14.189 100 33 64 0 1371105136 9804784 6642784
14:42:47 35101 8192000000 10282711984 10708533248 1357545472 14.215 99 38 76 0 1383591264 17364064 8681728
14:44:32 10888 8192000000 10292630864 10713288704 1366397552 14.295 99 42 84 2 1398823776 22749488 9676736
14:45:46 24531 8192000000 10325287472 10750283776 1403485072 14.626 100 42 81 0 1440878320 26348912 11044336
14:47:20 36745 8192000000 10335589360 10765824000 1397015760 14.568 99 40 79 1 1442805792 35757392 10032640
14:49:04 6949 8192000000 10357894944 10772193280 1412719856 14.708 100 75 142 0 1465976784 34135168 19121760
14:50:20 6667 8192000000 10369837888 10790760448 1425113840 14.818 100 59 115 0 1476821248 37239728 14467680
14:51:31 1874 8192000000 10374965888 10792091648 1420401856 14.776 100 70 138 0 1480491104 42875856 17213392
14:52:37 2906 8192000000 10341029104 10747068416 1388593872 14.493 100 88 174 0 1451694608 41422880 21677856
14:53:46 4678 8192000000 10354576048 10763096064 1393611088 14.538 100 76 147 0 1458904432 46430032 18863312
14:54:57 1629 8192000000 10366289552 10775707648 1402954032 14.621 100 82 163 0 1473470304 49581088 20935184
14:56:04 5194 8192000000 10368502064 10773630976 1399305408 14.589 100 82 161 3 1470417088 50177600 20934080
14:57:14 5621 8192000000 10360743872 10776756224 1397034848 14.569 100 68 132 1 1469076000 54682768 17358384
14:58:29 2150 8192000000 10383987520 10794717184 1407077616 14.658 100 76 150 1 1487947552 61455264 19414672
14:59:37 11858 8192000000 10345000336 10766708736 1379401616 14.411 98 45 88 0 1439822992 47874944 12546432
15:00:55 9130 8192000000 10332912944 10755256320 1377165328 14.391 99 39 77 2 1430163088 42366544 10631216
15:02:10 8363 8192000000 10341677984 10772852736 1389624016 14.503 100 42 84 0 1453942784 52252016 12066752
15:03:22 6568 8192000000 10335393296 10769113088 1377564784 14.395 100 35 70 0 1441773664 53714656 10494224
15:04:35 22353 8192000000 10326743056 10762092544 1362463472 14.259 99 26 52 0 1420786432 50477712 7845248
15:06:10 58889 8192000000 10282306400 10735075328 1334262560 14.006 99 18 36 0 1380339776 40868032 5209184
15:08:10 64010 8192000000 10275919568 10716823552 1344085136 14.094 99 17 35 0 1362129872 14156160 3888576
15:10:27 77057 8192000000 10286240320 10735792128 1357159312 14.212 99 14 28 0 1380135824 19975824 3000688
15:12:57 37831 8192000000 10303396016 10740850688 1374267248 14.365 100 45 87 0 1394821280 9926000 10628032
15:14:37 65100 8192000000 10287614576 10729472000 1342250480 14.078 97 27 55 0 1357966608 9350096 6366032
15:17:21 35660 8192000000 10308789616 10761674752 1391788432 14.522 100 18 36 0 1410691664 15027760 3875472
15:19:05 27219 8192000000 10306950832 10762919936 1396387680 14.563 100 35 69 0 1417465808 13329104 7749024
15:20:46 84555 8192000000 10268918096 10721931264 1332015136 13.985 96 15 29 0 1343287488 8416848 2855504
15:23:11 35637 8192000000 10277388400 10734440448 1370914320 14.335 99 12 24 0 1381308848 7626160 2768368
15:25:01 84592 8192000000 10284116608 10749374464 1388990784 14.497 100 17 34 0 1401703488 8882288 3830416
15:27:31 5733 8192000000 10297793392 10755919872 1392359808 14.527 100 27 56 0 1410762224 12263968 6138448
15:28:37 76275 8192000000 10289281584 10750754816 1377859776 14.397 98 21 42 0 1391577600 8395696 5322128
15:31:34 41772 8192000000 10259373632 10723852288 1328733296 13.956 96 9 18 0 1345580848 14848864 1998688
15:33:27 51051 8192000000 10270522752 10729070592 1357871104 14.218 99 28 56 0 1373849728 9503312 6475312
15:35:22 35148 8192000000 10277131440 10727641088 1348198976 14.131 98 15 30 0 1365132304 13240160 3693168
15:36:58 54002 8192000000 10269608848 10721001472 1298923696 13.685 95 17 31 1 1324130288 21214960 3991632
15:38:58 50834 8192000000 10260628608 10705195008 1295200208 13.652 95 21 42 0 1310530976 10948512 4382256
15:40:57 42932 8192000000 10271107872 10719174656 1325398112 13.926 97 39 78 1 1349507504 15651952 8457440
15:43:05 91782 8192000000 10270907136 10715213824 1294709952 13.647 94 18 36 0 1308472496 9897360 3865184
15:45:44 103159 8192000000 10249321168 10699427840 1323601760 13.909 97 23 47 0 1336322336 7251680 5468896
15:48:29 52881 8192000000 10257759824 10706759680 1338747952 14.046 99 38 76 0 1359883616 12804272 8331392
15:50:54 46106 8192000000 10276781248 10728816640 1353630336 14.180 99 20 40 0 1380542832 22055632 4856864
15:52:40 78334 8192000000 10276734576 10732150784 1350487856 14.152 98 18 36 0 1374916304 19589296 4839152
15:55:03 47894 8192000000 10279938048 10727972864 1364066688 14.274 100 25 51 0 1390342912 19857120 6419104
15:56:55 64128 8192000000 10283812464 10745663488 1382318032 14.437 100 13 26 0 1397243776 12155040 2770704
15:58:59 54414 8192000000 10295610128 10755964928 1394033392 14.542 100 26 51 0 1410668064 10414768 6219904
16:01:26 121670 8192000000 10272730960 10717347840 1324599504 13.918 95 9 18 0 1336823344 10514048 1709792
16:04:31 45386 8192000000 10284462176 10730876928 1354337664 14.186 98 19 38 0 1367898112 9457520 4102928
16:06:17 6897 8192000000 10307930976 10743701504 1381171520 14.427 100 50 100 0 1403438912 11533408 10733984
16:07:26 131150 8192000000 10303267824 10758217728 1395487488 14.555 100 20 38 1 1411022144 11108976 4425680
16:10:51 28406 8192000000 10298283936 10761203712 1399804768 14.593 100 10 20 0 1413118608 10476496 2837344
16:12:19 38524 8192000000 10276652064 10748620800 1367918320 14.308 98 8 15 0 1378007920 8776016 1313584
16:13:59 56121 8192000000 10280233520 10738847744 1307013024 13.759 93 23 45 1 1322020848 10533424 4474400
16:15:55 35131 8192000000 10299278320 10758590464 1366633472 14.297 98 21 42 0 1387355648 15961184 4760992
16:18:42 73190 8192000000 10270459856 10729365504 1321750864 13.893 96 18 34 0 1334967152 9665040 3551248
16:20:55 10466 8192000000 10290528656 10731982848 1369772368 14.325 100 40 78 1 1386727808 8444912 8510528
16:22:06 25859 8192000000 10282325200 10716221440 1340857968 14.065 99 43 86 0 1361904752 11779344 9267440
16:23:34 29530 8192000000 10261990944 10711052288 1325365072 13.925 98 39 79 0 1347545968 13875360 8305536
16:25:05 123947 8192000000 10274212128 10715918336 1279549856 13.509 94 11 23 0 1299254496 17387632 2317008
16:29:58 63637 8192000000 10280090896 10738749440 1343104048 14.085 98 17 34 0 1366763168 20128016 3531104
16:32:03 38002 8192000000 10269769728 10737442816 1356047120 14.202 99 11 22 0 1384473472 26037328 2389024
16:34:23 52334 8192000000 10286656240 10746863616 1364001600 14.273 99 16 29 0 1383331360 16079664 3250096
16:36:16 59438 8192000000 10279667488 10742677504 1367362768 14.303 99 15 31 0 1380673552 9768032 3542752
16:38:16 31481 8192000000 9102129536 9336979456 266790144 3.153 20 4 8 0 271349648 3752608 806896

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-03-2023 16:40:06 to 01-03-2023 16:40:21.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 194 v_add_invoice_to_stock 335544517
except 16 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-03-2023 16:40:22 to 01-03-2023 16:40:24.

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          247681
MON$OLDEST_ACTIVE               247682
MON$OLDEST_SNAPSHOT             247682
MON$NEXT_TRANSACTION            247682
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                       2033824
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.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:818: 
:819: mscs-test-161.budzdorov.ru    Wed Mar  1 16:38:52 2023
:820:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:821: 
:822: 
:823: mscs-test-161.budzdorov.ru    Wed Mar  1 16:38:52 2023
:824:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:825: 
--- end of diff output ---

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

01.03.23 16:40:25. 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-01 16:40:28 No severe PSQL-related problems occured
01.03.23 16:40:29. End of report.