Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.03.2023 01: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 15086860 kB
MemAvailable 30394280 kB
Buffers 0 kB
Cached 16593644 kB
SwapCached 3028 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-05 01:39:09
DTS_END                         2023-03-05 01:38:41.0030
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/38608
TRN_ID                          248399
ATT_ID                          274
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: 9487.20 18391 1138464
customer order: creation 178.68 361 21442
customer order: refuse 15.02 325 1802
order to supplier: creation 46.94 1300 5633
order to supplier: removal 3.42 107207 410
invoice (draft): creation 47.92 3279 5750
invoice (draft): removal 53.95 858 6474
invoice accept: apply 43.49 17835 5219
invoice accept: cancel 48.56 745 5827
customer reserve: creation 4021.98 176 482638
customer reserve: removal 4726.08 60 567129
realization accept: apply 86.19 183 10343
realization accept: cancel 81.71 98 9805
payment from customer: creation 61.40 131 7368
payment from customer: removal 6.08 74 729
payment to supplier: creation 49.78 152 5974
payment to supplier: removal 3.36 57 403
service: total inventory turnovers 4.83 1199 580
service: total monetary turnovers 6.90 24 828
service: aggregate perf. data 0.88 19713 106
service: refresh index statistics 0.03 214043 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-03-2023 01:39:47 to 05-03-2023 01:39:49.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3387
TEST_TIME 2 2687
TEST_TIME 3 5090
TEST_TIME 4 5563
TEST_TIME 5 5328
TEST_TIME 6 5511
TEST_TIME 7 5261
TEST_TIME 8 5224
TEST_TIME 9 5025
TEST_TIME 10 5216
TEST_TIME 11 5212
TEST_TIME 12 5394
TEST_TIME 13 5423
TEST_TIME 14 5715
TEST_TIME 15 6309
TEST_TIME 16 6347
TEST_TIME 17 6573
TEST_TIME 18 6658
TEST_TIME 19 6692
TEST_TIME 20 6857
TEST_TIME 21 6877
TEST_TIME 22 7451
TEST_TIME 23 7939
TEST_TIME 24 8066
TEST_TIME 25 8272
TEST_TIME 26 8713
TEST_TIME 27 8935
TEST_TIME 28 9083
TEST_TIME 29 9401
TEST_TIME 30 9320
TEST_TIME 31 10301
TEST_TIME 32 10445
TEST_TIME 33 10573
TEST_TIME 34 10472
TEST_TIME 35 10646
TEST_TIME 36 10539
TEST_TIME 37 10460
TEST_TIME 38 10224
TEST_TIME 39 10035
TEST_TIME 40 10069
TEST_TIME 41 10026
TEST_TIME 42 9951
TEST_TIME 43 9864
TEST_TIME 44 9886
TEST_TIME 45 9753
TEST_TIME 46 9727
TEST_TIME 47 9750
TEST_TIME 48 9843
TEST_TIME 49 9892
TEST_TIME 50 9833
TEST_TIME 51 9868
TEST_TIME 52 9846
TEST_TIME 53 9710
TEST_TIME 54 9603
TEST_TIME 55 9586
TEST_TIME 56 9624
TEST_TIME 57 9607
TEST_TIME 58 9553
TEST_TIME 59 9463
TEST_TIME 60 9383
TEST_TIME 61 9386
TEST_TIME 62 9323
TEST_TIME 63 9348
TEST_TIME 64 9313
TEST_TIME 65 9386
TEST_TIME 66 9499
TEST_TIME 67 9599
TEST_TIME 68 9592
TEST_TIME 69 9626
TEST_TIME 70 9622
TEST_TIME 71 9653
TEST_TIME 72 9683
TEST_TIME 73 9691
TEST_TIME 74 9699
TEST_TIME 75 9701
TEST_TIME 76 9740
TEST_TIME 77 9644
TEST_TIME 78 9610
TEST_TIME 79 9570
TEST_TIME 80 9585
TEST_TIME 81 9613
TEST_TIME 82 9552
TEST_TIME 83 9491
TEST_TIME 84 9399
TEST_TIME 85 9455
TEST_TIME 86 9452
TEST_TIME 87 9412
TEST_TIME 88 9476
TEST_TIME 89 9444
TEST_TIME 90 9501
TEST_TIME 91 9522
TEST_TIME 92 9506
TEST_TIME 93 9500
TEST_TIME 94 9547
TEST_TIME 95 9558
TEST_TIME 96 9622
TEST_TIME 97 9680
TEST_TIME 98 9654
TEST_TIME 99 9691
TEST_TIME 100 9722
TEST_TIME 101 9784
TEST_TIME 102 9697
TEST_TIME 103 9646
TEST_TIME 104 9615
TEST_TIME 105 9607
TEST_TIME 106 9557
TEST_TIME 107 9535
TEST_TIME 108 9516
TEST_TIME 109 9507
TEST_TIME 110 9541
TEST_TIME 111 9464
TEST_TIME 112 9477
TEST_TIME 113 9493
TEST_TIME 114 9505
TEST_TIME 115 9470
TEST_TIME 116 9505
TEST_TIME 117 9514
TEST_TIME 118 9544
TEST_TIME 119 9526
TEST_TIME 120 9488

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-03-2023 01:39:51 to 05-03-2023 01:39:54.

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 1584957 1584957 0 0.00 0 22258 7 0 0
doc_list_biud 1584957 1584957 0 0.00 0 24546 28 0 0
sp_add_invoice_to_stock 5334 5219 115 2.15 45 212601 17835 0 115
sp_cancel_adding_invoice 5827 5827 0 0.00 4 48526 745 0 0
sp_cancel_client_order 1802 1802 0 0.00 4 17402 325 0 0
sp_cancel_customer_reserve 567129 567129 0 0.00 0 19520 60 0 0
sp_cancel_pay_from_customer 729 729 0 0.00 2 5840 75 0 0
sp_cancel_pay_to_supplier 403 403 0 0.00 3 1705 57 0 0
sp_cancel_supplier_invoice 6490 6474 16 0.24 1 26481 858 0 16
sp_cancel_supplier_order 410 410 0 0.00 7 1060444 107207 0 0
sp_cancel_write_off 9805 9805 0 0.00 1 14820 98 0 0
sp_client_order 21442 21442 0 0.00 3 25232 361 0 0
sp_customer_reserve 482638 482638 0 0.00 0 48194 176 0 0
sp_fill_shopping_cart 32824 32824 0 0.00 1 11491 35 0 0
sp_fill_shopping_cart_clo_res 482635 482635 0 0.00 0 9988 12 0 0
sp_get_clo_for_invoice 5216 5216 0 0.00 0 17096 271 0 0
sp_kill_cost_storno 16764 16764 0 0.00 0 7094 51 0 0
sp_kill_qstorno_ret_qs2qd 1150947 1150947 0 0.00 0 24544 33 0 0
sp_kill_qty_storno 607005 607005 0 0.00 0 24545 71 0 0
sp_lock_selected_doc 21446 21446 0 0.00 0 10187 4 0 0
sp_make_cost_storno 28900 28900 0 0.00 0 20215 66 0 0
sp_multiply_rows_for_pdistr 28900 28900 0 0.00 0 9213 29 0 0
sp_multiply_rows_for_qdistr 21442 21442 0 0.00 0 14172 146 0 0
sp_pay_from_customer 7368 7368 0 0.00 4 11892 131 0 0
sp_pay_to_supplier 5974 5974 0 0.00 4 18564 152 0 0
sp_payment_common 13342 13342 0 0.00 1 10661 39 0 0
sp_qd_handle_on_cancel_clo 1802 1802 0 0.00 0 17391 301 0 0
sp_qd_handle_on_invoice_upd_sts 11042 11042 0 0.00 0 24483 322 0 0
sp_qd_handle_on_reserve_upd_sts 10343 10343 0 0.00 0 16735 57 0 0
sp_reserve_write_off 10343 10343 0 0.00 6 22285 184 0 0
sp_supplier_invoice 5750 5750 0 0.00 6 85401 3280 0 0
sp_supplier_order 5633 5633 0 0.00 4 28920 1300 0 0
srv_aggregate_perf_data 106 106 0 0.00 2933 345572 19714 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1575 133383 42790 0 0
srv_find_qd_qs_mism 1088744 1088744 0 0.00 0 17019 6 0 0
srv_make_invnt_saldo 580 580 0 0.00 37 58868 1200 0 0
srv_make_money_saldo 828 828 0 0.00 1 791 24 0 0
srv_recalc_idx_stat 4 4 0 0.00 105099 410759 214044 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 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 5219 5219 0 0.00 0 22057 167 0 0
t$perf-norm:sp_cancel_adding_invoice 428 428 0 0.00 0 4951 82 0 0
t$perf-norm:sp_cancel_client_order 1802 1802 0 0.00 0 3626 19 0 0
t$perf-norm:sp_cancel_customer_reserve 710 710 0 0.00 0 3916 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 729 729 0 0.00 0 2287 11 0 0
t$perf-norm:sp_cancel_pay_to_supplier 403 403 0 0.00 0 15 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 413 413 0 0.00 0 2831 30 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 26089 1590 0 0
t$perf-norm:sp_cancel_write_off 993 993 0 0.00 0 566 3 0 0
t$perf-norm:sp_client_order 21442 21442 0 0.00 0 15515 17 0 0
t$perf-norm:sp_customer_reserve 1015 1015 0 0.00 0 1632 9 0 0
t$perf-norm:sp_pay_from_customer 7368 7368 0 0.00 0 3277 8 0 0
t$perf-norm:sp_pay_to_supplier 5974 5974 0 0.00 0 11616 14 0 0
t$perf-norm:sp_reserve_write_off 10343 10343 0 0.00 0 8267 14 0 0
t$perf-norm:sp_supplier_invoice 5750 5750 0 0.00 0 3368 16 0 0
t$perf-norm:sp_supplier_order 5633 5633 0 0.00 0 9114 21 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 575 17 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 1838 46 0 0
t$perf-norm:srv_make_invnt_saldo 580 580 0 0.00 0 1331 25 0 0
t$perf-norm:srv_make_money_saldo 828 828 0 0.00 0 2947 26 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 7 4 0 0
v_add_invoice_to_stock 10660 10430 230 2.15 0 7866 18 0 230
v_all_customers 38946 38946 0 0.00 0 32 1 0 0
v_all_suppliers 31698 31698 0 0.00 0 28 1 0 0
v_all_wares 42884 42884 0 0.00 0 88 1 0 0
v_cancel_adding_invoice 856 856 0 0.00 0 1014 9 0 0
v_cancel_client_order 3604 3604 0 0.00 0 2637 6 0 0
v_cancel_customer_prepayment 1458 1458 0 0.00 0 1407 7 0 0
v_cancel_customer_reserve 1420 1420 0 0.00 0 1726 6 0 0
v_cancel_payment_to_supplier 806 806 0 0.00 0 1681 8 0 0
v_cancel_supplier_invoice 858 826 32 3.72 0 1802 18 0 32
v_cancel_supplier_order 820 820 0 0.00 0 1981 12 0 0
v_cancel_write_off 1986 1986 0 0.00 0 2661 16 0 0
v_max_id_clo_ord 5632 5632 0 0.00 0 332 1 0 0
v_max_id_clo_res 1015 1015 0 0.00 0 1958 14 0 0
v_max_id_ord_sup 5750 5750 0 0.00 0 5079 4 0 0
v_max_non_paid_invoice 1507 1507 0 0.00 0 2349 6 0 0
v_max_non_paid_realizn 4051 4051 0 0.00 0 7120 10 0 0
v_min_id_clo_ord 5632 5632 0 0.00 0 7350 10 0 0
v_min_id_clo_res 1015 1015 0 0.00 0 9268 15 0 0
v_min_id_ord_sup 5750 5750 0 0.00 0 7338 8 0 0
v_min_non_paid_invoice 5974 5974 0 0.00 0 9583 24 0 0
v_min_non_paid_realizn 7368 7368 0 0.00 0 10654 34 0 0
v_our_firm 10572 10572 0 0.00 0 17 1 0 0
v_reserve_write_off 20686 20686 0 0.00 0 7488 7 0 0
x_lock_depdocs_on_canc_invoice 11888 11888 0 0.00 0 14195 115 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 9740 396 0 0
x_make_qstorno_1000_1200 5633 5633 0 0.00 1 28905 1226 0 0
x_make_qstorno_1200_2000 5750 5750 0 0.00 3 83229 3158 0 0
x_make_qstorno_2100_3300 482638 482638 0 0.00 0 48191 161 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-03-2023 01:39:55 to 05-03-2023 01:39:59.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
23:08:44 8 8192000000 8802891216 8815546368 7908448 0.096 2 2 3 0 8049536 66144 74944
23:09:44 13299 8192000000 9887984656 10195046400 973401440 10.620 100 91 182 0 1003106544 5678880 24026224
23:11:02 11784 8192000000 9940920880 10262020096 1026658496 11.136 100 86 172 0 1056698192 8176896 21862800
23:12:23 13755 8192000000 9973709104 10303479808 1056209664 11.420 100 85 170 0 1087641840 9418336 22013840
23:13:40 6945 8192000000 10013369312 10359558144 1106711040 11.901 100 76 152 0 1135148496 10293328 18144128
23:15:14 5184 8192000000 10072226272 10429550592 1158727344 12.391 100 84 169 0 1190501680 11830752 19943584
23:16:22 1844 8192000000 10125822112 10492239872 1212684384 12.894 100 87 173 0 1245980144 13633104 19662656
23:17:26 1258 8192000000 10158032112 10533294080 1245519792 13.197 100 85 170 0 1278258768 13905984 18832992
23:18:29 1207 8192000000 10191011056 10575163392 1280226240 13.515 100 77 154 0 1309658240 11984240 17447760
23:19:31 1837 8192000000 10211810496 10600169472 1301054560 13.705 100 79 158 1 1332313264 14206944 17051760
23:20:34 8306 8192000000 10205993072 10607783936 1305099648 13.742 100 55 108 0 1326003072 8443888 12459536
23:21:48 13081 8192000000 10206441152 10625167360 1321776544 13.893 100 18 36 0 1331888640 6102448 4009648
23:23:05 11717 8192000000 10205910560 10629664768 1329012576 13.958 100 25 48 0 1340478992 5966192 5500224
23:24:17 71020 8192000000 10229762784 10650992640 1335735744 14.019 100 27 55 1 1347427520 5552592 6139184
23:26:31 7486 8192000000 10225569440 10648801280 1336838816 14.029 100 28 57 0 1349305200 6236688 6229696
23:27:40 9174 8192000000 10213147424 10632155136 1309940176 13.786 98 21 42 1 1320571120 5822480 4808464
23:28:51 15344 8192000000 10166010240 10576191488 1194627968 12.726 91 13 26 1 1202810256 5528432 2653856
23:30:07 22714 8192000000 10160723360 10568851456 1181625168 12.605 91 23 46 1 1191798096 5322944 4849984
23:31:30 8295 8192000000 10132503408 10533429248 1212711680 12.894 97 22 42 0 1223846688 6136256 4998752
23:32:39 11328 8192000000 10145698528 10549288960 1235687168 13.107 98 20 40 0 1245164672 4936480 4541024
23:33:52 19645 8192000000 10166193184 10578104320 1279268608 13.506 100 26 52 1 1290435296 5848880 5317808
23:35:12 11861 8192000000 10179782144 10586304512 1282368288 13.535 100 35 67 0 1296599824 7333296 6898240
23:36:24 13291 8192000000 10187970224 10600124416 1294010656 13.641 100 26 53 0 1305854592 5819776 6024160
23:37:38 11352 8192000000 10200395152 10610909184 1302683776 13.720 100 20 40 1 1312496992 4906336 4906880
23:38:51 23140 8192000000 10228533968 10640707584 1329198912 13.960 100 41 81 2 1343816192 4951584 9665696
23:40:18 47816 8192000000 10243710288 10664206336 1340645040 14.063 100 39 78 1 1359860736 10052240 9163456
23:42:13 15782 8192000000 10271747488 10686713856 1354667344 14.189 100 49 97 1 1375962000 10656496 10638160
23:43:48 13771 8192000000 10279157712 10701684736 1369425968 14.322 100 46 92 0 1395777168 15796752 10554448
23:45:06 10267 8192000000 10304210512 10721042432 1385896816 14.469 100 48 94 0 1412428736 15165744 11366176
23:46:31 32294 8192000000 10298025840 10722557952 1378257952 14.401 99 37 72 1 1405424288 18624352 8541984
23:48:10 5726 8192000000 10300119632 10723258368 1383801344 14.451 99 44 87 0 1413542560 19385888 10355328
23:49:21 9477 8192000000 10314119248 10737741824 1382802592 14.442 99 47 94 1 1417185808 23029840 11353376
23:50:36 6207 8192000000 10319618224 10731855872 1374996048 14.372 99 65 127 0 1417951552 27664656 15290848
23:51:47 2447 8192000000 10335144032 10743083008 1386747264 14.477 99 74 146 1 1440117024 36348864 17020896
23:52:57 2592 8192000000 10333560496 10737885184 1381124112 14.427 100 85 168 2 1439479696 37950336 20405248
23:54:05 3995 8192000000 10325551552 10727264256 1369001376 14.318 100 79 157 0 1425599744 37188832 19409536
23:55:16 7228 8192000000 10305443600 10715209728 1350596848 14.153 99 59 118 0 1401994032 36844192 14552992
23:56:50 1604 8192000000 10333126736 10739142656 1378094608 14.400 100 70 137 0 1437008336 40411744 18501984
23:58:00 4361 8192000000 10344154656 10752212992 1384838960 14.460 100 63 125 2 1446917376 46472448 15605968
23:59:09 1574 8192000000 10355615200 10758418432 1383672464 14.449 100 76 147 3 1456086768 52805056 19609248
00:00:18 4415 8192000000 10357093440 10777468928 1402194992 14.615 100 53 106 0 1469854320 53527584 14131744
00:01:38 8407 8192000000 10348503744 10766159872 1391769520 14.522 100 53 106 2 1457628928 51255056 14604352
00:02:52 16647 8192000000 10346987664 10776772608 1397309648 14.571 100 37 72 2 1456523664 48824544 10389472
00:04:18 6033 8192000000 10342958176 10769440768 1382336688 14.437 100 44 87 0 1449084144 54168448 12579008
00:05:27 29004 8192000000 10322637040 10762752000 1373806560 14.361 99 28 55 1 1423822144 42330960 7684624
00:07:00 20823 8192000000 10325862112 10768453632 1384813904 14.460 100 29 58 0 1440558912 47603424 8141584
00:08:44 41111 8192000000 10310758512 10754338816 1343253184 14.087 98 23 46 0 1399692928 49931456 6508288
00:10:26 34507 8192000000 10287805024 10725031936 1356651712 14.207 99 30 61 1 1375365536 11751152 6962672
00:12:02 37794 8192000000 10297183280 10736947200 1383602096 14.449 100 17 34 0 1398401984 10936416 3863472
00:13:46 130096 8192000000 10305893104 10755735552 1383159440 14.445 99 19 38 0 1398652016 10992016 4500560
00:16:59 63137 8192000000 10313172704 10762465280 1398925232 14.585 100 51 100 1 1423419488 12964896 11529360
00:19:05 48065 8192000000 10307399296 10754592768 1371075680 14.337 98 25 47 1 1382651232 6643152 4932400
00:21:21 56589 8192000000 10300752368 10758230016 1391243824 14.517 100 20 40 0 1409213168 13185920 4783424
00:23:24 64062 8192000000 10323993424 10778886144 1394128592 14.543 99 22 44 0 1412130464 12550464 5451408
00:25:29 49699 8192000000 10323323056 10787401728 1408311968 14.669 100 40 80 1 1438746480 20772720 9661792
00:27:20 50101 8192000000 10311442096 10784083968 1390140064 14.507 99 14 29 0 1413185552 19323824 3721664
00:29:18 64610 8192000000 10305574288 10766336000 1390137008 14.507 99 15 30 0 1409068032 15657888 3273136
00:31:28 104097 8192000000 10316224736 10782220288 1397509456 14.573 99 14 28 0 1411057456 10409360 3138640
00:34:30 49828 8192000000 10294404608 10782400512 1391986880 14.524 100 7 14 0 1418351504 24363024 2001600
00:36:20 41085 8192000000 10279962496 10741932032 1361461856 14.250 98 7 14 0 1375457648 12763424 1232368
00:38:04 90396 8192000000 10259568640 10728787968 1319930192 13.876 95 12 22 0 1331524464 9559568 2034704
00:40:42 35091 8192000000 10249829376 10708033536 1309054128 13.777 96 21 41 0 1321927296 8650080 4223088
00:42:26 68177 8192000000 10247058016 10696302592 1300998272 13.704 94 17 34 0 1320288432 15341920 3948240
00:45:03 50519 8192000000 10250282544 10711486464 1316560272 13.846 97 13 26 0 1337294624 17359392 3374960
00:46:54 30367 8192000000 10261297968 10696654848 1293213184 13.633 95 34 67 0 1314949568 13645808 8090576
00:48:27 38698 8192000000 10251673216 10694057984 1287669840 13.583 95 24 48 0 1301229424 8354416 5205168
00:50:06 67553 8192000000 10242851632 10685435904 1295453536 13.654 96 18 35 0 1312113872 12976784 3683552
00:52:23 37759 8192000000 10258830192 10690686976 1310699168 13.792 97 33 67 0 1327224496 9281200 7244128
00:54:05 41989 8192000000 10244869568 10697179136 1326150240 13.932 99 25 50 1 1348318496 16448752 5719504
00:55:53 51148 8192000000 10262519120 10695266304 1325800192 13.929 98 30 60 0 1344555744 12222736 6532816
00:57:48 14934 8192000000 10261818752 10708529152 1349457632 14.143 100 37 73 1 1369707520 12043360 8206528
00:59:03 35187 8192000000 10256852336 10721652736 1364073520 14.274 100 16 32 0 1376982768 8818944 4090304
01:00:54 87983 8192000000 10287405232 10741379072 1383208544 14.445 100 13 26 0 1397841040 11725392 2907104
01:04:31 46613 8192000000 10275760960 10737958912 1358823392 14.227 98 12 24 0 1370213696 9059936 2330368
01:06:21 60194 8192000000 10286420720 10749349888 1385739360 14.468 100 12 24 0 1405315152 16688688 2887104
01:08:23 55818 8192000000 10302160144 10762272768 1383046544 14.444 99 22 44 0 1396585968 8984848 4554576
01:10:19 54247 8192000000 10322179520 10778361856 1399142880 14.587 99 40 80 0 1420779232 12421584 9214768
01:12:13 62102 8192000000 10322850800 10775986176 1412467408 14.706 100 17 34 0 1430133264 14000720 3665136
01:14:17 133383 8192000000 10322598784 10781048832 1390887104 14.514 98 9 18 0 1405968192 13029632 2051456
01:17:31 99744 8192000000 10296061344 10765209600 1307774368 13.766 93 16 32 0 1326725936 15373968 3577600
01:20:25 120053 8192000000 10266286016 10723270656 1345949344 14.111 97 23 46 1 1358106448 7067760 5089344
01:23:25 66534 8192000000 10285634096 10751111168 1377392048 14.393 99 15 30 0 1388448192 7608176 3447968
01:25:38 58166 8192000000 10285761072 10744631296 1354720336 14.190 97 15 30 0 1369216352 11072032 3423984
01:28:01 77443 8192000000 10292090512 10756644864 1333128816 13.995 95 21 42 1 1354099040 15967680 5002544
01:30:19 46695 8192000000 10280589344 10744045568 1356215632 14.203 98 8 16 0 1373338464 15107168 2015664
01:32:34 30148 8192000000 10280170704 10718339072 1336410608 14.025 97 19 38 0 1348055712 7289856 4355248
01:34:08 33539 8192000000 10258677984 10707406848 1309859376 13.785 96 33 66 0 1330578496 13507648 7211472
01:35:44 30116 8192000000 10279788192 10728943616 1347701392 14.127 99 42 84 0 1369374944 12581024 9092528
01:37:17 48292 8192000000 10282854912 10728878080 1332630320 13.991 97 29 58 1 1348906528 9970720 6305488

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 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 05-03-2023 01:40:17 to 05-03-2023 01:40:19.

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          257311
MON$OLDEST_ACTIVE               257312
MON$OLDEST_SNAPSHOT             257312
MON$NEXT_TRANSACTION            257312
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                       2051232
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 ---
--- end of diff output ---

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

05.03.23 01:40:20. 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-05 01:40:23 No severe PSQL-related problems occured
05.03.23 01:40:24. End of report.