Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 29.03.2022 01:40
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 16733048 kB
MemAvailable 31653552 kB
Buffers 0 kB
Cached 15017924 kB
SwapCached 2188 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 3.0.10
DB_NAME                         /home/bases/oltp30-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp30-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp30-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb30/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3333
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp30-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp30
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2022-03-29 01:38:13
DTS_END                         2022-03-29 01:38:13.6990
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/37638
TRN_ID                          239238
ATT_ID                          723
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: 9345.40 22830 1121448
customer order: creation 170.40 344 20448
customer order: refuse 14.66 322 1759
order to supplier: creation 45.31 1262 5437
order to supplier: removal 3.83 85796 460
invoice (draft): creation 45.47 3300 5456
invoice (draft): removal 54.24 670 6509
invoice accept: apply 40.34 15618 4841
invoice accept: cancel 49.32 663 5918
customer reserve: creation 3777.12 151 453254
customer reserve: removal 4841.79 54 581015
realization accept: apply 82.25 161 9870
realization accept: cancel 92.28 93 11074
payment from customer: creation 59.73 127 7168
payment from customer: removal 5.63 130 676
payment to supplier: creation 48.00 140 5760
payment to supplier: removal 3.44 57 413
service: total inventory turnovers 4.38 1161 526
service: total monetary turnovers 6.33 20 759
service: aggregate perf. data 0.84 20394 101
service: refresh index statistics 0.03 326141 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 29-03-2022 01:40:32 to 29-03-2022 01:40:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5631
TEST_TIME 2 3097
TEST_TIME 3 2946
TEST_TIME 4 2709
TEST_TIME 5 3329
TEST_TIME 6 3645
TEST_TIME 7 4489
TEST_TIME 8 4873
TEST_TIME 9 4772
TEST_TIME 10 5020
TEST_TIME 11 4971
TEST_TIME 12 5305
TEST_TIME 13 5601
TEST_TIME 14 6097
TEST_TIME 15 6311
TEST_TIME 16 6782
TEST_TIME 17 6860
TEST_TIME 18 7085
TEST_TIME 19 7749
TEST_TIME 20 8093
TEST_TIME 21 8163
TEST_TIME 22 8772
TEST_TIME 23 8613
TEST_TIME 24 9555
TEST_TIME 25 9883
TEST_TIME 26 10182
TEST_TIME 27 10423
TEST_TIME 28 10404
TEST_TIME 29 10258
TEST_TIME 30 10217
TEST_TIME 31 10125
TEST_TIME 32 10234
TEST_TIME 33 10482
TEST_TIME 34 10391
TEST_TIME 35 10111
TEST_TIME 36 10040
TEST_TIME 37 10003
TEST_TIME 38 9954
TEST_TIME 39 9872
TEST_TIME 40 9820
TEST_TIME 41 9688
TEST_TIME 42 9758
TEST_TIME 43 9740
TEST_TIME 44 9708
TEST_TIME 45 9736
TEST_TIME 46 9683
TEST_TIME 47 9667
TEST_TIME 48 9595
TEST_TIME 49 9465
TEST_TIME 50 9543
TEST_TIME 51 9568
TEST_TIME 52 9417
TEST_TIME 53 9320
TEST_TIME 54 9255
TEST_TIME 55 9286
TEST_TIME 56 9323
TEST_TIME 57 9261
TEST_TIME 58 9355
TEST_TIME 59 9387
TEST_TIME 60 9360
TEST_TIME 61 9370
TEST_TIME 62 9508
TEST_TIME 63 9596
TEST_TIME 64 9668
TEST_TIME 65 9661
TEST_TIME 66 9797
TEST_TIME 67 9751
TEST_TIME 68 9818
TEST_TIME 69 9926
TEST_TIME 70 9974
TEST_TIME 71 9841
TEST_TIME 72 9794
TEST_TIME 73 9797
TEST_TIME 74 9739
TEST_TIME 75 9710
TEST_TIME 76 9650
TEST_TIME 77 9653
TEST_TIME 78 9614
TEST_TIME 79 9567
TEST_TIME 80 9557
TEST_TIME 81 9533
TEST_TIME 82 9493
TEST_TIME 83 9502
TEST_TIME 84 9475
TEST_TIME 85 9541
TEST_TIME 86 9569
TEST_TIME 87 9572
TEST_TIME 88 9577
TEST_TIME 89 9553
TEST_TIME 90 9611
TEST_TIME 91 9651
TEST_TIME 92 9666
TEST_TIME 93 9686
TEST_TIME 94 9708
TEST_TIME 95 9620
TEST_TIME 96 9603
TEST_TIME 97 9574
TEST_TIME 98 9514
TEST_TIME 99 9469
TEST_TIME 100 9459
TEST_TIME 101 9419
TEST_TIME 102 9399
TEST_TIME 103 9403
TEST_TIME 104 9400
TEST_TIME 105 9419
TEST_TIME 106 9455
TEST_TIME 107 9461
TEST_TIME 108 9452
TEST_TIME 109 9515
TEST_TIME 110 9515
TEST_TIME 111 9559
TEST_TIME 112 9538
TEST_TIME 113 9560
TEST_TIME 114 9506
TEST_TIME 115 9514
TEST_TIME 116 9490
TEST_TIME 117 9481
TEST_TIME 118 9441
TEST_TIME 119 9415
TEST_TIME 120 9349

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 29-03-2022 01:40:35 to 29-03-2022 01:40:39.

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 1543122 1543122 0 0.00 0 21004 5 0 0
doc_list_biud 1543121 1543121 0 0.00 0 65943 25 0 0
sp_add_invoice_to_stock 4969 4841 128 2.57 14 188472 15619 0 128
sp_cancel_adding_invoice 5918 5918 0 0.00 5 49537 664 0 0
sp_cancel_client_order 1759 1759 0 0.00 4 8085 323 0 0
sp_cancel_customer_reserve 581015 581015 0 0.00 1 65947 54 0 0
sp_cancel_pay_from_customer 676 676 0 0.00 3 17644 130 0 0
sp_cancel_pay_to_supplier 413 413 0 0.00 3 1947 58 0 0
sp_cancel_supplier_invoice 6518 6509 9 0.13 2 43591 670 0 9
sp_cancel_supplier_order 460 460 0 0.00 8 1121954 85797 0 0
sp_cancel_write_off 11074 11074 0 0.00 1 17939 94 0 0
sp_client_order 20448 20448 0 0.00 3 15667 344 0 0
sp_customer_reserve 453254 453254 0 0.00 0 36988 152 0 0
sp_fill_shopping_cart 31334 31334 0 0.00 1 16806 40 0 0
sp_fill_shopping_cart_clo_res 453249 453249 0 0.00 0 19854 14 0 0
sp_get_clo_for_invoice 4835 4835 0 0.00 0 35701 323 0 0
sp_kill_cost_storno 18081 18081 0 0.00 0 18076 53 0 0
sp_kill_qstorno_ret_qs2qd 1180073 1180073 0 0.00 0 65942 29 0 0
sp_kill_qty_storno 621438 621438 0 0.00 0 65943 62 0 0
sp_lock_selected_doc 20511 20511 0 0.00 0 8056 7 0 0
sp_make_cost_storno 27632 27632 0 0.00 0 20996 59 0 0
sp_multiply_rows_for_pdistr 27631 27631 0 0.00 0 17733 31 0 0
sp_multiply_rows_for_qdistr 20448 20448 0 0.00 0 11413 164 0 0
sp_pay_from_customer 7168 7168 0 0.00 4 18037 128 0 0
sp_pay_to_supplier 5760 5760 0 0.00 4 21014 140 0 0
sp_payment_common 12928 12928 0 0.00 2 17671 36 0 0
sp_qd_handle_on_cancel_clo 1759 1759 0 0.00 0 6934 288 0 0
sp_qd_handle_on_invoice_upd_sts 10751 10751 0 0.00 0 34431 293 0 0
sp_qd_handle_on_reserve_upd_sts 9870 9870 0 0.00 0 11992 48 0 0
sp_reserve_write_off 9870 9870 0 0.00 6 17773 161 0 0
sp_supplier_invoice 5456 5456 0 0.00 6 112864 3300 0 0
sp_supplier_order 5437 5437 0 0.00 5 37419 1263 0 0
srv_aggregate_perf_data 101 101 0 0.00 2619 250036 20394 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 743 157155 50607 0 0
srv_find_qd_qs_mism 1075276 1075276 0 0.00 0 17858 4 0 0
srv_make_invnt_saldo 526 526 0 0.00 49 48646 1161 0 0
srv_make_money_saldo 759 759 0 0.00 1 1635 20 0 0
srv_recalc_idx_stat 4 4 0 0.00 271690 432279 326141 0 0
t$perf-abend:sp_add_invoice_to_stock 128 128 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 4841 4841 0 0.00 0 28997 189 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 5594 96 0 0
t$perf-norm:sp_cancel_client_order 1759 1759 0 0.00 0 4246 12 0 0
t$perf-norm:sp_cancel_customer_reserve 697 697 0 0.00 0 4774 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 676 676 0 0.00 0 4528 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 413 413 0 0.00 0 1884 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 1646 28 0 0
t$perf-norm:sp_cancel_supplier_order 460 460 0 0.00 0 29794 1383 0 0
t$perf-norm:sp_cancel_write_off 984 984 0 0.00 0 1498 7 0 0
t$perf-norm:sp_client_order 20448 20448 0 0.00 0 22448 20 0 0
t$perf-norm:sp_customer_reserve 990 990 0 0.00 0 9578 18 0 0
t$perf-norm:sp_pay_from_customer 7168 7168 0 0.00 0 5531 10 0 0
t$perf-norm:sp_pay_to_supplier 5760 5760 0 0.00 0 13306 19 0 0
t$perf-norm:sp_reserve_write_off 9870 9870 0 0.00 0 8516 13 0 0
t$perf-norm:sp_supplier_invoice 5456 5456 0 0.00 0 5620 15 0 0
t$perf-norm:sp_supplier_order 5437 5437 0 0.00 0 6153 17 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 206 5 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 4 0 0 0
t$perf-norm:srv_make_invnt_saldo 526 526 0 0.00 0 1425 21 0 0
t$perf-norm:srv_make_money_saldo 759 759 0 0.00 0 5842 20 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 9922 9666 256 2.58 0 4915 16 0 256
v_all_customers 37884 37884 0 0.00 0 25 0 0 0
v_all_suppliers 30302 30302 0 0.00 0 113 1 0 0
v_all_wares 40896 40896 0 0.00 0 213 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 1435 8 0 0
v_cancel_client_order 3518 3518 0 0.00 0 3778 5 0 0
v_cancel_customer_prepayment 1352 1352 0 0.00 0 2449 9 0 0
v_cancel_customer_reserve 1394 1394 0 0.00 0 333 3 0 0
v_cancel_payment_to_supplier 826 826 0 0.00 0 1922 7 0 0
v_cancel_supplier_invoice 818 800 18 2.20 0 2901 24 0 18
v_cancel_supplier_order 920 920 0 0.00 0 226 2 0 0
v_cancel_write_off 1968 1968 0 0.00 0 5384 17 0 0
v_max_id_clo_ord 5437 5437 0 0.00 0 425 1 0 0
v_max_id_clo_res 990 990 0 0.00 0 2717 19 0 0
v_max_id_ord_sup 5447 5447 0 0.00 0 5217 4 0 0
v_max_non_paid_invoice 1493 1493 0 0.00 0 328 2 0 0
v_max_non_paid_realizn 3551 3551 0 0.00 0 635 3 0 0
v_min_id_clo_ord 5437 5437 0 0.00 0 11274 13 0 0
v_min_id_clo_res 990 990 0 0.00 0 3848 10 0 0
v_min_id_ord_sup 5447 5447 0 0.00 0 4951 7 0 0
v_min_non_paid_invoice 5760 5760 0 0.00 0 7368 19 0 0
v_min_non_paid_realizn 7168 7168 0 0.00 0 17667 30 0 0
v_our_firm 10246 10246 0 0.00 0 15 1 0 0
v_reserve_write_off 19740 19740 0 0.00 0 3153 6 0 0
x_lock_depdocs_on_canc_invoice 12027 12027 0 0.00 0 10820 99 0 0
x_lock_depdocs_on_canc_sup_ord 460 460 0 0.00 0 25535 410 0 0
x_make_qstorno_1000_1200 5437 5437 0 0.00 1 36040 1184 0 0
x_make_qstorno_1200_2000 5456 5456 0 0.00 3 112787 3157 0 0
x_make_qstorno_2100_3300 453254 453254 0 0.00 0 35924 135 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 29-03-2022 01:40:39 to 29-03-2022 01:40:44.

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:17 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
23:09:18 14180 8192000000 9897838208 10211094528 986593168 10.748 100 82 165 0 1014239712 6035120 21611424
23:10:41 21235 8192000000 9962844832 10286731264 1040610800 11.271 100 91 182 0 1072469488 8153744 23704944
23:12:04 18233 8192000000 9983575264 10318344192 1067634240 11.529 100 79 158 1 1097650688 9814704 20201744
23:13:27 15851 8192000000 10043388896 10389733376 1120549792 12.032 100 83 166 0 1152247712 11905856 19792064
23:14:46 9385 8192000000 10072591456 10429583360 1162774528 12.429 100 78 157 0 1192852320 12213680 17864112
23:15:58 5234 8192000000 10118679184 10487738368 1210647328 12.875 100 82 164 1 1241326656 12391712 18287616
23:17:06 4573 8192000000 10158807200 10532065280 1243545968 13.179 100 87 174 2 1275875184 13207136 19122080
23:18:12 4747 8192000000 10175044432 10555269120 1263059168 13.358 100 85 169 0 1295902672 14375472 18468032
23:19:18 3063 8192000000 10212802096 10597048320 1295094352 13.651 100 92 184 1 1328853584 12958880 20800352
23:20:25 6369 8192000000 10231742704 10622324736 1317469360 13.854 100 75 150 0 1345976848 12110352 16397136
23:21:34 5953 8192000000 10210276624 10622492672 1324729440 13.920 100 47 92 1 1343123904 6297808 12096656
23:22:40 4394 8192000000 10221090704 10639503360 1334398128 14.007 100 20 41 1 1349099696 10635408 4066160
23:23:47 10749 8192000000 10225875088 10647003136 1339684576 14.055 100 29 57 0 1352308656 6456912 6167168
23:24:59 14668 8192000000 10233959616 10652487680 1342845216 14.083 100 22 44 1 1354423040 5505648 6072176
23:26:18 2831 8192000000 10266982848 10666897408 1352608352 14.171 100 88 174 1 1379663840 6501840 20553648
23:27:21 4438 8192000000 10232422240 10650677248 1342165392 14.077 100 31 60 1 1356308368 7350784 6792192
23:28:26 6392 8192000000 10215567248 10635206656 1318805584 13.866 99 25 50 0 1330683184 6144880 5732720
23:29:34 58103 8192000000 10202313280 10598887424 1273312608 13.452 97 49 98 0 1290736000 5686720 11736672
23:31:32 6466 8192000000 10166796816 10568617984 1262627456 13.354 99 39 78 0 1278384080 6493536 9263088
23:32:41 8385 8192000000 10145213040 10546737152 1243516784 13.179 99 26 53 1 1255636416 5842576 6277056
23:33:50 64739 8192000000 10157556000 10566152192 1249923520 13.238 98 27 50 4 1260762592 5020976 5818096
23:35:57 6302 8192000000 10179724112 10588762112 1287584480 13.582 100 28 56 0 1300778384 6385312 6808592
23:37:07 4533 8192000000 10196868032 10607230976 1301619120 13.710 100 35 72 3 1315341008 5286208 8435680
23:38:17 40610 8192000000 10225086384 10637135872 1321462816 13.890 100 29 57 1 1341917312 13636848 6817648
23:40:00 120662 8192000000 10241112960 10664738816 1336588624 14.027 99 35 69 1 1352117424 7646768 7882032
23:43:21 56572 8192000000 10276383904 10697371648 1367075632 14.301 100 35 68 2 1389357056 14120352 8161072
23:45:29 10222 8192000000 10302742176 10723491840 1386138624 14.471 100 54 105 1 1413207872 14762928 12306320
23:46:47 12553 8192000000 10329143616 10748911616 1403256800 14.624 100 56 112 0 1433216112 16333600 13625712
23:48:09 11801 8192000000 10339600752 10762285056 1411008720 14.693 100 56 112 1 1447496016 23299424 13187872
23:49:27 15344 8192000000 10354880896 10781290496 1425838880 14.824 100 55 109 2 1465083200 24592832 14651488
23:50:47 6196 8192000000 10359800912 10786983936 1426751280 14.833 100 57 111 2 1471260528 29762272 14746976
23:52:08 6234 8192000000 10377420400 10801246208 1439622832 14.946 100 62 124 2 1478350160 24470400 14256928
23:53:18 4882 8192000000 10387183040 10804998144 1439297488 14.943 100 80 158 0 1492210816 34009424 18903904
23:54:29 3883 8192000000 10365388848 10776231936 1413765328 14.717 100 73 146 1 1467845584 35258064 18822192
23:55:39 1360 8192000000 10370242848 10778374144 1413756016 14.717 100 86 169 2 1471828384 37685616 20386752
23:56:45 743 8192000000 10373483632 10780848128 1408116608 14.667 100 82 164 0 1474228064 45775984 20335472
23:57:57 10200 8192000000 10339328336 10764816384 1393796944 14.540 100 38 76 1 1444569216 40574144 10198128
23:59:12 11122 8192000000 10331006480 10759151616 1390023296 14.506 100 43 86 0 1440314784 39197040 11094448
00:00:39 14565 8192000000 10308683824 10749243392 1366282816 14.294 100 27 54 0 1419001200 44622240 8096144
00:01:59 19571 8192000000 10296910608 10731769856 1341670048 14.072 99 30 60 0 1396769936 46601856 8498032
00:03:30 52395 8192000000 10251426656 10696908800 1321248944 13.888 99 14 28 0 1352008576 27013936 3745696
00:05:23 112979 8192000000 10252464176 10696122368 1323985824 13.913 98 16 31 2 1338823408 11094144 3743440
00:08:17 36586 8192000000 10256341504 10692861952 1329295216 13.961 98 11 22 0 1338889728 7363632 2230880
00:09:55 76695 8192000000 10269133248 10715844608 1329591360 13.963 98 10 20 0 1353078608 21328656 2158592
00:13:24 75902 8192000000 10291180704 10733150208 1372346256 14.348 100 29 57 0 1386350624 7848944 6155424
00:15:48 30989 8192000000 10276352112 10732220416 1360385712 14.241 99 23 46 0 1380441936 14970976 5085248
00:17:21 46831 8192000000 10299191728 10749366272 1388070416 14.489 100 35 70 2 1409550880 13635888 7844576
00:19:18 74837 8192000000 10309479088 10756263936 1377608448 14.395 99 12 24 1 1400199440 19516096 3074896
00:21:33 67659 8192000000 10267711472 10720350208 1350055664 14.148 97 16 32 0 1366410672 12342000 4013008
00:23:41 56929 8192000000 10295635184 10753552384 1395529520 14.555 100 10 20 0 1407230992 9459664 2241808
00:25:44 36309 8192000000 10301390320 10757140480 1390336016 14.509 99 29 58 2 1403492368 6580608 6575744
00:27:21 28040 8192000000 10315162336 10764247040 1406769360 14.655 100 30 60 1 1424475184 10586432 7119392
00:28:50 32960 8192000000 10304107216 10767884288 1402375232 14.616 100 8 16 1 1413264128 8799408 2089488
00:30:27 46941 8192000000 10301173904 10764103680 1405803776 14.647 100 10 20 1 1415856256 7994608 2057872
00:32:20 52501 8192000000 10307910304 10778370048 1412284608 14.704 100 6 12 1 1422615600 9100128 1230864
00:34:13 47933 8192000000 10303502816 10771574784 1394774896 14.548 99 6 12 0 1405413872 9478608 1160368
00:36:06 64283 8192000000 10310053280 10786103296 1384351088 14.455 98 24 48 0 1406571776 16890816 5329872
00:38:11 30035 8192000000 10322206896 10776768512 1391450272 14.519 98 36 72 0 1413842976 14392752 7999952
00:39:52 73582 8192000000 10288742608 10748387328 1340577536 14.063 96 11 22 1 1359110304 16354400 2178368
00:42:06 48114 8192000000 10277325696 10740862976 1323913104 13.912 95 14 28 0 1341743120 14589184 3240832
00:44:01 69943 8192000000 10266288496 10709884928 1271040048 13.431 92 24 48 0 1286632128 10536656 5055424
00:46:14 93952 8192000000 10286130768 10731941888 1323344112 13.907 97 9 18 0 1341874416 16712576 1817728
00:48:48 76190 8192000000 10274148016 10707988480 1326738176 13.938 98 6 12 0 1342766048 14635264 1392608
00:51:27 31069 8192000000 10253734880 10713427968 1349429040 14.142 100 16 32 0 1366017120 13278896 3309184
00:53:01 69297 8192000000 10237915168 10687266816 1283457360 13.545 95 13 26 0 1296840912 10723920 2659632
00:55:11 55659 8192000000 10238542656 10689642496 1323596896 13.909 98 14 28 1 1334696256 7851424 3247936
00:57:46 89934 8192000000 10262181040 10719752192 1333710000 14.001 98 16 32 0 1345493824 8169856 3613968
01:00:21 49878 8192000000 10260279664 10714480640 1359461616 14.233 100 13 26 0 1372169856 9747680 2960560
01:02:23 76112 8192000000 10278832528 10732490752 1352617856 14.171 98 15 30 0 1371421968 15228128 3575984
01:04:40 69265 8192000000 10273671360 10722443264 1348343888 14.133 99 17 34 1 1361814976 9801920 3669168
01:06:50 43498 8192000000 10297649392 10745434112 1383309712 14.446 100 39 78 3 1404090512 12199840 8580960
01:08:33 123335 8192000000 10298200128 10754568192 1362912464 14.263 98 11 22 0 1384073168 18730224 2430480
01:11:38 157154 8192000000 10302482832 10762121216 1371239264 14.338 98 9 18 0 1387863376 14713120 1910992
01:16:23 45781 8192000000 10277352160 10745434112 1352176800 14.167 97 7 14 0 1366208832 12748720 1283312
01:18:45 68913 8192000000 10301044880 10751340544 1382468112 14.439 99 18 35 1 1394423952 7954496 4001344
01:20:56 49928 8192000000 10312000944 10765615104 1397728384 14.575 100 26 52 0 1415580336 11176416 6675536
01:23:00 91127 8192000000 10317071984 10765783040 1384564768 14.457 99 17 34 1 1404235856 15464496 4206592
01:25:41 57182 8192000000 10328180288 10788691968 1411317408 14.696 100 12 24 0 1430378176 16384416 2676352
01:27:41 32569 8192000000 10312350272 10757947392 1353804240 14.182 96 36 72 0 1370758448 8148752 8805456
01:29:20 59517 8192000000 10288570224 10735095808 1306538624 13.755 94 18 36 0 1319701088 9321568 3840896
01:31:20 30386 8192000000 10265689312 10714312704 1326778848 13.938 98 20 40 0 1349095104 18007552 4308704
01:32:55 100505 8192000000 10257308528 10708541440 1318858160 13.866 97 33 66 1 1337162896 9960896 8343840
01:35:36 68047 8192000000 10237012864 10700840960 1313581952 13.819 97 10 20 0 1324594544 8790048 2222544
01:37:44 38659 8192000000 9186062256 9449836544 346567744 4.058 27 6 11 0 355831520 7948368 1315408

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 29-03-2022 01:40:45 to 29-03-2022 01:41:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 128 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 29-03-2022 01:41:01 to 29-03-2022 01:41:03.

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          239082
MON$OLDEST_ACTIVE               239639
MON$OLDEST_SNAPSHOT             239639
MON$NEXT_TRANSACTION            239639
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                       2032352
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.10.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:657: 
:658: mscs-test-161.budzdorov.ru    Tue Mar 29 01:38:25 2022
:659:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:660: 
:661: 
:662: mscs-test-161.budzdorov.ru    Tue Mar 29 01:38:36 2022
:663:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:664: 
:665: 
:666: mscs-test-161.budzdorov.ru    Tue Mar 29 01:38:38 2022
:667:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:668: 
--- end of diff output ---

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

29.03.22 01:41:04. There are 3 logs in /var/tmp/logs-oltp30 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2022-03-29 01:41:06 No severe PSQL-related problems occured
29.03.22 01:41:08. End of report.