Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.02.2023 22:39
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15225204 kB
MemAvailable 30399168 kB
Buffers 0 kB
Cached 16466008 kB
SwapCached 3404 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-02-13 22:38:39
DTS_END                         2023-02-13 22:38:39.0920
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/56444
TRN_ID                          245517
ATT_ID                          665
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: 9422.90 20677 1130748
customer order: creation 176.37 352 21164
customer order: refuse 15.50 333 1860
order to supplier: creation 47.62 1328 5714
order to supplier: removal 3.39 109530 407
invoice (draft): creation 46.22 3272 5546
invoice (draft): removal 53.20 821 6384
invoice accept: apply 41.88 17828 5025
invoice accept: cancel 49.24 833 5909
customer reserve: creation 3872.03 176 464643
customer reserve: removal 4818.96 61 578275
realization accept: apply 84.79 209 10175
realization accept: cancel 80.45 111 9654
payment from customer: creation 61.18 118 7342
payment from customer: removal 5.89 100 707
payment to supplier: creation 50.38 138 6045
payment to supplier: removal 3.61 100 433
service: total inventory turnovers 4.86 1226 583
service: total monetary turnovers 6.52 25 782
service: aggregate perf. data 0.80 21936 96
service: refresh index statistics 0.03 255052 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 13-02-2023 22:39:45 to 13-02-2023 22:39:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3652
TEST_TIME 2 2482
TEST_TIME 3 3394
TEST_TIME 4 3291
TEST_TIME 5 3846
TEST_TIME 6 4165
TEST_TIME 7 4103
TEST_TIME 8 4433
TEST_TIME 9 5038
TEST_TIME 10 5381
TEST_TIME 11 5666
TEST_TIME 12 6343
TEST_TIME 13 6442
TEST_TIME 14 6549
TEST_TIME 15 6895
TEST_TIME 16 7128
TEST_TIME 17 7165
TEST_TIME 18 7564
TEST_TIME 19 7576
TEST_TIME 20 7472
TEST_TIME 21 7677
TEST_TIME 22 8031
TEST_TIME 23 8685
TEST_TIME 24 8761
TEST_TIME 25 8722
TEST_TIME 26 8780
TEST_TIME 27 9476
TEST_TIME 28 10513
TEST_TIME 29 11050
TEST_TIME 30 11359
TEST_TIME 31 11066
TEST_TIME 32 10961
TEST_TIME 33 10658
TEST_TIME 34 10753
TEST_TIME 35 10514
TEST_TIME 36 10502
TEST_TIME 37 10391
TEST_TIME 38 10264
TEST_TIME 39 10310
TEST_TIME 40 10239
TEST_TIME 41 10154
TEST_TIME 42 10015
TEST_TIME 43 10158
TEST_TIME 44 10028
TEST_TIME 45 9924
TEST_TIME 46 9873
TEST_TIME 47 9736
TEST_TIME 48 9659
TEST_TIME 49 9643
TEST_TIME 50 9623
TEST_TIME 51 9610
TEST_TIME 52 9498
TEST_TIME 53 9403
TEST_TIME 54 9569
TEST_TIME 55 9469
TEST_TIME 56 9472
TEST_TIME 57 9404
TEST_TIME 58 9328
TEST_TIME 59 9427
TEST_TIME 60 9410
TEST_TIME 61 9344
TEST_TIME 62 9396
TEST_TIME 63 9434
TEST_TIME 64 9454
TEST_TIME 65 9482
TEST_TIME 66 9575
TEST_TIME 67 9571
TEST_TIME 68 9582
TEST_TIME 69 9634
TEST_TIME 70 9711
TEST_TIME 71 9657
TEST_TIME 72 9662
TEST_TIME 73 9564
TEST_TIME 74 9542
TEST_TIME 75 9501
TEST_TIME 76 9452
TEST_TIME 77 9425
TEST_TIME 78 9413
TEST_TIME 79 9420
TEST_TIME 80 9412
TEST_TIME 81 9346
TEST_TIME 82 9316
TEST_TIME 83 9315
TEST_TIME 84 9359
TEST_TIME 85 9327
TEST_TIME 86 9376
TEST_TIME 87 9353
TEST_TIME 88 9382
TEST_TIME 89 9498
TEST_TIME 90 9452
TEST_TIME 91 9487
TEST_TIME 92 9598
TEST_TIME 93 9620
TEST_TIME 94 9606
TEST_TIME 95 9593
TEST_TIME 96 9588
TEST_TIME 97 9566
TEST_TIME 98 9496
TEST_TIME 99 9461
TEST_TIME 100 9449
TEST_TIME 101 9461
TEST_TIME 102 9430
TEST_TIME 103 9386
TEST_TIME 104 9396
TEST_TIME 105 9373
TEST_TIME 106 9346
TEST_TIME 107 9360
TEST_TIME 108 9345
TEST_TIME 109 9364
TEST_TIME 110 9386
TEST_TIME 111 9458
TEST_TIME 112 9472
TEST_TIME 113 9495
TEST_TIME 114 9490
TEST_TIME 115 9456
TEST_TIME 116 9483
TEST_TIME 117 9475
TEST_TIME 118 9531
TEST_TIME 119 9481
TEST_TIME 120 9427

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 13-02-2023 22:39:49 to 13-02-2023 22:39:53.

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 1561257 1561257 0 0.00 0 37713 6 0 0
doc_list_biud 1561256 1561256 0 0.00 0 43387 29 0 0
sp_add_invoice_to_stock 5169 5025 144 2.78 40 156006 17829 0 144
sp_cancel_adding_invoice 5909 5909 0 0.00 4 67536 833 0 0
sp_cancel_client_order 1860 1860 0 0.00 4 8117 333 0 0
sp_cancel_customer_reserve 578275 578275 0 0.00 0 43390 62 0 0
sp_cancel_pay_from_customer 707 707 0 0.00 3 8962 101 0 0
sp_cancel_pay_to_supplier 433 433 0 0.00 2 10072 101 0 0
sp_cancel_supplier_invoice 6393 6384 9 0.14 1 19188 822 0 9
sp_cancel_supplier_order 407 407 0 0.00 4 1301149 109531 0 0
sp_cancel_write_off 9654 9654 0 0.00 1 11373 111 0 0
sp_client_order 21164 21164 0 0.00 3 30807 353 0 0
sp_customer_reserve 464643 464643 0 0.00 0 58387 176 0 0
sp_fill_shopping_cart 32422 32422 0 0.00 1 11940 37 0 0
sp_fill_shopping_cart_clo_res 464635 464635 0 0.00 0 12350 14 0 0
sp_get_clo_for_invoice 5017 5017 0 0.00 0 17842 274 0 0
sp_kill_cost_storno 16703 16703 0 0.00 0 10786 53 0 0
sp_kill_qstorno_ret_qs2qd 1172995 1172995 0 0.00 0 43372 33 0 0
sp_kill_qty_storno 617681 617681 0 0.00 0 43387 71 0 0
sp_lock_selected_doc 21150 21150 0 0.00 0 7992 5 0 0
sp_make_cost_storno 28579 28579 0 0.00 0 37607 60 0 0
sp_multiply_rows_for_pdistr 28579 28579 0 0.00 0 10286 27 0 0
sp_multiply_rows_for_qdistr 21164 21164 0 0.00 0 23602 144 0 0
sp_pay_from_customer 7342 7342 0 0.00 4 22820 119 0 0
sp_pay_to_supplier 6045 6045 0 0.00 5 24278 138 0 0
sp_payment_common 13387 13387 0 0.00 1 14535 36 0 0
sp_qd_handle_on_cancel_clo 1860 1860 0 0.00 0 8111 293 0 0
sp_qd_handle_on_invoice_upd_sts 10926 10926 0 0.00 0 27978 330 0 0
sp_qd_handle_on_reserve_upd_sts 10175 10175 0 0.00 0 12383 68 0 0
sp_reserve_write_off 10175 10175 0 0.00 7 26725 210 0 0
sp_supplier_invoice 5546 5546 0 0.00 7 69244 3273 0 0
sp_supplier_order 5714 5714 0 0.00 4 37104 1328 0 0
srv_aggregate_perf_data 96 96 0 0.00 3353 260647 21936 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 2413 140093 41213 0 0
srv_find_qd_qs_mism 1082210 1082210 0 0.00 0 23132 6 0 0
srv_make_invnt_saldo 583 583 0 0.00 17 119641 1227 0 0
srv_make_money_saldo 782 782 0 0.00 1 680 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 167256 411728 255052 0 0
t$perf-abend:sp_add_invoice_to_stock 144 144 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 5025 5025 0 0.00 0 16831 157 0 0
t$perf-norm:sp_cancel_adding_invoice 466 466 0 0.00 0 4564 108 0 0
t$perf-norm:sp_cancel_client_order 1860 1860 0 0.00 0 3953 17 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 1862 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 707 707 0 0.00 0 3600 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 433 433 0 0.00 0 8352 36 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 3172 33 0 0
t$perf-norm:sp_cancel_supplier_order 407 407 0 0.00 0 35094 1651 0 0
t$perf-norm:sp_cancel_write_off 1029 1029 0 0.00 0 13184 19 0 0
t$perf-norm:sp_client_order 21164 21164 0 0.00 0 12966 19 0 0
t$perf-norm:sp_customer_reserve 1000 1000 0 0.00 0 3017 6 0 0
t$perf-norm:sp_pay_from_customer 7342 7342 0 0.00 0 6470 13 0 0
t$perf-norm:sp_pay_to_supplier 6045 6045 0 0.00 0 4421 11 0 0
t$perf-norm:sp_reserve_write_off 10175 10175 0 0.00 0 15931 11 0 0
t$perf-norm:sp_supplier_invoice 5546 5546 0 0.00 0 6712 20 0 0
t$perf-norm:sp_supplier_order 5714 5714 0 0.00 0 5291 19 0 0
t$perf-norm:srv_aggregate_perf_data 96 96 0 0.00 0 726 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 216 4 0 0
t$perf-norm:srv_make_invnt_saldo 583 583 0 0.00 0 1454 26 0 0
t$perf-norm:srv_make_money_saldo 782 782 0 0.00 0 1326 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10322 10034 288 2.79 0 8982 23 0 288
v_all_customers 38724 38724 0 0.00 0 99 1 0 0
v_all_suppliers 31224 31224 0 0.00 0 55 1 0 0
v_all_wares 42328 42328 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 932 932 0 0.00 0 2727 13 0 0
v_cancel_client_order 3720 3720 0 0.00 0 6119 12 0 0
v_cancel_customer_prepayment 1414 1414 0 0.00 0 8948 20 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 3197 13 0 0
v_cancel_payment_to_supplier 866 866 0 0.00 0 698 6 0 0
v_cancel_supplier_invoice 796 778 18 2.26 0 4037 20 0 18
v_cancel_supplier_order 814 814 0 0.00 0 949 6 0 0
v_cancel_write_off 2058 2058 0 0.00 0 4692 17 0 0
v_max_id_clo_ord 5714 5714 0 0.00 0 682 1 0 0
v_max_id_clo_res 1000 1000 0 0.00 0 1134 9 0 0
v_max_id_ord_sup 5544 5544 0 0.00 0 5276 4 0 0
v_max_non_paid_invoice 1691 1691 0 0.00 0 2033 4 0 0
v_max_non_paid_realizn 3768 3768 0 0.00 0 7227 10 0 0
v_min_id_clo_ord 5714 5714 0 0.00 0 4487 8 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 8751 18 0 0
v_min_id_ord_sup 5544 5544 0 0.00 0 3997 6 0 0
v_min_non_paid_invoice 6045 6045 0 0.00 0 14533 28 0 0
v_min_non_paid_realizn 7342 7342 0 0.00 0 9219 24 0 0
v_our_firm 10752 10752 0 0.00 0 42 1 0 0
v_reserve_write_off 20350 20350 0 0.00 0 9660 10 0 0
x_lock_depdocs_on_canc_invoice 11904 11904 0 0.00 0 27508 128 0 0
x_lock_depdocs_on_canc_sup_ord 407 407 0 0.00 0 31796 461 0 0
x_make_qstorno_1000_1200 5714 5714 0 0.00 1 37057 1250 0 0
x_make_qstorno_1200_2000 5546 5546 0 0.00 3 69233 3139 0 0
x_make_qstorno_2100_3300 464643 464643 0 0.00 0 58386 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 13-02-2023 22:39:54 to 13-02-2023 22:39:58.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:43 2 8192000000 8800002112 8811544576 6398640 0.078 2 1 2 0 6498272 56848 42784
20:09:43 15828 8192000000 9889175584 10195034112 971880512 10.605 100 85 169 0 1000147584 5243184 23023888
20:11:04 17865 8192000000 9939589888 10258292736 1023589872 11.107 100 90 180 0 1055315008 7406736 24318400
20:12:27 14533 8192000000 9990882928 10321006592 1063964656 11.494 100 86 171 0 1094314224 9038128 21311440
20:13:50 8962 8192000000 10023715984 10372874240 1116032928 11.989 100 67 135 0 1141964416 10289776 15641712
20:15:04 4983 8192000000 10077653376 10434813952 1166522528 12.464 100 87 170 1 1198690272 12354768 19812976
20:16:13 5040 8192000000 10124004832 10492010496 1206987584 12.841 100 79 158 0 1237445232 12420992 18036656
20:17:20 2908 8192000000 10149799984 10523906048 1239374576 13.140 100 80 160 0 1270202496 13310368 17517552
20:18:24 1995 8192000000 10183818096 10562588672 1265185120 13.378 100 77 154 0 1295496928 13449920 16861888
20:19:28 1776 8192000000 10199722400 10585206784 1294539360 13.646 100 88 174 1 1327351840 13481872 19330608
20:20:32 1348 8192000000 10200155520 10598748160 1301708896 13.711 100 55 107 0 1321512688 7463600 12340192
20:21:34 5167 8192000000 10191003152 10605490176 1309098208 13.778 100 20 40 1 1319233952 5857216 4278528
20:22:41 6773 8192000000 10205254736 10619387904 1319989552 13.877 100 25 48 0 1331936256 6771200 5175504
20:23:51 11623 8192000000 10202745376 10624835584 1323413264 13.908 100 8 14 0 1330220736 5330240 1477232
20:25:08 71148 8192000000 10227779232 10647568384 1330871344 13.975 100 26 52 1 1342868160 5122608 6874208
20:27:23 5164 8192000000 10235021520 10651627520 1337340256 14.033 100 25 48 0 1349451440 5637136 6474048
20:28:29 9851 8192000000 10204138992 10624483328 1289477216 13.599 97 14 28 0 1297705968 5265696 2963056
20:29:39 58255 8192000000 10170411056 10582822912 1238238048 13.130 95 25 50 0 1249870032 6193696 5438288
20:31:39 5355 8192000000 10129654736 10531725312 1205947360 12.832 96 28 56 0 1219096944 6972832 6176752
20:32:47 7406 8192000000 10141104832 10533855232 1221561088 12.976 97 19 38 0 1230659216 4891552 4206576
20:33:58 75035 8192000000 10176024448 10585649152 1283120384 13.541 100 26 52 1 1294738624 5729088 5889152
20:36:16 6013 8192000000 10190355968 10602328064 1296953216 13.668 100 20 40 0 1307234864 5270176 5011472
20:37:22 7651 8192000000 10205456752 10615734272 1307557664 13.764 100 18 35 0 1316402672 5088096 3756912
20:38:30 11199 8192000000 10215015840 10631884800 1313588144 13.819 99 12 25 0 1320673488 4708080 2377264
20:39:50 46621 8192000000 10261665216 10672988160 1346691408 14.118 100 43 85 0 1367349520 11033360 9624752
20:41:52 17022 8192000000 10282795328 10696507392 1366758864 14.298 100 46 92 0 1393730288 16379936 10591488
20:43:14 11019 8192000000 10290366304 10710126592 1376423008 14.385 100 36 72 0 1404100096 19569648 8107440
20:44:30 13410 8192000000 10303589552 10723102720 1383193392 14.445 100 46 88 1 1416089632 22697008 10199232
20:45:46 10015 8192000000 10324603280 10739392512 1393971392 14.541 100 72 142 1 1438250448 27006848 17272208
20:47:01 11254 8192000000 10319054064 10743812096 1379886192 14.416 99 43 86 0 1422108208 30846544 11375472
20:48:18 3385 8192000000 10338569152 10759974912 1402932496 14.621 100 58 114 0 1448900576 32011792 13956288
20:49:25 7099 8192000000 10338147200 10756026368 1400120992 14.596 100 52 103 0 1444669568 30662544 13886032
20:50:38 3144 8192000000 10315287696 10724679680 1369455744 14.322 98 69 136 0 1415986256 30350080 16180432
20:51:45 3544 8192000000 10354635360 10773303296 1408555184 14.671 100 61 120 0 1460690288 37057680 15077424
20:52:52 5193 8192000000 10338233840 10754736128 1371510080 14.341 98 68 136 1 1427871520 39098864 17262576
20:54:04 4543 8192000000 10335236528 10745856000 1381400112 14.429 100 74 147 1 1439210208 39470160 18339936
20:55:15 5598 8192000000 10344203808 10756513792 1392476144 14.528 100 64 128 0 1447956512 39054528 16425840
20:56:24 2580 8192000000 10362176432 10772303872 1398440144 14.581 100 75 148 0 1466765152 48612688 19712320
20:57:30 10894 8192000000 10350122432 10766274560 1391311504 14.518 99 42 84 1 1443044208 40810352 10922352
20:58:44 2975 8192000000 10367615392 10773557248 1398089520 14.578 100 75 148 0 1466087584 48905328 19092736
20:59:54 8075 8192000000 10379308928 10798292992 1411749392 14.699 100 66 132 0 1486647872 57626752 17271728
21:01:07 2411 8192000000 10390228320 10812493824 1419251376 14.766 100 56 111 0 1490831296 56334784 15245136
21:02:15 15519 8192000000 10360569712 10801942528 1407413040 14.661 100 31 61 0 1469426544 53700032 8313472
21:03:33 18301 8192000000 10365501504 10798645248 1393967232 14.541 99 32 63 0 1461172240 58068512 9136496
21:05:04 19892 8192000000 10330662128 10768916480 1357900192 14.218 99 29 58 0 1430148128 63528704 8719232
21:06:28 56234 8192000000 10301933760 10738974720 1305626224 13.746 96 16 32 1 1358722944 47836384 5260336
21:08:29 76588 8192000000 10294977952 10731036672 1372073216 14.346 100 23 47 0 1391626160 14949024 4603920
21:10:52 98604 8192000000 10285942832 10743300096 1383548000 14.448 100 31 62 0 1402736640 11617456 7571184
21:14:08 5077 8192000000 10320225968 10742009856 1374700192 14.369 99 80 146 6 1398614784 7639008 16275584
21:15:13 70291 8192000000 10327378896 10778394624 1406229552 14.650 100 31 61 1 1425087872 11635024 7223296
21:17:50 70190 8192000000 10304040704 10765422592 1394089232 14.542 99 13 25 0 1413824000 16795600 2939168
21:20:01 26468 8192000000 10309819776 10765512704 1393080656 14.533 99 14 27 0 1410276256 14344944 2850656
21:21:29 65796 8192000000 10305867616 10759069696 1407294480 14.660 100 18 35 0 1427428656 15744208 4389968
21:24:24 88655 8192000000 10315133072 10779115520 1412634480 14.707 100 28 56 1 1429449776 9051760 7763536
21:26:59 6822 8192000000 10316510864 10770018304 1414436880 14.723 100 35 70 1 1432998880 10381792 8180208
21:28:16 25465 8192000000 10320399440 10770644992 1412060640 14.702 100 43 86 0 1431620752 8912736 10647376
21:29:55 41787 8192000000 10300253744 10758914048 1384583792 14.458 98 12 22 1 1396846336 9809008 2453536
21:31:38 63111 8192000000 10313201920 10792906752 1401959616 14.612 99 15 30 0 1426101376 20352336 3789424
21:33:54 38892 8192000000 10306261536 10778103808 1414355072 14.723 100 21 42 0 1431351360 11764864 5231424
21:35:36 61629 8192000000 10316370496 10790649856 1418192256 14.757 100 13 26 1 1435437520 13939136 3306128
21:37:38 42256 8192000000 10312233792 10777247744 1415559120 14.733 100 11 22 0 1434397056 16416448 2421488
21:39:29 9280 8192000000 10283136656 10723319808 1346902320 14.120 98 49 97 1 1365391568 7643392 10845856
21:40:39 34802 8192000000 10291715200 10748293120 1380777008 14.423 100 22 44 0 1398799712 12994048 5028656
21:42:14 59163 8192000000 10269342432 10725429248 1305702928 13.747 94 13 26 0 1319333488 10643504 2987056
21:44:21 71085 8192000000 10270493600 10715308032 1307465248 13.763 95 12 22 0 1325925216 16122384 2337584
21:47:25 135751 8192000000 10262730992 10715029504 1248228528 13.222 91 20 39 0 1272541024 19923936 4388560
21:50:41 86042 8192000000 10249706368 10686251008 1274905424 13.466 94 41 83 0 1294028896 9710160 9413312
21:54:25 12172 8192000000 10267020512 10714873856 1364140096 14.275 100 36 72 0 1381029888 8458992 8430800
21:55:45 73726 8192000000 10264495312 10729279488 1356452016 14.205 99 8 15 0 1371165680 13114592 1599072
21:58:15 61702 8192000000 10270041584 10730774528 1370382880 14.330 100 23 46 0 1387608208 11968960 5256368
22:00:35 84086 8192000000 10248120320 10701402112 1277934960 13.494 93 10 20 0 1290259392 10016624 2307808
22:03:14 20253 8192000000 10253531024 10692739072 1314327232 13.825 97 34 68 1 1329093824 7591792 7174800
22:04:39 41725 8192000000 10270369600 10716680192 1344891040 14.101 99 18 36 0 1356986048 8203776 3891232
22:06:27 94637 8192000000 10274053936 10752241664 1373506048 14.358 100 16 32 0 1394921728 17493792 3921888
22:09:05 34577 8192000000 10301481120 10750836736 1382605360 14.440 99 28 56 0 1398320752 10028560 5686832
22:10:40 140092 8192000000 10297220320 10751201280 1358647072 14.225 97 11 21 0 1370892192 10318576 1926544
22:14:07 30674 8192000000 10313265296 10751688704 1377018496 14.390 98 39 82 1 1394833504 8558544 9256464
22:15:38 114606 8192000000 10310045808 10761977856 1385927680 14.470 99 33 62 0 1403662656 11052128 6682848
22:18:35 25199 8192000000 10298393008 10751279104 1386247152 14.472 99 15 30 0 1399715600 9537744 3930704
22:20:07 33231 8192000000 10296346928 10759020544 1400101968 14.596 100 12 24 0 1411004656 7723312 3179376
22:21:41 51867 8192000000 10291634464 10758881280 1390950304 14.514 99 14 27 0 1402285264 8216656 3118304
22:23:33 33183 8192000000 10289980960 10747965440 1377873952 14.398 99 13 26 0 1386959360 6329584 2755824
22:25:13 62929 8192000000 10277879712 10737250304 1345035744 14.103 97 15 28 0 1356906736 8563184 3307808
22:27:16 40396 8192000000 10280182864 10732449792 1373438288 14.358 100 23 46 0 1391532880 12619664 5474928
22:29:32 72978 8192000000 10252671024 10703695872 1301096544 13.705 95 14 28 0 1316623152 12323792 3202816
22:31:54 55798 8192000000 10254734080 10701836288 1285213072 13.561 94 26 50 0 1302202000 11452816 5536112
22:33:51 42493 8192000000 10262079936 10714189824 1334205344 14.005 98 29 58 0 1354224816 13510272 6509200
22:35:36 52177 8192000000 10251044224 10709569536 1302381600 13.717 96 35 70 0 1327605520 17403552 7820368
22:37:33 73805 8192000000 9100634432 9338933248 270392784 3.195 20 6 12 0 273500976 1836320 1271872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 13-02-2023 22:40:00 to 13-02-2023 22:40:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 144 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 13-02-2023 22:40:16 to 13-02-2023 22:40:18.

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          245847
MON$OLDEST_ACTIVE               245848
MON$OLDEST_SNAPSHOT             245848
MON$NEXT_TRANSACTION            245848
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                       2036960
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.33662 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33662 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33662 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33662 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 ---
:248: 
:249: mscs-test-161.budzdorov.ru    Mon Feb 13 22:38:48 2023
:250:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:251: 
:252: 
:253: mscs-test-161.budzdorov.ru    Mon Feb 13 22:38:48 2023
:254:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:255: 
--- end of diff output ---

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

13.02.23 22:40:19. 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-02-13 22:40:22 No severe PSQL-related problems occured
13.02.23 22:40:23. End of report.