Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.01.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 15310304 kB
MemAvailable 30458032 kB
Buffers 0 kB
Cached 16424844 kB
SwapCached 1716 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-01-20 16:38:34
DTS_END                         2023-01-20 16:38:34.1360
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(131:22) ==> SP_CANCEL_ADDING_INVOICE(33:5) ==> SP_ADD_INVOICE_TO_STOCK(69:14) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51376
TRN_ID                          239586
ATT_ID                          637
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: 9280.72 23414 1113686
customer order: creation 170.36 354 20443
customer order: refuse 14.96 315 1795
order to supplier: creation 45.10 1309 5412
order to supplier: removal 3.50 93960 420
invoice (draft): creation 45.91 3365 5509
invoice (draft): removal 53.80 722 6456
invoice accept: apply 40.60 15850 4872
invoice accept: cancel 48.41 744 5809
customer reserve: creation 3755.13 155 450615
customer reserve: removal 4814.89 53 577787
realization accept: apply 82.87 178 9944
realization accept: cancel 76.83 118 9220
payment from customer: creation 59.03 114 7084
payment from customer: removal 5.56 86 667
payment to supplier: creation 48.68 151 5842
payment to supplier: removal 3.50 60 420
service: total inventory turnovers 4.66 1237 559
service: total monetary turnovers 6.12 24 734
service: aggregate perf. data 0.78 22481 94
service: refresh index statistics 0.03 327007 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 20-01-2023 16:40:02 to 20-01-2023 16:40:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4262
TEST_TIME 2 4991
TEST_TIME 3 4841
TEST_TIME 4 4284
TEST_TIME 5 4495
TEST_TIME 6 5183
TEST_TIME 7 5170
TEST_TIME 8 5348
TEST_TIME 9 5433
TEST_TIME 10 5357
TEST_TIME 11 5542
TEST_TIME 12 5528
TEST_TIME 13 5538
TEST_TIME 14 5779
TEST_TIME 15 5792
TEST_TIME 16 6236
TEST_TIME 17 6459
TEST_TIME 18 6626
TEST_TIME 19 7622
TEST_TIME 20 7719
TEST_TIME 21 7937
TEST_TIME 22 8083
TEST_TIME 23 8855
TEST_TIME 24 8761
TEST_TIME 25 9050
TEST_TIME 26 9070
TEST_TIME 27 9774
TEST_TIME 28 10133
TEST_TIME 29 10056
TEST_TIME 30 10030
TEST_TIME 31 9899
TEST_TIME 32 9780
TEST_TIME 33 9771
TEST_TIME 34 9915
TEST_TIME 35 9842
TEST_TIME 36 9682
TEST_TIME 37 9632
TEST_TIME 38 9728
TEST_TIME 39 9707
TEST_TIME 40 9698
TEST_TIME 41 9697
TEST_TIME 42 9567
TEST_TIME 43 9600
TEST_TIME 44 9581
TEST_TIME 45 9524
TEST_TIME 46 9418
TEST_TIME 47 9374
TEST_TIME 48 9271
TEST_TIME 49 9305
TEST_TIME 50 9219
TEST_TIME 51 9142
TEST_TIME 52 9137
TEST_TIME 53 9113
TEST_TIME 54 9137
TEST_TIME 55 9238
TEST_TIME 56 9243
TEST_TIME 57 9340
TEST_TIME 58 9341
TEST_TIME 59 9376
TEST_TIME 60 9484
TEST_TIME 61 9511
TEST_TIME 62 9500
TEST_TIME 63 9686
TEST_TIME 64 9724
TEST_TIME 65 9773
TEST_TIME 66 9708
TEST_TIME 67 9588
TEST_TIME 68 9536
TEST_TIME 69 9543
TEST_TIME 70 9458
TEST_TIME 71 9445
TEST_TIME 72 9380
TEST_TIME 73 9291
TEST_TIME 74 9334
TEST_TIME 75 9390
TEST_TIME 76 9370
TEST_TIME 77 9387
TEST_TIME 78 9395
TEST_TIME 79 9395
TEST_TIME 80 9353
TEST_TIME 81 9399
TEST_TIME 82 9428
TEST_TIME 83 9500
TEST_TIME 84 9524
TEST_TIME 85 9525
TEST_TIME 86 9483
TEST_TIME 87 9643
TEST_TIME 88 9594
TEST_TIME 89 9514
TEST_TIME 90 9481
TEST_TIME 91 9417
TEST_TIME 92 9476
TEST_TIME 93 9479
TEST_TIME 94 9411
TEST_TIME 95 9403
TEST_TIME 96 9407
TEST_TIME 97 9375
TEST_TIME 98 9365
TEST_TIME 99 9438
TEST_TIME 100 9441
TEST_TIME 101 9441
TEST_TIME 102 9434
TEST_TIME 103 9520
TEST_TIME 104 9506
TEST_TIME 105 9499
TEST_TIME 106 9533
TEST_TIME 107 9611
TEST_TIME 108 9604
TEST_TIME 109 9531
TEST_TIME 110 9502
TEST_TIME 111 9468
TEST_TIME 112 9481
TEST_TIME 113 9453
TEST_TIME 114 9418
TEST_TIME 115 9391
TEST_TIME 116 9371
TEST_TIME 117 9379
TEST_TIME 118 9345
TEST_TIME 119 9334
TEST_TIME 120 9282

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-01-2023 16:40:06 to 20-01-2023 16:40:10.

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 1532833 1532833 0 0.00 0 23804 5 0 0
doc_list_biud 1532831 1532831 0 0.00 0 56002 26 0 0
sp_add_invoice_to_stock 4985 4872 113 2.26 37 169809 15851 0 113
sp_cancel_adding_invoice 5809 5809 0 0.00 3 61866 745 0 0
sp_cancel_client_order 1795 1795 0 0.00 4 7599 316 0 0
sp_cancel_customer_reserve 577787 577787 0 0.00 0 56003 54 0 0
sp_cancel_pay_from_customer 667 667 0 0.00 3 7010 87 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 2233 60 0 0
sp_cancel_supplier_invoice 6465 6456 9 0.13 1 25984 722 0 9
sp_cancel_supplier_order 420 420 0 0.00 9 1006931 93960 0 0
sp_cancel_write_off 9220 9220 0 0.00 1 16364 118 0 0
sp_client_order 20443 20443 0 0.00 3 31235 355 0 0
sp_customer_reserve 450615 450615 0 0.00 0 43010 156 0 0
sp_fill_shopping_cart 31360 31360 0 0.00 1 16971 38 0 0
sp_fill_shopping_cart_clo_res 450609 450609 0 0.00 0 20447 14 0 0
sp_get_clo_for_invoice 4865 4865 0 0.00 0 20893 303 0 0
sp_kill_cost_storno 16116 16116 0 0.00 0 23552 52 0 0
sp_kill_qstorno_ret_qs2qd 1171670 1171670 0 0.00 0 56000 29 0 0
sp_kill_qty_storno 616295 616295 0 0.00 0 56002 63 0 0
sp_lock_selected_doc 20630 20630 0 0.00 0 10724 8 0 0
sp_make_cost_storno 27735 27735 0 0.00 0 20735 56 0 0
sp_multiply_rows_for_pdistr 27733 27733 0 0.00 0 8510 27 0 0
sp_multiply_rows_for_qdistr 20443 20443 0 0.00 0 23913 165 0 0
sp_pay_from_customer 7084 7084 0 0.00 4 25891 115 0 0
sp_pay_to_supplier 5842 5842 0 0.00 4 25932 151 0 0
sp_payment_common 12925 12925 0 0.00 1 16343 34 0 0
sp_qd_handle_on_cancel_clo 1795 1795 0 0.00 0 7589 295 0 0
sp_qd_handle_on_invoice_upd_sts 10674 10674 0 0.00 0 20968 312 0 0
sp_qd_handle_on_reserve_upd_sts 9943 9943 0 0.00 0 13662 58 0 0
sp_reserve_write_off 9944 9944 0 0.00 7 20744 179 0 0
sp_supplier_invoice 5509 5509 0 0.00 7 101895 3366 0 0
sp_supplier_order 5412 5412 0 0.00 5 43208 1309 0 0
srv_aggregate_perf_data 94 94 0 0.00 2565 292401 22481 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1791 120656 41202 0 0
srv_find_qd_qs_mism 1067559 1067559 0 0.00 0 23759 4 0 0
srv_make_invnt_saldo 559 559 0 0.00 36 50642 1237 0 0
srv_make_money_saldo 734 734 0 0.00 1 932 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 113220 501071 327008 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 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 4872 4872 0 0.00 0 25676 195 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 5586 112 0 0
t$perf-norm:sp_cancel_client_order 1795 1795 0 0.00 0 3084 13 0 0
t$perf-norm:sp_cancel_customer_reserve 696 696 0 0.00 0 2131 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 667 667 0 0.00 0 3442 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 6764 37 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 1924 26 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 24829 1580 0 0
t$perf-norm:sp_cancel_write_off 1002 1002 0 0.00 0 4969 14 0 0
t$perf-norm:sp_client_order 20443 20443 0 0.00 0 19358 20 0 0
t$perf-norm:sp_customer_reserve 981 981 0 0.00 0 2766 11 0 0
t$perf-norm:sp_pay_from_customer 7084 7084 0 0.00 0 7366 17 0 0
t$perf-norm:sp_pay_to_supplier 5842 5842 0 0.00 0 13123 20 0 0
t$perf-norm:sp_reserve_write_off 9944 9944 0 0.00 0 10891 13 0 0
t$perf-norm:sp_supplier_invoice 5509 5509 0 0.00 0 18387 24 0 0
t$perf-norm:sp_supplier_order 5412 5412 0 0.00 0 7220 19 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 317 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 1127 35 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 1745 24 0 0
t$perf-norm:srv_make_money_saldo 734 734 0 0.00 0 4862 25 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 9954 9728 226 2.27 0 9063 20 0 226
v_all_customers 37858 37858 0 0.00 0 62 0 0 0
v_all_suppliers 30550 30550 0 0.00 0 21 1 0 0
v_all_wares 40886 40886 0 0.00 0 74 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 3942 11 0 0
v_cancel_client_order 3590 3590 0 0.00 0 1022 4 0 0
v_cancel_customer_prepayment 1334 1334 0 0.00 0 4059 7 0 0
v_cancel_customer_reserve 1392 1392 0 0.00 0 672 3 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 1729 5 0 0
v_cancel_supplier_invoice 862 844 18 2.08 0 1527 17 0 18
v_cancel_supplier_order 840 840 0 0.00 0 3681 13 0 0
v_cancel_write_off 2004 2004 0 0.00 0 2329 14 0 0
v_max_id_clo_ord 5412 5412 0 0.00 0 346 1 0 0
v_max_id_clo_res 981 981 0 0.00 0 2329 17 0 0
v_max_id_ord_sup 5505 5505 0 0.00 0 2798 4 0 0
v_max_non_paid_invoice 1483 1483 0 0.00 0 16319 18 0 0
v_max_non_paid_realizn 3578 3578 0 0.00 0 11508 9 0 0
v_min_id_clo_ord 5412 5412 0 0.00 0 7370 11 0 0
v_min_id_clo_res 981 981 0 0.00 0 4161 25 0 0
v_min_id_ord_sup 5505 5505 0 0.00 0 5980 6 0 0
v_min_non_paid_invoice 5841 5841 0 0.00 0 7257 23 0 0
v_min_non_paid_realizn 7084 7084 0 0.00 0 7288 22 0 0
v_our_firm 10040 10040 0 0.00 0 59 1 0 0
v_reserve_write_off 19884 19884 0 0.00 0 3463 5 0 0
x_lock_depdocs_on_canc_invoice 11843 11843 0 0.00 0 40417 116 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 12601 398 0 0
x_make_qstorno_1000_1200 5412 5412 0 0.00 2 43189 1237 0 0
x_make_qstorno_1200_2000 5509 5509 0 0.00 3 101817 3222 0 0
x_make_qstorno_2100_3300 450614 450614 0 0.00 0 37875 139 0 0

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

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:38 10 8192000000 8814193120 8830885888 17840288 0.217 5 4 5 0 18068352 88784 139280
14:09:39 21021 8192000000 9916532304 10230415360 997395632 10.853 100 85 170 0 1025607040 6285776 21925632
14:11:03 6635 8192000000 9953346208 10277167104 1038496304 11.250 100 86 172 0 1069496960 8852288 22148368
14:12:12 14240 8192000000 9999731888 10333261824 1072879184 11.580 100 88 176 0 1105238128 10337168 22021776
14:13:37 9662 8192000000 10025724720 10371424256 1108573984 11.919 100 82 164 0 1139977312 11505568 19897760
14:14:49 7549 8192000000 10072925072 10429304832 1160681056 12.410 100 81 161 0 1191833696 12324592 18828048
14:16:02 4971 8192000000 10105600592 10474758144 1201184480 12.787 100 70 140 0 1228291632 11214256 15892896
14:17:10 2014 8192000000 10160540976 10535804928 1248994960 13.229 100 76 153 0 1278150864 12287712 16868192
14:18:14 6472 8192000000 10182226832 10563690496 1274393584 13.462 100 87 169 1 1306817952 14280496 18143872
14:19:22 1423 8192000000 10222703840 10608611328 1307126272 13.760 100 90 181 0 1340265584 13142576 19996736
14:20:25 1473 8192000000 10221722880 10621149184 1317814880 13.857 100 66 129 3 1341987120 8007568 16164672
14:21:28 5335 8192000000 10216079376 10629693440 1324994688 13.922 100 27 53 1 1337815712 7186352 5634672
14:22:34 2989 8192000000 10220451152 10637774848 1330795824 13.974 100 27 52 0 1343812096 7284096 5732176
14:23:38 6720 8192000000 10234715760 10647117824 1340451104 14.061 100 27 55 1 1352860336 6443120 5966112
14:24:46 66296 8192000000 10226107200 10653679616 1342434464 14.079 100 21 41 1 1351981760 5421488 4125808
14:26:53 4680 8192000000 10237909920 10658209792 1342089488 14.076 100 35 68 0 1356336080 5957552 8289040
14:27:59 7507 8192000000 10223296304 10646302720 1325329728 13.925 99 29 58 1 1338364176 6527552 6506896
14:29:11 12653 8192000000 10179781648 10594381824 1262949568 13.357 97 9 18 0 1270139040 5285216 1904256
14:30:27 32546 8192000000 10169757616 10576863232 1240829712 13.154 96 27 54 0 1252989632 5801056 6358864
14:32:07 10733 8192000000 10149434960 10547290112 1241833824 13.163 99 28 57 0 1254558432 6481376 6243232
14:33:19 9133 8192000000 10162165696 10566692864 1255329840 13.287 99 27 54 0 1270853984 9246688 6277456
14:34:32 63047 8192000000 10189497792 10600849408 1297989648 13.677 100 36 72 0 1313038016 5986016 9062352
14:36:36 1663 8192000000 10203754800 10611441664 1303773536 13.730 100 44 88 1 1322045712 7438800 10833376
14:37:38 4775 8192000000 10209230176 10621464576 1313212272 13.815 100 28 56 1 1326126416 5658768 7255376
14:38:45 12352 8192000000 10241565728 10652782592 1340494432 14.062 100 56 111 1 1360876032 8294944 12086656
14:40:09 95785 8192000000 10261233264 10689626112 1356784064 14.208 100 41 82 0 1377254416 10475392 9994960
14:42:55 27572 8192000000 10280568048 10707951616 1371158240 14.337 100 33 65 0 1396439920 17420944 7860736
14:44:24 33143 8192000000 10293064384 10720129024 1356308336 14.204 98 49 98 1 1389983680 21982400 11692944
14:46:12 25222 8192000000 10314899024 10741374976 1388726864 14.495 100 51 100 1 1424962624 24572944 11662816
14:47:40 7148 8192000000 10330596512 10760687616 1401618784 14.609 100 39 78 0 1436408064 25521600 9267680
14:48:51 17037 8192000000 10323199472 10755293184 1397185120 14.570 99 39 78 1 1435456048 28316960 9953968
14:50:15 2744 8192000000 10342664256 10759704576 1404363728 14.634 100 65 130 1 1448875264 28269488 16242048
14:51:23 5390 8192000000 10356784256 10777731072 1416122576 14.738 100 64 126 1 1463777552 32071360 15583616
14:53:00 8429 8192000000 10349395312 10774278144 1409206928 14.677 100 52 97 0 1457423552 35580960 12635664
14:54:11 2562 8192000000 10340744544 10755751936 1383381216 14.447 99 61 122 3 1438945520 39943936 15620368
14:55:21 1949 8192000000 10352400336 10757681152 1383866432 14.451 100 88 176 0 1454475200 47768560 22840208
14:56:27 1790 8192000000 10305640944 10708455424 1330790608 13.974 97 69 134 3 1396059104 48129840 17138656
14:57:35 8685 8192000000 10323689280 10741780480 1369208944 14.320 100 47 94 0 1432075248 50075392 12790912
14:58:54 12348 8192000000 10318396000 10739744768 1364835888 14.281 100 38 74 0 1420766768 45946352 9984528
15:00:16 2693 8192000000 10283209616 10699804672 1339749936 14.055 99 47 95 3 1393555840 40517456 13288448
15:01:21 14022 8192000000 10275986144 10704318464 1337376688 14.034 99 27 54 0 1375194352 30754736 7062928
15:02:42 8334 8192000000 10295265296 10720661504 1357515824 14.215 100 33 63 0 1399301184 33703152 8082208
15:04:00 33004 8192000000 10287838816 10717995008 1352048096 14.166 100 18 36 0 1392518608 35389152 5081360
15:05:36 26934 8192000000 10286104400 10736504832 1364282080 14.276 100 18 36 0 1407433648 38372160 4779408
15:07:04 46692 8192000000 10297085280 10728677376 1347727360 14.127 98 35 70 0 1370430592 14407680 8295552
15:08:55 76205 8192000000 10282952416 10726244352 1364490896 14.278 99 23 46 0 1383313168 13233024 5589248
15:11:36 24320 8192000000 10291458816 10733486080 1381271616 14.428 100 16 29 0 1397812768 13767552 2773600
15:13:01 84428 8192000000 10276901440 10739179520 1343895552 14.093 97 17 31 0 1361401904 14485008 3021344
15:15:29 17549 8192000000 10304385568 10749480960 1396208480 14.561 100 29 58 1 1410338448 7551824 6578144
15:16:50 36782 8192000000 10291314016 10752561152 1391562352 14.520 100 16 30 0 1409223856 13251824 4409680
15:18:28 19014 8192000000 10293019584 10746699776 1378289312 14.401 99 13 26 0 1393383104 11888256 3205536
15:20:13 35706 8192000000 10288917168 10740822016 1375618160 14.377 99 14 28 0 1392603968 13615040 3370768
15:21:55 55175 8192000000 10290672768 10756681728 1393217792 14.535 100 12 24 0 1409052384 13076512 2758080
15:23:50 64296 8192000000 10292682688 10746490880 1380718752 14.423 99 34 66 0 1393971840 6825840 6427248
15:26:03 46535 8192000000 10296674944 10750935040 1375330368 14.375 98 30 61 1 1390673664 8500896 6842400
15:27:59 49251 8192000000 10270043776 10735083520 1364513808 14.278 98 15 30 1 1373477712 5889216 3074688
15:29:51 53811 8192000000 10286504432 10752442368 1377660096 14.396 99 17 34 0 1390118960 8329952 4128912
15:31:48 56970 8192000000 10311753360 10772295680 1384140848 14.454 99 18 35 0 1400539040 12667776 3730416
15:33:46 59299 8192000000 10316998080 10778566656 1395773712 14.557 99 17 34 0 1420316928 20348880 4194336
15:35:46 33967 8192000000 10280551760 10731397120 1349372336 14.142 97 24 48 0 1366063488 11289248 5401904
15:37:22 42685 8192000000 10276671936 10731520000 1329305792 13.961 96 25 50 0 1348699104 12917648 6475664
15:39:06 23779 8192000000 10272619424 10714660864 1348426240 14.133 98 44 87 0 1367816032 8697280 10692512
15:40:30 120656 8192000000 10245888608 10704150528 1238098944 13.129 91 4 8 0 1255003616 16325600 579072
15:43:38 74440 8192000000 10273583056 10731376640 1365226336 14.284 100 16 32 0 1393415216 24303744 3885136
15:46:19 4683 8192000000 10289857008 10726658048 1368256880 14.311 100 48 96 2 1387931952 9055408 10619664
15:47:28 75544 8192000000 10252361520 10716897280 1343722560 14.091 98 6 12 0 1353578160 8597488 1258112
15:50:18 74749 8192000000 10253248736 10703200256 1320870304 13.885 97 16 32 0 1335787248 11114176 3802768
15:53:06 41325 8192000000 10252262496 10714091520 1352017376 14.166 100 9 17 0 1366686096 13034000 1634720
15:54:51 38159 8192000000 10247990448 10691104768 1336166640 14.023 98 33 63 0 1352532016 8819968 7545408
15:56:38 41240 8192000000 10256508848 10709196800 1346474176 14.116 99 13 26 0 1359273568 9724048 3075344
15:59:20 61659 8192000000 10278868112 10732650496 1358480400 14.224 99 15 30 0 1378453312 16626832 3346080
16:01:35 99298 8192000000 10294473792 10745225216 1375095280 14.373 100 16 32 0 1399020128 20527152 3397696
16:04:15 24863 8192000000 10299044880 10735288320 1376239840 14.383 100 33 63 1 1395070608 12123296 6707472
16:05:44 53734 8192000000 10271273424 10716762112 1334322304 14.006 97 5 10 0 1348772928 13350448 1100176
16:07:38 74893 8192000000 10296358208 10741526528 1378165344 14.400 100 22 44 0 1396694880 12983488 5546048
16:09:57 2262 8192000000 10301567952 10742194176 1373007296 14.354 99 44 86 1 1399818752 17836448 8975008
16:11:00 56295 8192000000 10281278944 10753216512 1390984240 14.515 100 10 20 0 1403980944 10788576 2208128
16:12:56 64674 8192000000 10265783136 10726879232 1334006640 14.003 96 9 18 0 1345025584 8724704 2294240
16:15:01 77693 8192000000 10299974704 10746552320 1365012944 14.282 98 28 54 1 1379449936 8264560 6172432
16:17:57 41431 8192000000 10300519264 10751688704 1385335712 14.464 99 12 22 0 1397237344 9073232 2828400
16:19:48 60601 8192000000 10328826768 10791088128 1407435472 14.661 100 27 55 0 1433268816 19706720 6126624
16:21:56 36282 8192000000 10305622912 10756141056 1375382560 14.375 98 13 26 0 1386133152 8213536 2537056
16:23:45 61523 8192000000 10312036768 10766311424 1374676528 14.369 99 22 45 0 1398047312 18488240 4882544
16:25:49 41023 8192000000 10293143440 10727567360 1324018448 13.913 95 29 58 1 1344436720 14140416 6277856
16:27:43 97535 8192000000 10251929184 10694021120 1311780048 13.802 95 39 78 0 1332336880 11696096 8860736
16:30:28 24337 8192000000 10257117216 10726428672 1366228208 14.293 100 6 12 0 1377002080 9490400 1283472
16:32:01 29600 8192000000 10246907856 10702958592 1347683024 14.127 99 15 31 0 1357284240 6048016 3553200
16:33:44 45196 8192000000 10255812800 10715738112 1361655456 14.252 100 12 24 1 1372709680 8216416 2837808
16:35:30 69398 8192000000 10268198384 10735849472 1368425824 14.313 100 7 14 0 1381732208 11792752 1513632
16:37:48 52489 8192000000 9333384352 9622708224 464308672 5.363 34 11 22 0 476005056 9153840 2542544

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-01-2023 16:40:17 to 20-01-2023 16:40:32.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 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 20-01-2023 16:40:33 to 20-01-2023 16:40:35.

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          239585
MON$OLDEST_ACTIVE               242718
MON$OLDEST_SNAPSHOT             242718
MON$NEXT_TRANSACTION            242718
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                       2034592
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.33654 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33654 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33654 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33654 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 ---
:194: 
:195: mscs-test-161.budzdorov.ru    Fri Jan 20 16:38:45 2023
:196:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:197: 
:198: 
:199: mscs-test-161.budzdorov.ru    Fri Jan 20 16:38:50 2023
:200:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:201: 
--- end of diff output ---

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

20.01.23 16:40:36. 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-01-20 16:40:39 No severe PSQL-related problems occured
20.01.23 16:40:40. End of report.