Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.06.2022 19: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 16329444 kB
MemAvailable 31205088 kB
Buffers 0 kB
Cached 15361496 kB
SwapCached 2492 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-06-23 19:38:21
DTS_END                         2022-06-23 19:38:21.1340
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(108:9) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/51280
TRN_ID                          235139
ATT_ID                          649
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: 9174.00 20302 1100880
customer order: creation 169.82 383 20378
customer order: refuse 14.92 354 1790
order to supplier: creation 45.16 1323 5419
order to supplier: removal 3.50 112923 420
invoice (draft): creation 45.33 3489 5440
invoice (draft): removal 52.59 855 6311
invoice accept: apply 40.88 18110 4906
invoice accept: cancel 47.58 804 5709
customer reserve: creation 3764.66 179 451759
customer reserve: removal 4698.82 66 563858
realization accept: apply 82.64 173 9917
realization accept: cancel 79.01 125 9481
payment from customer: creation 59.49 112 7139
payment from customer: removal 5.71 89 685
payment to supplier: creation 48.58 142 5830
payment to supplier: removal 3.18 66 381
service: total inventory turnovers 4.78 1062 573
service: total monetary turnovers 6.50 28 780
service: aggregate perf. data 0.83 22567 100
service: refresh index statistics 0.03 243189 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 23-06-2022 19:39:36 to 23-06-2022 19:39:39.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2328
TEST_TIME 2 3246
TEST_TIME 3 4408
TEST_TIME 4 4430
TEST_TIME 5 4732
TEST_TIME 6 5330
TEST_TIME 7 5723
TEST_TIME 8 5765
TEST_TIME 9 5556
TEST_TIME 10 5771
TEST_TIME 11 5664
TEST_TIME 12 5734
TEST_TIME 13 5735
TEST_TIME 14 5927
TEST_TIME 15 6218
TEST_TIME 16 6415
TEST_TIME 17 6334
TEST_TIME 18 6681
TEST_TIME 19 6885
TEST_TIME 20 7302
TEST_TIME 21 7667
TEST_TIME 22 7935
TEST_TIME 23 8017
TEST_TIME 24 8324
TEST_TIME 25 8358
TEST_TIME 26 9075
TEST_TIME 27 9610
TEST_TIME 28 10433
TEST_TIME 29 10740
TEST_TIME 30 10603
TEST_TIME 31 10372
TEST_TIME 32 10154
TEST_TIME 33 9998
TEST_TIME 34 9978
TEST_TIME 35 9988
TEST_TIME 36 9846
TEST_TIME 37 9789
TEST_TIME 38 9766
TEST_TIME 39 9813
TEST_TIME 40 9728
TEST_TIME 41 9809
TEST_TIME 42 9676
TEST_TIME 43 9721
TEST_TIME 44 9770
TEST_TIME 45 9726
TEST_TIME 46 9692
TEST_TIME 47 9766
TEST_TIME 48 9754
TEST_TIME 49 9828
TEST_TIME 50 9797
TEST_TIME 51 9714
TEST_TIME 52 9754
TEST_TIME 53 9877
TEST_TIME 54 9787
TEST_TIME 55 9878
TEST_TIME 56 9869
TEST_TIME 57 9941
TEST_TIME 58 9963
TEST_TIME 59 9805
TEST_TIME 60 9759
TEST_TIME 61 9789
TEST_TIME 62 9714
TEST_TIME 63 9687
TEST_TIME 64 9665
TEST_TIME 65 9582
TEST_TIME 66 9526
TEST_TIME 67 9461
TEST_TIME 68 9415
TEST_TIME 69 9412
TEST_TIME 70 9386
TEST_TIME 71 9360
TEST_TIME 72 9357
TEST_TIME 73 9436
TEST_TIME 74 9417
TEST_TIME 75 9368
TEST_TIME 76 9369
TEST_TIME 77 9451
TEST_TIME 78 9415
TEST_TIME 79 9508
TEST_TIME 80 9512
TEST_TIME 81 9456
TEST_TIME 82 9536
TEST_TIME 83 9523
TEST_TIME 84 9504
TEST_TIME 85 9450
TEST_TIME 86 9398
TEST_TIME 87 9400
TEST_TIME 88 9356
TEST_TIME 89 9315
TEST_TIME 90 9288
TEST_TIME 91 9281
TEST_TIME 92 9287
TEST_TIME 93 9252
TEST_TIME 94 9261
TEST_TIME 95 9322
TEST_TIME 96 9308
TEST_TIME 97 9365
TEST_TIME 98 9438
TEST_TIME 99 9475
TEST_TIME 100 9430
TEST_TIME 101 9503
TEST_TIME 102 9513
TEST_TIME 103 9603
TEST_TIME 104 9553
TEST_TIME 105 9525
TEST_TIME 106 9466
TEST_TIME 107 9418
TEST_TIME 108 9399
TEST_TIME 109 9359
TEST_TIME 110 9311
TEST_TIME 111 9298
TEST_TIME 112 9302
TEST_TIME 113 9257
TEST_TIME 114 9227
TEST_TIME 115 9200
TEST_TIME 116 9234
TEST_TIME 117 9213
TEST_TIME 118 9229
TEST_TIME 119 9246
TEST_TIME 120 9178

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 23-06-2022 19:39:40 to 23-06-2022 19:39:43.

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 1519772 1519772 0 0.00 0 22520 6 0 0
doc_list_biud 1519772 1519772 0 0.00 0 48805 31 0 0
sp_add_invoice_to_stock 4997 4906 91 1.82 26 202259 18111 0 91
sp_cancel_adding_invoice 5709 5709 0 0.00 5 65382 805 0 0
sp_cancel_client_order 1790 1790 0 0.00 4 22072 354 0 0
sp_cancel_customer_reserve 563858 563858 0 0.00 1 48806 67 0 0
sp_cancel_pay_from_customer 685 685 0 0.00 3 5192 90 0 0
sp_cancel_pay_to_supplier 381 381 0 0.00 3 1615 66 0 0
sp_cancel_supplier_invoice 6317 6311 6 0.09 2 27231 855 0 6
sp_cancel_supplier_order 420 420 0 0.00 12 1163113 112923 0 0
sp_cancel_write_off 9481 9481 0 0.00 1 12666 126 0 0
sp_client_order 20378 20378 0 0.00 4 46828 383 0 0
sp_customer_reserve 451759 451759 0 0.00 0 116357 179 0 0
sp_fill_shopping_cart 31226 31226 0 0.00 1 20393 38 0 0
sp_fill_shopping_cart_clo_res 451757 451757 0 0.00 0 19015 15 0 0
sp_get_clo_for_invoice 4902 4902 0 0.00 0 30149 329 0 0
sp_kill_cost_storno 16256 16256 0 0.00 0 9269 65 0 0
sp_kill_qstorno_ret_qs2qd 1143928 1143928 0 0.00 0 48803 36 0 0
sp_kill_qty_storno 602388 602388 0 0.00 0 48805 77 0 0
sp_lock_selected_doc 20550 20550 0 0.00 0 7287 5 0 0
sp_make_cost_storno 27788 27788 0 0.00 0 14562 53 0 0
sp_multiply_rows_for_pdistr 27788 27788 0 0.00 0 8430 33 0 0
sp_multiply_rows_for_qdistr 20378 20378 0 0.00 0 46807 187 0 0
sp_pay_from_customer 7139 7139 0 0.00 4 11826 113 0 0
sp_pay_to_supplier 5830 5830 0 0.00 5 9878 143 0 0
sp_payment_common 12969 12969 0 0.00 2 7802 31 0 0
sp_qd_handle_on_cancel_clo 1790 1790 0 0.00 0 21929 332 0 0
sp_qd_handle_on_invoice_upd_sts 10611 10611 0 0.00 0 44445 358 0 0
sp_qd_handle_on_reserve_upd_sts 9917 9917 0 0.00 0 14031 58 0 0
sp_reserve_write_off 9917 9917 0 0.00 6 14054 174 0 0
sp_supplier_invoice 5440 5440 0 0.00 7 76558 3489 0 0
sp_supplier_order 5419 5419 0 0.00 5 39613 1324 0 0
srv_aggregate_perf_data 100 100 0 0.00 2363 303884 22568 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1351 120180 48078 0 0
srv_find_qd_qs_mism 1054069 1054069 0 0.00 0 22518 5 0 0
srv_make_invnt_saldo 573 573 0 0.00 41 35584 1063 0 0
srv_make_money_saldo 780 780 0 0.00 1 2582 28 0 0
srv_recalc_idx_stat 4 4 0 0.00 144222 302621 243190 0 0
t$perf-abend:sp_add_invoice_to_stock 91 91 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4906 4906 0 0.00 0 12018 178 0 0
t$perf-norm:sp_cancel_adding_invoice 410 410 0 0.00 1 4217 105 0 0
t$perf-norm:sp_cancel_client_order 1790 1790 0 0.00 0 12590 28 0 0
t$perf-norm:sp_cancel_customer_reserve 654 654 0 0.00 0 1011 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 685 685 0 0.00 0 1696 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 381 381 0 0.00 0 6285 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 396 396 0 0.00 0 1860 25 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 21581 1573 0 0
t$perf-norm:sp_cancel_write_off 995 995 0 0.00 0 1122 5 0 0
t$perf-norm:sp_client_order 20378 20378 0 0.00 0 8524 20 0 0
t$perf-norm:sp_customer_reserve 1001 1001 0 0.00 0 814 5 0 0
t$perf-norm:sp_pay_from_customer 7139 7139 0 0.00 0 8437 15 0 0
t$perf-norm:sp_pay_to_supplier 5830 5830 0 0.00 0 4309 13 0 0
t$perf-norm:sp_reserve_write_off 9917 9917 0 0.00 0 6877 12 0 0
t$perf-norm:sp_supplier_invoice 5440 5440 0 0.00 0 4551 22 0 0
t$perf-norm:sp_supplier_order 5419 5419 0 0.00 0 6849 22 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 507 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 370 14 0 0
t$perf-norm:srv_make_invnt_saldo 573 573 0 0.00 0 2362 24 0 0
t$perf-norm:srv_make_money_saldo 780 780 0 0.00 0 2640 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 4 0 0
v_add_invoice_to_stock 9986 9804 182 1.82 0 3240 15 0 182
v_all_customers 37380 37380 0 0.00 0 16 1 0 0
v_all_suppliers 30214 30214 0 0.00 0 120 1 0 0
v_all_wares 40754 40754 0 0.00 0 16 1 0 0
v_cancel_adding_invoice 820 820 0 0.00 0 229 5 0 0
v_cancel_client_order 3580 3580 0 0.00 0 2202 4 0 0
v_cancel_customer_prepayment 1370 1370 0 0.00 0 4599 10 0 0
v_cancel_customer_reserve 1308 1308 0 0.00 0 2195 4 0 0
v_cancel_payment_to_supplier 762 762 0 0.00 0 1433 7 0 0
v_cancel_supplier_invoice 804 792 12 1.49 0 4413 22 0 12
v_cancel_supplier_order 840 840 0 0.00 0 198 3 0 0
v_cancel_write_off 1990 1990 0 0.00 0 1977 12 0 0
v_max_id_clo_ord 5419 5419 0 0.00 0 499 1 0 0
v_max_id_clo_res 1001 1001 0 0.00 0 20556 33 0 0
v_max_id_ord_sup 5429 5429 0 0.00 0 3530 4 0 0
v_max_non_paid_invoice 1571 1571 0 0.00 0 3614 5 0 0
v_max_non_paid_realizn 3768 3768 0 0.00 0 1848 4 0 0
v_min_id_clo_ord 5419 5419 0 0.00 0 5851 5 0 0
v_min_id_clo_res 1000 1000 0 0.00 0 4276 7 0 0
v_min_id_ord_sup 5429 5429 0 0.00 0 3982 6 0 0
v_min_non_paid_invoice 5830 5830 0 0.00 0 7794 23 0 0
v_min_non_paid_realizn 7139 7139 0 0.00 0 7206 21 0 0
v_our_firm 10116 10116 0 0.00 0 13 1 0 0
v_reserve_write_off 19834 19834 0 0.00 0 2925 4 0 0
x_lock_depdocs_on_canc_invoice 11624 11624 0 0.00 0 14127 123 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 15614 358 0 0
x_make_qstorno_1000_1200 5419 5419 0 0.00 1 39593 1241 0 0
x_make_qstorno_1200_2000 5440 5440 0 0.00 3 76533 3353 0 0
x_make_qstorno_2100_3300 451759 451759 0 0.00 0 116357 162 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-06-2022 19:39:44 to 23-06-2022 19:39:48.

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
17:08:24 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:25 28264 8192000000 9900630656 10209796096 977221792 10.657 100 89 176 1 1006527040 5560064 23745184
17:10:58 21136 8192000000 9929647264 10252525568 1016205408 11.035 100 86 172 0 1046411808 7291296 22915104
17:12:28 23103 8192000000 9991130432 10326102016 1068442320 11.537 100 83 166 0 1098116144 8841648 20832176
17:13:59 12505 8192000000 10063538192 10418053120 1147898256 12.290 100 77 154 0 1175870912 9968064 18004592
17:15:20 6319 8192000000 10107837952 10472128512 1195002032 12.730 100 82 164 0 1226653360 12952528 18698800
17:16:30 4115 8192000000 10141862032 10509942784 1223801888 12.997 100 83 163 3 1255516464 13697632 18016944
17:17:36 3595 8192000000 10164027440 10541494272 1251131680 13.249 100 79 158 0 1283350000 14018432 18199888
17:18:41 2737 8192000000 10198318592 10578501632 1282158480 13.533 100 95 191 0 1317025584 14021904 20845200
17:19:45 1118 8192000000 10217476736 10604916736 1303473696 13.727 100 80 160 1 1333622544 11954944 18193904
17:20:47 4131 8192000000 10208900928 10610356224 1311361664 13.798 100 77 155 1 1341764016 13229760 17172592
17:21:53 6320 8192000000 10199954704 10613878784 1315182064 13.833 100 28 56 0 1328707872 7545424 5980384
17:23:01 7327 8192000000 10207516432 10623213568 1323692672 13.910 100 24 48 0 1335310880 6137712 5480496
17:24:09 7707 8192000000 10220217616 10639663104 1333003568 13.994 100 29 58 2 1350797152 11346768 6446816
17:25:18 10866 8192000000 10226980944 10641408000 1337117728 14.031 100 50 101 3 1355158240 4814960 13225552
17:26:29 17377 8192000000 10257334128 10657624064 1339096304 14.049 100 78 154 1 1364157664 6840848 18220512
17:27:47 6084 8192000000 10229506624 10654158848 1340646336 14.063 100 28 57 1 1353218896 5973424 6599136
17:28:56 44427 8192000000 10201217680 10623934464 1295314144 13.653 97 23 46 0 1306171952 5978400 4879408
17:30:41 4307 8192000000 10169873568 10571759616 1251823424 13.255 97 43 86 1 1269090720 6661488 10605808
17:31:46 9960 8192000000 10141159136 10541379584 1201254272 12.788 95 18 37 0 1211034928 5353584 4427072
17:32:56 50623 8192000000 10156837920 10557165568 1247579008 13.216 99 29 59 2 1267684896 13127296 6978592
17:35:02 5551 8192000000 10171440096 10569945088 1267463776 13.398 100 40 80 1 1287361264 10815856 9081632
17:36:11 10529 8192000000 10176167744 10587439104 1286335408 13.571 100 23 46 0 1296923360 4798448 5789504
17:37:24 14679 8192000000 10194558224 10609197056 1299419728 13.690 100 18 36 3 1313324432 9750736 4153968
17:38:40 92795 8192000000 10236250688 10649190400 1328865696 13.957 100 29 58 0 1344599120 8672832 7060592
17:41:21 11674 8192000000 10266843120 10680987648 1356947456 14.210 100 42 82 0 1380623664 13880528 9795680
17:42:35 12209 8192000000 10289223088 10701922304 1369412080 14.322 100 57 113 0 1398207264 16148672 12646512
17:43:58 7300 8192000000 10296351232 10715992064 1382114800 14.435 100 52 104 1 1413785040 18254816 13415424
17:45:13 6616 8192000000 10309547712 10732957696 1391662368 14.521 100 60 119 2 1425453136 19687104 14103664
17:46:24 7971 8192000000 10312193648 10737459200 1386214784 14.472 99 44 88 1 1417622624 20802384 10605456
17:47:36 11709 8192000000 10336532304 10758610944 1406435616 14.652 100 58 116 0 1448355680 28467312 13452752
17:48:52 4355 8192000000 10352260432 10774167552 1416440352 14.741 100 62 124 0 1460214720 28843488 14930880
17:50:02 3359 8192000000 10353154880 10772852736 1401815424 14.611 98 58 115 2 1447765664 31746640 14203600
17:51:11 3251 8192000000 10370328544 10786865152 1422076432 14.791 100 80 159 1 1480207904 38256096 19875376
17:52:20 3697 8192000000 10369951152 10786177024 1417697648 14.752 100 74 146 3 1478489408 42461776 18329984
17:53:30 4414 8192000000 10389479888 10803027968 1425094800 14.818 100 84 168 0 1497039776 49593408 22351568
17:54:43 5531 8192000000 10387722736 10811404288 1427389840 14.838 100 72 143 0 1498531664 52425456 18716368
17:55:54 5883 8192000000 10388637904 10799435776 1412860160 14.709 99 78 154 0 1492396880 58744928 20791792
17:57:06 4255 8192000000 10370428784 10777636864 1383562032 14.448 98 70 139 1 1459478032 57129088 18786912
17:58:22 1346 8192000000 10416615216 10828853248 1424480688 14.812 100 90 180 0 1512029264 63828448 23720128
17:59:33 3693 8192000000 10424552432 10842763264 1440134384 14.951 100 75 150 1 1523692896 64067184 19491328
18:00:40 8995 8192000000 10382131040 10814816256 1417771888 14.753 99 42 84 0 1482365872 52296992 12296992
18:02:03 10396 8192000000 10370573616 10807685120 1410289360 14.687 100 31 62 0 1473327280 54231936 8805984
18:03:21 18913 8192000000 10332231488 10770276352 1370796976 14.334 98 27 54 1 1439221072 60584432 7839664
18:04:59 43255 8192000000 10303707008 10757722112 1347196464 14.122 98 18 36 1 1409852048 56921616 5733968
18:06:43 32894 8192000000 10289500448 10738630656 1373073728 14.355 99 14 28 0 1388304048 11824160 3406160
18:08:17 63078 8192000000 10282848448 10730754048 1354250736 14.186 98 20 39 1 1367941600 9449744 4241120
18:11:13 101190 8192000000 10281266240 10718343168 1266606112 13.391 92 27 53 0 1280489712 8291456 5592144
18:14:03 59829 8192000000 10294162032 10735865856 1347686656 14.127 98 33 66 1 1372788880 17171856 7930368
18:16:09 33900 8192000000 10292439136 10751844352 1381230896 14.428 100 18 36 0 1402825024 16860336 4733792
18:17:44 112316 8192000000 10288771856 10749673472 1357185488 14.212 98 21 40 4 1379669088 17378768 5104832
18:20:39 47396 8192000000 10294703376 10756161536 1379988368 14.416 100 23 46 1 1404770768 19068320 5714080
18:22:37 61825 8192000000 10289823760 10739736576 1366476880 14.295 99 16 32 0 1379999184 10040784 3481520
18:24:52 120180 8192000000 10301693968 10765742080 1380893584 14.425 99 20 40 0 1395059712 9623056 4543072
18:28:04 87857 8192000000 10289644848 10743263232 1328880304 13.957 95 8 16 0 1345472640 14988512 1603824
18:30:32 78633 8192000000 10287353872 10753957888 1355174976 14.194 98 17 34 0 1378961568 20231232 3555360
18:33:01 44207 8192000000 10281688832 10734673920 1350447536 14.152 98 29 58 1 1371743648 13873024 7423088
18:34:50 24873 8192000000 10297491728 10742300672 1375027248 14.372 100 16 32 0 1393132256 14057728 4047280
18:36:16 119779 8192000000 10273922736 10718011392 1323651152 13.910 97 17 34 0 1351632672 24235296 3746224
18:39:16 72938 8192000000 10284049072 10736123904 1367960352 14.309 99 26 52 0 1386418432 12775952 5682128
18:41:31 59568 8192000000 10275807200 10734358528 1358571184 14.225 98 16 31 2 1371871984 9786832 3513968
18:43:32 40341 8192000000 10265619456 10720243712 1362875872 14.263 100 5 10 1 1377906608 13836512 1194224
18:45:33 40691 8192000000 10266997856 10720546816 1371888256 14.344 100 23 44 2 1385629744 8562064 5179424
18:47:15 49953 8192000000 10258746432 10713575424 1341175760 14.068 98 17 34 2 1353535552 8516704 3843088
18:49:19 50650 8192000000 10276042144 10729046016 1360299744 14.240 99 11 22 1 1374594640 11626896 2668000
18:51:25 76214 8192000000 10281881488 10724753408 1325565568 13.927 96 13 21 2 1339758672 12094320 2098784
18:53:58 44049 8192000000 10283516688 10729738240 1351476944 14.161 98 32 63 0 1369737184 10988432 7271808
18:55:44 28832 8192000000 10290206384 10727456768 1334354880 14.006 97 36 72 0 1353866960 11288576 8223504
18:57:33 42419 8192000000 10269588416 10725785600 1346219104 14.113 99 20 40 0 1367411856 16624512 4568240
18:59:16 44524 8192000000 10268528352 10712031232 1343706912 14.091 98 20 40 0 1359507328 11506192 4294224
19:01:09 59325 8192000000 10276932000 10737668096 1367908864 14.308 100 17 34 1 1384587824 12887024 3791936
19:03:15 107744 8192000000 10265391488 10722488320 1342837408 14.083 98 18 36 1 1355940016 8837200 4265408
19:06:03 61289 8192000000 10251642272 10716839936 1332382880 13.989 98 9 18 0 1345777760 11351360 2043520
19:08:40 51015 8192000000 10281471120 10735046656 1376584224 14.386 100 16 33 1 1390650944 10485744 3580976
19:10:31 87199 8192000000 10290278448 10747478016 1345044432 14.103 97 11 22 0 1362650960 15085952 2520576
19:12:59 73501 8192000000 10292667728 10749579264 1349601360 14.144 97 20 40 0 1371883104 17710304 4571440
19:16:03 116296 8192000000 10273668912 10719657984 1292042832 13.623 93 19 39 0 1312281984 15414608 4824544
19:19:07 81407 8192000000 10290657808 10742579200 1321248288 13.888 96 27 54 0 1341382992 14604816 5529888
19:21:29 83645 8192000000 10279961312 10725822464 1321950368 13.894 96 16 32 2 1340750304 14804496 3995440
19:23:59 90511 8192000000 10269892896 10723954688 1357141536 14.212 99 31 60 0 1372116784 8207392 6767856
19:26:35 45349 8192000000 10267434176 10730237952 1371669712 14.342 100 9 18 0 1381700880 8065744 1965424
19:28:36 63581 8192000000 10270163152 10733826048 1356463360 14.206 98 7 15 0 1365148576 7170848 1514368
19:30:40 87162 8192000000 10279124192 10741923840 1343221888 14.086 97 12 24 1 1359892512 13823904 2846720
19:33:09 61273 8192000000 10292627488 10761932800 1389798144 14.504 100 18 35 1 1411075536 17471872 3805520
19:35:11 33947 8192000000 10282923456 10740686848 1362745968 14.262 98 13 26 0 1375179072 9524640 2908464
19:36:46 67765 8192000000 10287864032 10755399680 1369498384 14.323 99 12 24 1 1382954480 10558240 2897856

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-06-2022 19:39:50 to 23-06-2022 19:40:05.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 91 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 23-06-2022 19:40:06 to 23-06-2022 19:40:08.

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          234398
MON$OLDEST_ACTIVE               235888
MON$OLDEST_SNAPSHOT             235888
MON$NEXT_TRANSACTION            235888
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                       2016768
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.33603 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33603 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33603 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33603 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 ---
:323: 
:324: mscs-test-161.budzdorov.ru    Thu Jun 23 19:38:31 2022
:325:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:326: 
:327: 
:328: mscs-test-161.budzdorov.ru    Thu Jun 23 19:38:31 2022
:329:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:330: 
--- end of diff output ---

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

23.06.22 19:40:09. 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-06-23 19:40:11 No severe PSQL-related problems occured
23.06.22 19:40:12. End of report.