Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.07.2022 22:38
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 16044484 kB
MemAvailable 31005080 kB
Buffers 0 kB
Cached 15650292 kB
SwapCached 2552 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 2022-07-26 22:38:23
DTS_END                         2022-07-26 22:38:23.7210
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/47402
TRN_ID                          248087
ATT_ID                          672
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: 9352.79 23060 1122335
customer order: creation 175.01 363 21001
customer order: refuse 14.86 357 1783
order to supplier: creation 45.83 1355 5499
order to supplier: removal 3.45 94205 414
invoice (draft): creation 47.09 3341 5651
invoice (draft): removal 52.73 698 6328
invoice accept: apply 42.39 18107 5087
invoice accept: cancel 47.44 806 5693
customer reserve: creation 3960.88 176 475305
customer reserve: removal 4669.78 55 560374
realization accept: apply 85.30 173 10236
realization accept: cancel 77.08 116 9250
payment from customer: creation 59.72 102 7166
payment from customer: removal 5.81 70 697
payment to supplier: creation 49.58 119 5949
payment to supplier: removal 3.57 95 428
service: total inventory turnovers 4.91 1049 589
service: total monetary turnovers 6.53 38 784
service: aggregate perf. data 0.81 19028 97
service: refresh index statistics 0.03 320948 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 26-07-2022 22:39:18 to 26-07-2022 22:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2456
TEST_TIME 2 1934
TEST_TIME 3 2897
TEST_TIME 4 3649
TEST_TIME 5 4281
TEST_TIME 6 4561
TEST_TIME 7 4592
TEST_TIME 8 4988
TEST_TIME 9 5134
TEST_TIME 10 5353
TEST_TIME 11 5546
TEST_TIME 12 5601
TEST_TIME 13 6087
TEST_TIME 14 6309
TEST_TIME 15 6486
TEST_TIME 16 6919
TEST_TIME 17 7171
TEST_TIME 18 7255
TEST_TIME 19 7346
TEST_TIME 20 7952
TEST_TIME 21 8272
TEST_TIME 22 8617
TEST_TIME 23 8882
TEST_TIME 24 9115
TEST_TIME 25 9238
TEST_TIME 26 10271
TEST_TIME 27 10056
TEST_TIME 28 10016
TEST_TIME 29 9951
TEST_TIME 30 9691
TEST_TIME 31 9814
TEST_TIME 32 9818
TEST_TIME 33 9576
TEST_TIME 34 9464
TEST_TIME 35 9359
TEST_TIME 36 9473
TEST_TIME 37 9642
TEST_TIME 38 9527
TEST_TIME 39 9553
TEST_TIME 40 9582
TEST_TIME 41 9414
TEST_TIME 42 9338
TEST_TIME 43 9447
TEST_TIME 44 9399
TEST_TIME 45 9507
TEST_TIME 46 9531
TEST_TIME 47 9388
TEST_TIME 48 9397
TEST_TIME 49 9451
TEST_TIME 50 9698
TEST_TIME 51 9765
TEST_TIME 52 9707
TEST_TIME 53 9799
TEST_TIME 54 9925
TEST_TIME 55 9904
TEST_TIME 56 9986
TEST_TIME 57 9859
TEST_TIME 58 9724
TEST_TIME 59 9691
TEST_TIME 60 9633
TEST_TIME 61 9656
TEST_TIME 62 9565
TEST_TIME 63 9492
TEST_TIME 64 9502
TEST_TIME 65 9459
TEST_TIME 66 9490
TEST_TIME 67 9465
TEST_TIME 68 9568
TEST_TIME 69 9529
TEST_TIME 70 9537
TEST_TIME 71 9517
TEST_TIME 72 9537
TEST_TIME 73 9578
TEST_TIME 74 9574
TEST_TIME 75 9625
TEST_TIME 76 9643
TEST_TIME 77 9655
TEST_TIME 78 9813
TEST_TIME 79 9730
TEST_TIME 80 9627
TEST_TIME 81 9564
TEST_TIME 82 9577
TEST_TIME 83 9505
TEST_TIME 84 9481
TEST_TIME 85 9461
TEST_TIME 86 9375
TEST_TIME 87 9390
TEST_TIME 88 9364
TEST_TIME 89 9348
TEST_TIME 90 9400
TEST_TIME 91 9410
TEST_TIME 92 9405
TEST_TIME 93 9478
TEST_TIME 94 9429
TEST_TIME 95 9459
TEST_TIME 96 9485
TEST_TIME 97 9564
TEST_TIME 98 9578
TEST_TIME 99 9552
TEST_TIME 100 9542
TEST_TIME 101 9635
TEST_TIME 102 9636
TEST_TIME 103 9591
TEST_TIME 104 9525
TEST_TIME 105 9499
TEST_TIME 106 9501
TEST_TIME 107 9460
TEST_TIME 108 9430
TEST_TIME 109 9415
TEST_TIME 110 9371
TEST_TIME 111 9330
TEST_TIME 112 9293
TEST_TIME 113 9365
TEST_TIME 114 9376
TEST_TIME 115 9356
TEST_TIME 116 9344
TEST_TIME 117 9402
TEST_TIME 118 9373
TEST_TIME 119 9416
TEST_TIME 120 9357

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-07-2022 22:39:22 to 26-07-2022 22:39:25.

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 1562715 1562715 0 0.00 0 29372 6 0 0
doc_list_biud 1562713 1562713 0 0.00 0 59515 25 0 0
sp_add_invoice_to_stock 5198 5087 111 2.13 26 153650 18108 0 111
sp_cancel_adding_invoice 5693 5693 0 0.00 4 80929 807 0 0
sp_cancel_client_order 1783 1783 0 0.00 4 11926 357 0 0
sp_cancel_customer_reserve 560374 560374 0 0.00 0 59516 56 0 0
sp_cancel_pay_from_customer 697 697 0 0.00 3 6006 70 0 0
sp_cancel_pay_to_supplier 428 428 0 0.00 3 5330 95 0 0
sp_cancel_supplier_invoice 6337 6328 9 0.14 1 14566 698 0 9
sp_cancel_supplier_order 414 414 0 0.00 8 1206341 94205 0 0
sp_cancel_write_off 9250 9250 0 0.00 1 12277 116 0 0
sp_client_order 21001 21001 0 0.00 3 55492 364 0 0
sp_customer_reserve 475305 475305 0 0.00 0 112785 177 0 0
sp_fill_shopping_cart 32138 32138 0 0.00 1 21312 36 0 0
sp_fill_shopping_cart_clo_res 475300 475300 0 0.00 0 45497 15 0 0
sp_get_clo_for_invoice 5081 5081 0 0.00 0 66590 390 0 0
sp_kill_cost_storno 16068 16068 0 0.00 0 8140 52 0 0
sp_kill_qstorno_ret_qs2qd 1136740 1136740 0 0.00 0 59511 30 0 0
sp_kill_qty_storno 599159 599159 0 0.00 0 59514 65 0 0
sp_lock_selected_doc 21256 21256 0 0.00 0 10625 6 0 0
sp_make_cost_storno 28432 28432 0 0.00 0 14274 42 0 0
sp_multiply_rows_for_pdistr 28432 28432 0 0.00 0 9105 24 0 0
sp_multiply_rows_for_qdistr 21001 21001 0 0.00 0 55485 163 0 0
sp_pay_from_customer 7166 7166 0 0.00 4 13063 102 0 0
sp_pay_to_supplier 5949 5949 0 0.00 4 14537 119 0 0
sp_payment_common 13115 13115 0 0.00 1 13051 30 0 0
sp_qd_handle_on_cancel_clo 1783 1783 0 0.00 0 11920 319 0 0
sp_qd_handle_on_invoice_upd_sts 10774 10774 0 0.00 0 41568 311 0 0
sp_qd_handle_on_reserve_upd_sts 10236 10236 0 0.00 0 22689 67 0 0
sp_reserve_write_off 10236 10236 0 0.00 5 22705 173 0 0
sp_supplier_invoice 5651 5651 0 0.00 5 95083 3341 0 0
sp_supplier_order 5499 5499 0 0.00 5 43210 1355 0 0
srv_aggregate_perf_data 97 97 0 0.00 2156 303937 19029 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1309 164937 45437 0 0
srv_find_qd_qs_mism 1073825 1073825 0 0.00 0 29371 6 0 0
srv_make_invnt_saldo 589 589 0 0.00 37 96906 1050 0 0
srv_make_money_saldo 784 784 0 0.00 0 12246 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 200708 457762 320948 0 0
t$perf-abend:sp_add_invoice_to_stock 111 111 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 5087 5087 0 0.00 0 18301 184 0 0
t$perf-norm:sp_cancel_adding_invoice 420 420 0 0.00 0 7111 112 0 0
t$perf-norm:sp_cancel_client_order 1783 1783 0 0.00 0 3590 15 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 716 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 697 697 0 0.00 0 10416 33 0 0
t$perf-norm:sp_cancel_pay_to_supplier 428 428 0 0.00 0 2982 12 0 0
t$perf-norm:sp_cancel_supplier_invoice 425 425 0 0.00 0 712 9 0 0
t$perf-norm:sp_cancel_supplier_order 414 414 0 0.00 0 23050 1563 0 0
t$perf-norm:sp_cancel_write_off 1092 1092 0 0.00 0 2639 12 0 0
t$perf-norm:sp_client_order 21001 21001 0 0.00 0 15818 17 0 0
t$perf-norm:sp_customer_reserve 1024 1024 0 0.00 0 2087 8 0 0
t$perf-norm:sp_pay_from_customer 7166 7166 0 0.00 0 7532 13 0 0
t$perf-norm:sp_pay_to_supplier 5949 5949 0 0.00 0 10539 12 0 0
t$perf-norm:sp_reserve_write_off 10236 10236 0 0.00 0 13001 14 0 0
t$perf-norm:sp_supplier_invoice 5651 5651 0 0.00 0 13693 21 0 0
t$perf-norm:sp_supplier_order 5499 5499 0 0.00 0 4500 16 0 0
t$perf-norm:srv_aggregate_perf_data 97 97 0 0.00 0 338 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 2719 59 0 0
t$perf-norm:srv_make_invnt_saldo 589 589 0 0.00 0 2533 27 0 0
t$perf-norm:srv_make_money_saldo 784 784 0 0.00 0 958 21 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 2 2 0 0
v_add_invoice_to_stock 10384 10162 222 2.13 0 5161 18 0 222
v_all_customers 38282 38282 0 0.00 0 55 1 0 0
v_all_suppliers 30984 30984 0 0.00 0 23 1 0 0
v_all_wares 41994 41994 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 840 840 0 0.00 0 125 6 0 0
v_cancel_client_order 3566 3566 0 0.00 0 2083 3 0 0
v_cancel_customer_prepayment 1394 1394 0 0.00 0 1788 5 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 1238 4 0 0
v_cancel_payment_to_supplier 856 856 0 0.00 0 5322 18 0 0
v_cancel_supplier_invoice 868 850 18 2.07 0 2839 24 0 18
v_cancel_supplier_order 828 828 0 0.00 0 1775 10 0 0
v_cancel_write_off 2184 2184 0 0.00 0 2589 15 0 0
v_max_id_clo_ord 5499 5499 0 0.00 0 1311 1 0 0
v_max_id_clo_res 1024 1024 0 0.00 0 1527 12 0 0
v_max_id_ord_sup 5642 5642 0 0.00 0 7744 8 0 0
v_max_non_paid_invoice 1596 1596 0 0.00 0 332 3 0 0
v_max_non_paid_realizn 3688 3688 0 0.00 0 1104 3 0 0
v_min_id_clo_ord 5499 5499 0 0.00 0 4162 5 0 0
v_min_id_clo_res 1024 1024 0 0.00 0 4477 15 0 0
v_min_id_ord_sup 5642 5642 0 0.00 0 11479 9 0 0
v_min_non_paid_invoice 5949 5949 0 0.00 0 9718 19 0 0
v_min_non_paid_realizn 7166 7166 0 0.00 0 13046 25 0 0
v_our_firm 10668 10668 0 0.00 0 54 1 0 0
v_reserve_write_off 20472 20472 0 0.00 0 10007 9 0 0
x_lock_depdocs_on_canc_invoice 11596 11596 0 0.00 0 14757 111 0 0
x_lock_depdocs_on_canc_sup_ord 414 414 0 0.00 0 12643 359 0 0
x_make_qstorno_1000_1200 5499 5499 0 0.00 1 43201 1291 0 0
x_make_qstorno_1200_2000 5651 5651 0 0.00 2 94984 3203 0 0
x_make_qstorno_2100_3300 475305 475305 0 0.00 0 112785 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-07-2022 22:39:26 to 26-07-2022 22:39:30.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
20:08:27 2 8192000000 8799010784 8810369024 5307904 0.064 2 1 2 0 5407504 56816 42784
20:09:28 22992 8192000000 9887778736 10192093184 965161280 10.539 100 78 156 0 991798864 5689616 20947968
20:10:56 22340 8192000000 9941943168 10259775488 1025247008 11.123 100 88 176 0 1056168064 8110768 22810288
20:12:22 15300 8192000000 9973983488 10304913408 1053165744 11.391 100 84 168 0 1084078176 9268816 21643616
20:13:44 8999 8192000000 10037801520 10384506880 1121934208 12.045 100 84 167 1 1152456736 11194768 19327760
20:15:00 6842 8192000000 10092031120 10449752064 1179112832 12.582 100 81 160 1 1210200160 13235696 17851632
20:16:08 5424 8192000000 10124759888 10491228160 1215753776 12.922 100 74 147 1 1244852400 12848256 16250368
20:17:15 2089 8192000000 10163850752 10539016192 1251726608 13.254 100 89 176 1 1284424640 13109632 19588400
20:18:19 1311 8192000000 10199564160 10578800640 1284768880 13.557 100 88 177 1 1317759152 13498752 19491520
20:19:22 1534 8192000000 10210341280 10597457920 1301765856 13.711 100 91 183 2 1332879552 10964672 20149024
20:20:26 3683 8192000000 10202908688 10603778048 1307831120 13.766 100 41 82 1 1325075024 8246800 8997104
20:21:33 11429 8192000000 10206537520 10622922752 1326098304 13.932 100 21 42 0 1337668848 6977904 4592640
20:22:50 15307 8192000000 10221733136 10636099584 1335292128 14.015 100 37 76 0 1350694592 6595504 8806960
20:24:08 26299 8192000000 10224739968 10649055232 1340556208 14.062 100 22 44 0 1351403712 5474448 5373056
20:25:35 6052 8192000000 10235824784 10661945344 1344520192 14.098 100 31 62 0 1358184000 6692368 6971440
20:26:44 9445 8192000000 10219760416 10645962752 1317650288 13.855 98 20 38 1 1327267312 5052176 4564848
20:27:54 6414 8192000000 10223995008 10644914176 1334414944 14.007 100 21 43 2 1344343728 5347008 4581776
20:29:03 31352 8192000000 10163999808 10557554688 1257486176 13.307 97 49 97 1 1274148576 4882048 11780352
20:30:38 6173 8192000000 10143932752 10545745920 1237657216 13.125 98 24 49 0 1248572928 5545664 5370048
20:31:48 5030 8192000000 10129222432 10525851648 1229069200 13.045 99 24 48 0 1240016144 5174448 5772496
20:32:55 5478 8192000000 10163819056 10567340032 1270542688 13.427 100 20 40 0 1281097328 5736192 4818448
20:34:04 30155 8192000000 10172687472 10587865088 1283320224 13.543 100 22 44 0 1293516176 5001568 5194384
20:35:36 6536 8192000000 10194464896 10603638784 1297237200 13.670 100 19 38 0 1306990720 5641072 4112448
20:36:44 10427 8192000000 10195814960 10614763520 1309242288 13.779 100 18 36 0 1319070224 5593456 4234480
20:37:55 16625 8192000000 10218212736 10633650176 1320894592 13.885 100 22 44 0 1330638528 4869360 4874576
20:39:13 86959 8192000000 10242216032 10658795520 1331151472 13.978 99 39 76 0 1348767952 9240848 8375632
20:41:45 4483 8192000000 10271718400 10681085952 1350784192 14.155 100 54 108 1 1376412928 13245088 12383648
20:42:56 10704 8192000000 10287036736 10706415616 1374040848 14.363 100 51 100 0 1402333888 16804288 11488752
20:44:14 19334 8192000000 10295665536 10717503488 1381532464 14.430 100 47 87 1 1406526736 14078176 10916096
20:45:43 2250 8192000000 10316884368 10735292416 1396733312 14.566 100 54 106 0 1426382256 16822912 12826032
20:46:49 7413 8192000000 10317867520 10744455168 1401066144 14.604 100 46 92 0 1431136832 18522448 11548240
20:47:59 13226 8192000000 10313573312 10740719616 1396462944 14.563 100 66 132 0 1432884832 20627744 15794144
20:49:21 10522 8192000000 10319805664 10745683968 1397734048 14.575 100 52 102 1 1432764288 22738608 12291632
20:50:33 2411 8192000000 10310491232 10718908416 1373806880 14.361 100 67 132 0 1415871760 26074352 15990528
20:51:41 3520 8192000000 10318377024 10730172416 1376393552 14.384 100 56 110 1 1418544336 28227072 13923712
20:52:53 1307 8192000000 10336355936 10744987648 1390971648 14.515 100 74 148 1 1441048368 31652656 18424064
20:53:59 3019 8192000000 10350622464 10763038720 1397473248 14.572 100 83 162 0 1454884560 36412784 20998528
20:55:08 2244 8192000000 10306203392 10713350144 1359489280 14.233 99 71 141 1 1412720560 35590752 17640528
20:56:14 3537 8192000000 10329095904 10734977024 1373841824 14.361 100 85 169 0 1429915136 34517056 21556256
20:57:26 7565 8192000000 10328673472 10737094656 1360726720 14.244 99 73 143 1 1421736816 42790224 18219872
20:58:38 11463 8192000000 10298091792 10719432704 1364219008 14.275 100 39 78 0 1409979088 34538464 11221616
20:59:54 4266 8192000000 10315469920 10732531712 1370408800 14.331 100 50 100 2 1420290080 36082704 13798576
21:01:03 8282 8192000000 10283517744 10707955712 1345036752 14.103 99 36 72 1 1385866880 31123760 9706368
21:02:14 21098 8192000000 10263609056 10691190784 1332426528 13.989 99 22 44 0 1369611952 31101408 6084016
21:03:41 41040 8192000000 10248675120 10693820416 1311272704 13.798 98 18 36 1 1352836432 36776800 4786928
21:05:23 38625 8192000000 10252244528 10680897536 1333900240 14.002 100 30 61 0 1349484560 8507360 7076960
21:07:17 95907 8192000000 10275342240 10713722880 1350344976 14.151 100 31 62 0 1368424368 10657776 7421616
21:10:02 164937 8192000000 10299235296 10740445184 1312520656 13.809 95 21 42 0 1337754416 20845536 4388224
21:13:48 41002 8192000000 10319554672 10767798272 1394867280 14.549 100 37 74 0 1428403552 24854384 8681888
21:15:50 59839 8192000000 10301147600 10765205504 1393791776 14.540 100 12 24 1 1421326592 24665072 2869744
21:17:52 64218 8192000000 10305372960 10761707520 1394886976 14.549 100 29 56 0 1417937296 16088224 6962096
21:19:57 33952 8192000000 10314829600 10769244160 1401027760 14.604 100 20 40 0 1423301712 17504864 4769088
21:21:31 67310 8192000000 10305546864 10773229568 1383770656 14.450 99 5 10 0 1405970192 20846496 1353040
21:23:40 51016 8192000000 10301694416 10757586944 1403832128 14.629 100 14 28 0 1419747792 13014256 2901408
21:25:35 25236 8192000000 10332673024 10772545536 1397959552 14.577 99 55 110 0 1422774608 12072992 12742064
21:27:01 90411 8192000000 10298997104 10763915264 1367099728 14.301 97 14 24 0 1392393136 22191056 3102352
21:29:41 58608 8192000000 10300475984 10751410176 1387358816 14.482 100 13 26 0 1404174976 13640496 3175664
21:31:50 83243 8192000000 10283570448 10738319360 1322225760 13.897 96 9 18 0 1342083888 17797984 2060144
21:34:17 51464 8192000000 10277393728 10732912640 1341001312 14.066 97 19 38 1 1360192112 15070048 4120752
21:36:18 82775 8192000000 10271967904 10725756928 1361522672 14.251 99 21 42 0 1378485744 11953968 5009104
21:38:46 48077 8192000000 10259184720 10722275328 1349256912 14.141 98 20 41 1 1361268560 7489488 4522160
21:40:45 71283 8192000000 10237836832 10694021120 1331379232 13.980 98 12 24 0 1343260176 9241360 2639584
21:42:57 41637 8192000000 10239684464 10694184960 1309970736 13.786 96 8 16 0 1320474416 8824288 1679392
21:44:39 59094 8192000000 10251473408 10701553664 1308470656 13.772 96 22 44 0 1323110112 9436448 5203008
21:46:39 47299 8192000000 10249670800 10703486976 1310480656 13.790 96 11 22 0 1321396496 8168688 2747152
21:48:26 49663 8192000000 10227739280 10668486656 1271809728 13.438 94 26 54 0 1289879552 12217312 5852512
21:50:17 43882 8192000000 10259474288 10702032896 1317373984 13.853 98 31 60 0 1341581936 17332784 6875168
21:52:02 42803 8192000000 10260062160 10702991360 1338662480 14.045 99 34 68 0 1358166672 12206480 7297712
21:53:49 39926 8192000000 10260120880 10703986688 1312615024 13.810 97 24 46 0 1331352208 13829472 4907712
21:55:36 52141 8192000000 10270912560 10725171200 1331018640 13.976 98 13 26 0 1349181808 15272528 2890640
21:57:37 93377 8192000000 10262498880 10709594112 1337654928 14.036 98 31 62 1 1354320224 8386384 8278912
22:00:12 55600 8192000000 10258172928 10711785472 1349990272 14.147 99 8 16 0 1360280640 8467664 1822704
22:02:11 42846 8192000000 10273265568 10727981056 1362287808 14.258 99 14 28 1 1374158448 8586768 3283872
22:04:00 49388 8192000000 10266378336 10730819584 1375798960 14.379 100 14 28 0 1387966592 8782256 3385376
22:05:58 46905 8192000000 10263660640 10724724736 1372816400 14.352 100 15 30 0 1383972352 8003200 3152752
22:07:46 47247 8192000000 10290161904 10749943808 1392298832 14.526 100 25 51 2 1409219584 11302992 5617760
22:09:34 25579 8192000000 10311263424 10765135872 1403841120 14.629 100 20 40 0 1417115280 8927312 4346848
22:10:59 53942 8192000000 10300208304 10759061504 1383280320 14.446 99 14 28 0 1396313968 10096960 2936688
22:12:57 19796 8192000000 10327454048 10777595904 1415309312 14.731 100 41 83 1 1437574592 13126688 9138592
22:14:19 55591 8192000000 10325915376 10782523392 1398974480 14.586 99 13 26 0 1419122400 16683120 3464800
22:16:19 92254 8192000000 10306420256 10756235264 1284731712 13.556 91 22 44 1 1300892816 10613328 5547776
22:18:58 112556 8192000000 10283974336 10743013376 1347680960 14.127 97 11 22 0 1371696640 21282784 2732896
22:21:53 80293 8192000000 10287871424 10735521792 1347137568 14.122 97 28 56 0 1362482208 9145520 6199120
22:24:15 33546 8192000000 10254298352 10713128960 1347087248 14.121 98 11 22 0 1360196816 10907088 2202480
22:26:00 76523 8192000000 10267543600 10719961088 1324374416 13.916 96 12 23 0 1332311168 5431440 2505312
22:28:17 63754 8192000000 10294598560 10752057344 1392779536 14.531 100 21 43 1 1409482192 11663120 5039536
22:30:40 72486 8192000000 10252757744 10719805440 1288061472 13.587 93 16 32 0 1306175600 14368544 3745584
22:33:07 34727 8192000000 10256358800 10695692288 1304831136 13.739 97 20 40 0 1315750848 6052592 4867120
22:34:55 71851 8192000000 10249261648 10704367616 1321420160 13.890 97 20 40 0 1342301712 16301616 4579936
22:37:07 56124 8192000000 10244485072 10707304448 1336810736 14.029 99 32 64 0 1358177296 14295520 7071040

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-07-2022 22:39:32 to 26-07-2022 22:39:47.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 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 26-07-2022 22:39:48 to 26-07-2022 22:39:50.

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          247055
MON$OLDEST_ACTIVE               248223
MON$OLDEST_SNAPSHOT             248223
MON$NEXT_TRANSACTION            248223
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                       2027680
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.33613 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33613 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33613 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33613 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 ---
:236: 
:237: mscs-test-161.budzdorov.ru    Tue Jul 26 22:38:24 2022
:238:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:239: 
:240: 
:241: mscs-test-161.budzdorov.ru    Tue Jul 26 22:38:27 2022
:242:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:243: 
--- end of diff output ---

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

26.07.22 22:39:51. 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-07-26 22:39:54 No severe PSQL-related problems occured
26.07.22 22:39:55. End of report.