Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 26.04.2022 16:42
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 16581688 kB
MemAvailable 31547832 kB
Buffers 0 kB
Cached 15112132 kB
SwapCached 3036 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-04-26 16:42:41
DTS_END                         2022-04-26 16:42:32.6270
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_FILL_MON_CACHE_MEMORY(20:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/36592
TRN_ID                          242503
ATT_ID                          274
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9557.45 19182 1146894
customer order: creation 178.29 319 21395
customer order: refuse 15.48 374 1857
order to supplier: creation 46.61 1278 5593
order to supplier: removal 3.50 96184 420
invoice (draft): creation 47.53 3438 5703
invoice (draft): removal 54.81 831 6577
invoice accept: apply 42.83 17805 5139
invoice accept: cancel 49.12 735 5894
customer reserve: creation 3988.93 175 478671
customer reserve: removal 4823.02 52 578762
realization accept: apply 84.35 176 10122
realization accept: cancel 90.88 108 10905
payment from customer: creation 59.65 99 7158
payment from customer: removal 5.91 67 709
payment to supplier: creation 50.61 140 6073
payment to supplier: removal 3.32 71 398
service: total inventory turnovers 5.01 1078 601
service: total monetary turnovers 6.71 25 805
service: aggregate perf. data 0.90 17830 108
service: refresh index statistics 0.03 242851 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-04-2022 16:43:15 to 26-04-2022 16:43:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4399
TEST_TIME 2 4078
TEST_TIME 3 3655
TEST_TIME 4 3649
TEST_TIME 5 3734
TEST_TIME 6 3527
TEST_TIME 7 3587
TEST_TIME 8 4052
TEST_TIME 9 4704
TEST_TIME 10 5649
TEST_TIME 11 5932
TEST_TIME 12 6121
TEST_TIME 13 6335
TEST_TIME 14 6811
TEST_TIME 15 7533
TEST_TIME 16 7832
TEST_TIME 17 7867
TEST_TIME 18 7937
TEST_TIME 19 8474
TEST_TIME 20 8268
TEST_TIME 21 8165
TEST_TIME 22 9102
TEST_TIME 23 9447
TEST_TIME 24 9695
TEST_TIME 25 9538
TEST_TIME 26 9826
TEST_TIME 27 10056
TEST_TIME 28 10087
TEST_TIME 29 9937
TEST_TIME 30 11121
TEST_TIME 31 11050
TEST_TIME 32 10856
TEST_TIME 33 10545
TEST_TIME 34 10621
TEST_TIME 35 10391
TEST_TIME 36 10442
TEST_TIME 37 10287
TEST_TIME 38 10275
TEST_TIME 39 10417
TEST_TIME 40 10320
TEST_TIME 41 10186
TEST_TIME 42 10132
TEST_TIME 43 10104
TEST_TIME 44 9965
TEST_TIME 45 9988
TEST_TIME 46 9925
TEST_TIME 47 9833
TEST_TIME 48 9771
TEST_TIME 49 9731
TEST_TIME 50 9648
TEST_TIME 51 9557
TEST_TIME 52 9545
TEST_TIME 53 9537
TEST_TIME 54 9584
TEST_TIME 55 9507
TEST_TIME 56 9491
TEST_TIME 57 9478
TEST_TIME 58 9492
TEST_TIME 59 9583
TEST_TIME 60 9579
TEST_TIME 61 9546
TEST_TIME 62 9542
TEST_TIME 63 9601
TEST_TIME 64 9554
TEST_TIME 65 9597
TEST_TIME 66 9705
TEST_TIME 67 9734
TEST_TIME 68 9680
TEST_TIME 69 9654
TEST_TIME 70 9855
TEST_TIME 71 9822
TEST_TIME 72 9704
TEST_TIME 73 9628
TEST_TIME 74 9785
TEST_TIME 75 9723
TEST_TIME 76 9674
TEST_TIME 77 9720
TEST_TIME 78 9653
TEST_TIME 79 9621
TEST_TIME 80 9633
TEST_TIME 81 9603
TEST_TIME 82 9518
TEST_TIME 83 9507
TEST_TIME 84 9591
TEST_TIME 85 9561
TEST_TIME 86 9541
TEST_TIME 87 9564
TEST_TIME 88 9549
TEST_TIME 89 9573
TEST_TIME 90 9603
TEST_TIME 91 9604
TEST_TIME 92 9664
TEST_TIME 93 9670
TEST_TIME 94 9677
TEST_TIME 95 9639
TEST_TIME 96 9667
TEST_TIME 97 9707
TEST_TIME 98 9727
TEST_TIME 99 9702
TEST_TIME 100 9667
TEST_TIME 101 9609
TEST_TIME 102 9676
TEST_TIME 103 9629
TEST_TIME 104 9634
TEST_TIME 105 9586
TEST_TIME 106 9588
TEST_TIME 107 9562
TEST_TIME 108 9508
TEST_TIME 109 9457
TEST_TIME 110 9438
TEST_TIME 111 9489
TEST_TIME 112 9462
TEST_TIME 113 9493
TEST_TIME 114 9507
TEST_TIME 115 9527
TEST_TIME 116 9521
TEST_TIME 117 9524
TEST_TIME 118 9510
TEST_TIME 119 9523
TEST_TIME 120 9563

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-04-2022 16:43:19 to 26-04-2022 16:43:22.

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 1589403 1589403 0 0.00 0 50964 6 0 0
doc_list_biud 1589396 1589396 0 0.00 0 60168 25 0 0
sp_add_invoice_to_stock 5232 5139 93 1.77 39 205052 17805 0 93
sp_cancel_adding_invoice 5894 5894 0 0.00 4 51073 735 0 0
sp_cancel_client_order 1857 1857 0 0.00 4 21577 374 0 0
sp_cancel_customer_reserve 578762 578762 0 0.00 0 60171 53 0 0
sp_cancel_pay_from_customer 709 709 0 0.00 3 2268 67 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 2940 71 0 0
sp_cancel_supplier_invoice 6592 6577 15 0.22 2 42644 832 0 15
sp_cancel_supplier_order 420 420 0 0.00 9 1046847 96184 0 0
sp_cancel_write_off 10905 10905 0 0.00 1 24108 109 0 0
sp_client_order 21395 21395 0 0.00 3 31230 319 0 0
sp_customer_reserve 478671 478671 0 0.00 0 111012 176 0 0
sp_fill_shopping_cart 32688 32688 0 0.00 1 29122 39 0 0
sp_fill_shopping_cart_clo_res 478662 478662 0 0.00 0 15892 13 0 0
sp_get_clo_for_invoice 5129 5129 0 0.00 0 22582 275 0 0
sp_kill_cost_storno 17906 17906 0 0.00 0 10446 56 0 0
sp_kill_qstorno_ret_qs2qd 1175426 1175426 0 0.00 0 60141 29 0 0
sp_kill_qty_storno 619665 619665 0 0.00 0 60167 64 0 0
sp_lock_selected_doc 21170 21170 0 0.00 0 7716 5 0 0
sp_make_cost_storno 28481 28481 0 0.00 0 50961 57 0 0
sp_multiply_rows_for_pdistr 28481 28481 0 0.00 0 14846 29 0 0
sp_multiply_rows_for_qdistr 21395 21395 0 0.00 0 13359 135 0 0
sp_pay_from_customer 7158 7158 0 0.00 4 14928 100 0 0
sp_pay_to_supplier 6073 6073 0 0.00 5 50971 141 0 0
sp_payment_common 13231 13231 0 0.00 1 9828 25 0 0
sp_qd_handle_on_cancel_clo 1857 1857 0 0.00 0 21571 350 0 0
sp_qd_handle_on_invoice_upd_sts 11022 11022 0 0.00 0 23121 341 0 0
sp_qd_handle_on_reserve_upd_sts 10122 10122 0 0.00 0 18074 57 0 0
sp_reserve_write_off 10122 10122 0 0.00 6 18100 176 0 0
sp_supplier_invoice 5703 5703 0 0.00 6 115376 3438 0 0
sp_supplier_order 5593 5593 0 0.00 6 39732 1279 0 0
srv_aggregate_perf_data 108 108 0 0.00 2508 166395 17831 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1918 138501 49115 0 0
srv_find_qd_qs_mism 1097367 1097367 0 0.00 0 25436 6 0 0
srv_make_invnt_saldo 601 601 0 0.00 46 36438 1078 0 0
srv_make_money_saldo 805 805 0 0.00 1 201 26 0 0
srv_recalc_idx_stat 4 4 0 0.00 124424 458932 242852 0 0
t$perf-abend:sp_add_invoice_to_stock 93 93 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5139 5139 0 0.00 0 10846 186 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 1 3926 95 0 0
t$perf-norm:sp_cancel_client_order 1857 1857 0 0.00 0 3531 14 0 0
t$perf-norm:sp_cancel_customer_reserve 720 720 0 0.00 0 2237 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 709 709 0 0.00 0 3494 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 475 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 407 407 0 0.00 0 4907 27 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 27462 1724 0 0
t$perf-norm:sp_cancel_write_off 1010 1010 0 0.00 0 2573 9 0 0
t$perf-norm:sp_client_order 21395 21395 0 0.00 0 12229 20 0 0
t$perf-norm:sp_customer_reserve 1066 1066 0 0.00 0 693 4 0 0
t$perf-norm:sp_pay_from_customer 7158 7158 0 0.00 0 8227 9 0 0
t$perf-norm:sp_pay_to_supplier 6073 6073 0 0.00 0 8282 8 0 0
t$perf-norm:sp_reserve_write_off 10123 10123 0 0.00 0 7984 14 0 0
t$perf-norm:sp_supplier_invoice 5703 5703 0 0.00 0 4035 16 0 0
t$perf-norm:sp_supplier_order 5593 5593 0 0.00 0 3758 15 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 192 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 704 15 0 0
t$perf-norm:srv_make_invnt_saldo 601 601 0 0.00 0 1634 21 0 0
t$perf-norm:srv_make_money_saldo 805 805 0 0.00 0 1364 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 10442 10256 186 1.78 0 6467 16 0 186
v_all_customers 39320 39320 0 0.00 0 19 1 0 0
v_all_suppliers 31628 31628 0 0.00 0 19 1 0 0
v_all_wares 42790 42790 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 258 6 0 0
v_cancel_client_order 3714 3714 0 0.00 0 3229 5 0 0
v_cancel_customer_prepayment 1418 1418 0 0.00 0 1783 7 0 0
v_cancel_customer_reserve 1440 1440 0 0.00 0 684 5 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 1514 7 0 0
v_cancel_supplier_invoice 844 814 30 3.55 0 2244 17 0 30
v_cancel_supplier_order 840 840 0 0.00 0 2372 9 0 0
v_cancel_write_off 2020 2020 0 0.00 0 2515 14 0 0
v_max_id_clo_ord 5593 5593 0 0.00 0 5753 3 0 0
v_max_id_clo_res 1066 1066 0 0.00 0 1829 11 0 0
v_max_id_ord_sup 5700 5700 0 0.00 0 4590 4 0 0
v_max_non_paid_invoice 1552 1552 0 0.00 0 929 3 0 0
v_max_non_paid_realizn 3634 3634 0 0.00 0 3220 9 0 0
v_min_id_clo_ord 5593 5593 0 0.00 0 8485 9 0 0
v_min_id_clo_res 1066 1066 0 0.00 0 2146 12 0 0
v_min_id_ord_sup 5700 5700 0 0.00 0 8193 14 0 0
v_min_non_paid_invoice 6073 6073 0 0.00 0 5733 14 0 0
v_min_non_paid_realizn 7158 7158 0 0.00 0 9814 19 0 0
v_our_firm 10518 10518 0 0.00 0 20 1 0 0
v_reserve_write_off 20244 20244 0 0.00 0 3604 6 0 0
x_lock_depdocs_on_canc_invoice 12064 12064 0 0.00 0 22679 108 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 7034 338 0 0
x_make_qstorno_1000_1200 5593 5593 0 0.00 1 31214 1188 0 0
x_make_qstorno_1200_2000 5703 5703 0 0.00 2 115348 3306 0 0
x_make_qstorno_2100_3300 478671 478671 0 0.00 0 111011 160 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-04-2022 16:43:23 to 26-04-2022 16:43:27.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
14:12:36 39 8192000000 8819353920 8837636096 19645456 0.239 5 5 6 0 19915040 98112 171472
14:13:37 35514 8192000000 9903001376 10210410496 981209152 10.696 100 86 173 0 1010245856 5972160 23064544
14:15:15 25214 8192000000 9936737760 10254200832 1017580336 11.049 100 91 182 0 1049706288 8602560 23523392
14:16:45 8341 8192000000 9968782592 10296123392 1055807744 11.416 100 89 178 0 1088324464 10079360 22437360
14:17:58 14277 8192000000 10028386768 10371543040 1105531024 11.890 100 83 164 0 1136457312 11201296 19724992
14:19:19 6572 8192000000 10079908336 10438426624 1167369360 12.472 100 84 168 1 1199990752 12679504 19941888
14:20:27 4306 8192000000 10122744384 10490089472 1208028896 12.851 100 88 174 2 1242580720 13992784 20559040
14:21:34 3344 8192000000 10154150560 10526760960 1243504048 13.178 100 89 178 1 1276739808 13701312 19534448
14:22:40 2215 8192000000 10190700496 10569736192 1273877552 13.457 100 84 167 2 1305696960 13350400 18469008
14:23:43 1781 8192000000 10215973984 10598211584 1298342944 13.680 100 90 180 2 1332695504 14059040 20293520
14:24:46 30154 8192000000 10214693696 10608746496 1309221664 13.779 100 64 129 0 1331435584 5706592 16507328
14:26:16 9464 8192000000 10201137184 10616274944 1313271536 13.816 100 31 62 1 1327509216 7494976 6742704
14:27:26 11647 8192000000 10197494016 10618179584 1318542320 13.864 100 20 41 2 1328955552 5376928 5036304
14:28:42 21354 8192000000 10216640832 10632925184 1327551376 13.945 100 23 46 0 1338861536 5728896 5581264
14:30:06 61670 8192000000 10213478512 10639712256 1325340912 13.925 100 23 44 1 1335600544 4568416 5691216
14:32:09 6645 8192000000 10220884272 10634620928 1315375088 13.835 99 28 56 0 1328672000 5643760 7653152
14:33:17 9469 8192000000 10205997040 10624524288 1309684880 13.783 99 20 40 1 1320395712 6161552 4549280
14:34:32 15711 8192000000 10149006224 10551513088 1214809392 12.914 95 25 49 3 1227050768 4829648 7411728
14:35:48 19283 8192000000 10142163200 10536579072 1178049328 12.572 93 25 50 1 1192972576 9855232 5068016
14:37:11 7024 8192000000 10147930544 10544074752 1232420384 13.076 98 27 54 3 1244502832 5521776 6560672
14:38:19 5847 8192000000 10184017104 10582519808 1268311664 13.406 99 37 73 2 1283606960 5205824 10089472
14:39:31 74090 8192000000 10194150240 10609451008 1299626272 13.692 100 22 44 0 1310592688 6190544 4775872
14:41:50 5835 8192000000 10203807216 10621149184 1314441312 13.826 100 26 53 1 1326222720 6027712 5753696
14:43:00 37840 8192000000 10254728288 10668826624 1346166352 14.113 100 45 90 0 1364211584 7463184 10582048
14:44:51 16749 8192000000 10285813616 10701053952 1367931984 14.309 100 43 86 2 1389534080 11933472 9668624
14:46:13 61368 8192000000 10281309520 10715140096 1379037712 14.408 100 29 58 1 1403396944 17302560 7056672
14:48:52 18156 8192000000 10315659664 10739662848 1393934976 14.541 100 37 74 1 1423554384 19923904 9695504
14:50:19 20414 8192000000 10329386736 10751262720 1389003392 14.497 99 61 121 0 1427990960 23983104 15004464
14:51:45 4522 8192000000 10343227072 10770112512 1414096880 14.720 100 52 104 0 1456485648 29915504 12473264
14:52:58 8741 8192000000 10361032432 10782658560 1424096048 14.809 100 73 146 1 1471269632 29834576 17339008
14:54:11 3454 8192000000 10371587888 10791051264 1427641376 14.840 100 81 163 1 1479200960 32156352 19403232
14:55:17 5346 8192000000 10361869024 10783698944 1410470880 14.688 99 73 146 0 1463630048 35776752 17382416
14:56:26 1916 8192000000 10352755680 10768723968 1391193088 14.517 99 83 164 0 1447644112 36824544 19626480
14:57:31 3299 8192000000 10353453536 10777006080 1412813696 14.709 100 63 125 1 1459593552 31250208 15529648
14:58:38 4730 8192000000 10333942400 10753622016 1386142640 14.471 100 68 134 2 1440401408 37302416 16956352
14:59:51 7120 8192000000 10331319328 10750939136 1382434832 14.438 100 56 112 0 1435335392 38258912 14641648
15:01:11 5055 8192000000 10348349264 10761011200 1395293712 14.553 100 82 161 1 1453169536 37877888 19997936
15:02:17 24541 8192000000 10312267616 10737844224 1352928784 14.174 98 44 88 3 1402260640 36959248 12372608
15:03:54 9962 8192000000 10335492096 10755203072 1368768272 14.316 100 58 116 2 1435367808 50574032 16025504
15:05:08 16405 8192000000 10314095440 10742374400 1369360064 14.321 100 25 50 2 1416511728 39245520 7906144
15:06:30 18160 8192000000 10291097568 10729529344 1361466688 14.251 100 17 34 2 1390649152 24343504 4838960
15:07:54 10357 8192000000 10304000832 10730668032 1358419120 14.223 100 28 56 1 1404447920 38299392 7729408
15:09:45 42286 8192000000 10304641888 10746691584 1369064912 14.319 100 15 30 0 1411060480 38047344 3948224
15:11:28 54018 8192000000 10299884432 10741178368 1308662480 13.774 96 21 42 0 1364693456 49783824 6247152
15:13:23 111976 8192000000 10284157824 10726174720 1325065472 13.923 96 26 51 1 1342519552 10826560 6627520
15:16:28 77251 8192000000 10279830000 10733834240 1360144896 14.239 99 12 24 0 1375415456 12571744 2698816
15:18:47 67536 8192000000 10307525152 10749030400 1362960576 14.264 98 20 41 0 1389196000 21867504 4367920
15:20:59 138487 8192000000 10283634592 10738786304 1368287296 14.312 99 13 26 0 1387824800 16583968 2953536
15:24:18 52075 8192000000 10303532816 10757808128 1398832544 14.585 100 23 46 2 1413311552 9035904 5443104
15:27:01 73015 8192000000 10303945056 10774110208 1407728368 14.664 100 15 30 0 1424461072 13092768 3639936
15:29:14 74392 8192000000 10308919312 10771390464 1406958448 14.657 100 23 46 2 1419726048 7778448 4989152
15:31:31 36021 8192000000 10310790592 10779631616 1414956960 14.728 100 17 34 1 1430480208 11650224 3873024
15:33:07 27477 8192000000 10328714608 10785169408 1407047200 14.658 99 35 70 1 1429703472 13854352 8801920
15:34:40 79573 8192000000 10308703344 10779201536 1373867360 14.362 97 13 26 1 1392398048 15367648 3163040
15:37:02 68905 8192000000 10282810368 10743111680 1360611968 14.243 97 18 31 2 1378402272 14381536 3408768
15:39:19 56442 8192000000 10284737696 10747207680 1333097232 13.995 95 20 40 0 1345046976 7352592 4597152
15:41:16 28985 8192000000 10295077568 10751758336 1373031952 14.354 98 33 66 1 1394210576 14137552 7041072
15:42:45 20610 8192000000 10304889920 10755096576 1397496960 14.573 100 46 92 2 1412946352 5739472 9709920
15:44:09 25775 8192000000 10283788160 10730258432 1339565520 14.053 97 38 76 1 1360726480 12496176 8664784
15:45:38 34626 8192000000 10262020816 10703003648 1325983472 13.931 97 26 52 0 1342606704 11093376 5529856
15:47:16 115509 8192000000 10262693968 10714238976 1301137888 13.706 95 15 30 1 1318113552 13930224 3045440
15:50:20 40741 8192000000 10288777200 10728890368 1367922720 14.308 100 27 54 1 1388934944 14895184 6117040
15:52:17 42204 8192000000 10263072992 10715881472 1335191424 14.014 98 5 10 0 1352868624 16399952 1277248
15:54:01 122637 8192000000 10255406016 10704142336 1321912512 13.894 98 24 48 1 1346979664 18841200 6225952
15:57:10 50187 8192000000 10260194800 10707308544 1349787680 14.146 100 25 50 1 1366946064 11692784 5465600
15:59:01 62086 8192000000 10238465040 10698817536 1298613648 13.683 96 12 24 0 1314175936 12681968 2880320
16:01:03 50048 8192000000 10250218032 10698579968 1334412432 14.007 99 13 26 0 1352345552 14552960 3380160
16:03:02 46380 8192000000 10252730928 10708615168 1335307536 14.015 99 11 23 0 1352868256 14341888 3218832
16:05:14 67457 8192000000 10257104304 10717859840 1337898528 14.038 98 17 34 0 1358375712 16478608 3998576
16:07:23 43257 8192000000 10249361872 10689769472 1316631824 13.846 97 18 35 0 1326785600 6414048 3739728
16:09:07 52655 8192000000 10268309296 10715660288 1356793728 14.209 100 16 32 1 1369402752 8918944 3690080
16:11:00 120710 8192000000 10290437344 10732195840 1331797888 13.983 97 13 26 0 1345289168 11133424 2357856
16:14:12 57954 8192000000 10291412576 10751766528 1382749792 14.441 100 14 28 0 1400906432 15273488 2883152
16:16:11 75368 8192000000 10305499504 10741547008 1368919952 14.317 99 28 58 1 1386406272 11561456 5924864
16:18:28 130243 8192000000 10289472736 10745462784 1341921424 14.075 96 13 26 0 1356631008 11845584 2864000
16:21:41 95575 8192000000 10288785040 10749628416 1377064016 14.390 99 11 22 0 1395226864 15800032 2362816
16:24:33 72756 8192000000 10304489248 10761457664 1377964272 14.398 99 22 43 0 1393866208 11154064 4747872
16:26:47 111559 8192000000 10249351120 10702848000 1268576896 13.409 91 11 22 0 1285080800 14015248 2488656
16:29:39 85258 8192000000 10251912896 10708115456 1327658912 13.946 97 9 18 0 1338860304 8911792 2289600
16:32:04 30553 8192000000 10253916432 10706362368 1327872304 13.948 97 32 63 1 1348612208 13268768 7471136
16:33:56 38990 8192000000 10254507792 10701246464 1323847232 13.912 97 16 32 0 1337151408 9926784 3377392
16:35:37 56159 8192000000 10253395168 10701934592 1308791248 13.775 96 23 46 2 1322728912 8704048 5233616
16:37:38 47726 8192000000 10257847184 10696306688 1311455456 13.799 97 27 54 1 1328622512 11327120 5839936
16:39:35 39902 8192000000 10260851376 10695876608 1322761376 13.902 98 31 62 0 1344074176 14481744 6831056
16:41:19 41537 8192000000 10276687872 10721034240 1349177568 14.140 99 44 88 2 1378006624 19095536 9733520

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-04-2022 16:43:29 to 26-04-2022 16:43:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 93 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 26-04-2022 16:43:45 to 26-04-2022 16:43:47.

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          247029
MON$OLDEST_ACTIVE               247030
MON$OLDEST_SNAPSHOT             247030
MON$NEXT_TRANSACTION            247030
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                       2031776
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.33578 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33578 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33578 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33578 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
on disk structure version 12.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
--- end of diff output ---

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

26.04.22 16:43:48. 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-04-26 16:43:50 No severe PSQL-related problems occured
26.04.22 16:43:51. End of report.