Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.04.2022 16: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 16641608 kB
MemAvailable 31577372 kB
Buffers 0 kB
Cached 15100164 kB
SwapCached 3192 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-27 16:38:16
DTS_END                         2022-04-27 16:38:16.7950
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53406
TRN_ID                          235249
ATT_ID                          706
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: 9373.85 24223 1124862
customer order: creation 163.28 368 19593
customer order: refuse 14.25 363 1710
order to supplier: creation 44.30 1260 5316
order to supplier: removal 3.57 97722 428
invoice (draft): creation 44.24 3176 5309
invoice (draft): removal 54.80 760 6576
invoice accept: apply 40.64 17429 4877
invoice accept: cancel 49.93 741 5991
customer reserve: creation 3765.58 171 451870
customer reserve: removal 4899.97 55 587996
realization accept: apply 80.72 172 9687
realization accept: cancel 89.28 100 10713
payment from customer: creation 57.08 102 6849
payment from customer: removal 5.01 88 601
payment to supplier: creation 47.18 119 5662
payment to supplier: removal 3.33 88 399
service: total inventory turnovers 4.15 1225 498
service: total monetary turnovers 5.74 71 689
service: aggregate perf. data 0.78 25561 94
service: refresh index statistics 0.03 334893 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-04-2022 16:40:12 to 27-04-2022 16:40:14.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2476
TEST_TIME 2 4113
TEST_TIME 3 4274
TEST_TIME 4 4825
TEST_TIME 5 5135
TEST_TIME 6 5029
TEST_TIME 7 5218
TEST_TIME 8 5397
TEST_TIME 9 5428
TEST_TIME 10 5659
TEST_TIME 11 5921
TEST_TIME 12 5816
TEST_TIME 13 5781
TEST_TIME 14 5948
TEST_TIME 15 5919
TEST_TIME 16 6291
TEST_TIME 17 6499
TEST_TIME 18 7412
TEST_TIME 19 8207
TEST_TIME 20 8257
TEST_TIME 21 8655
TEST_TIME 22 8807
TEST_TIME 23 8765
TEST_TIME 24 8933
TEST_TIME 25 9225
TEST_TIME 26 9169
TEST_TIME 27 9603
TEST_TIME 28 10395
TEST_TIME 29 10307
TEST_TIME 30 10721
TEST_TIME 31 10646
TEST_TIME 32 10422
TEST_TIME 33 10372
TEST_TIME 34 10304
TEST_TIME 35 10169
TEST_TIME 36 10252
TEST_TIME 37 10029
TEST_TIME 38 10016
TEST_TIME 39 9874
TEST_TIME 40 9963
TEST_TIME 41 10024
TEST_TIME 42 9940
TEST_TIME 43 9796
TEST_TIME 44 9773
TEST_TIME 45 9662
TEST_TIME 46 9678
TEST_TIME 47 9589
TEST_TIME 48 9588
TEST_TIME 49 9521
TEST_TIME 50 9457
TEST_TIME 51 9375
TEST_TIME 52 9389
TEST_TIME 53 9306
TEST_TIME 54 9309
TEST_TIME 55 9442
TEST_TIME 56 9500
TEST_TIME 57 9501
TEST_TIME 58 9557
TEST_TIME 59 9593
TEST_TIME 60 9575
TEST_TIME 61 9666
TEST_TIME 62 9669
TEST_TIME 63 9726
TEST_TIME 64 9653
TEST_TIME 65 9708
TEST_TIME 66 9643
TEST_TIME 67 9612
TEST_TIME 68 9555
TEST_TIME 69 9491
TEST_TIME 70 9468
TEST_TIME 71 9468
TEST_TIME 72 9439
TEST_TIME 73 9361
TEST_TIME 74 9342
TEST_TIME 75 9369
TEST_TIME 76 9365
TEST_TIME 77 9385
TEST_TIME 78 9451
TEST_TIME 79 9410
TEST_TIME 80 9397
TEST_TIME 81 9426
TEST_TIME 82 9490
TEST_TIME 83 9489
TEST_TIME 84 9503
TEST_TIME 85 9507
TEST_TIME 86 9470
TEST_TIME 87 9647
TEST_TIME 88 9628
TEST_TIME 89 9706
TEST_TIME 90 9608
TEST_TIME 91 9562
TEST_TIME 92 9530
TEST_TIME 93 9523
TEST_TIME 94 9448
TEST_TIME 95 9419
TEST_TIME 96 9416
TEST_TIME 97 9411
TEST_TIME 98 9481
TEST_TIME 99 9459
TEST_TIME 100 9446
TEST_TIME 101 9413
TEST_TIME 102 9464
TEST_TIME 103 9437
TEST_TIME 104 9536
TEST_TIME 105 9542
TEST_TIME 106 9521
TEST_TIME 107 9569
TEST_TIME 108 9554
TEST_TIME 109 9628
TEST_TIME 110 9613
TEST_TIME 111 9538
TEST_TIME 112 9524
TEST_TIME 113 9529
TEST_TIME 114 9541
TEST_TIME 115 9523
TEST_TIME 116 9473
TEST_TIME 117 9451
TEST_TIME 118 9408
TEST_TIME 119 9417
TEST_TIME 120 9374

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-04-2022 16:40:16 to 27-04-2022 16:40:19.

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 1542368 1542368 0 0.00 0 26352 6 0 0
doc_list_biud 1542368 1542368 0 0.00 0 60596 27 0 0
sp_add_invoice_to_stock 4988 4877 111 2.22 30 182935 17430 0 111
sp_cancel_adding_invoice 5991 5991 0 0.00 5 65077 741 0 0
sp_cancel_client_order 1710 1710 0 0.00 4 11379 363 0 0
sp_cancel_customer_reserve 587996 587996 0 0.00 1 60597 56 0 0
sp_cancel_pay_from_customer 601 601 0 0.00 2 3306 88 0 0
sp_cancel_pay_to_supplier 399 399 0 0.00 3 5512 89 0 0
sp_cancel_supplier_invoice 6586 6576 10 0.15 2 13986 761 0 10
sp_cancel_supplier_order 428 428 0 0.00 7 931249 97723 0 0
sp_cancel_write_off 10713 10713 0 0.00 1 16903 100 0 0
sp_client_order 19593 19593 0 0.00 3 18854 368 0 0
sp_customer_reserve 451870 451870 0 0.00 0 119638 171 0 0
sp_fill_shopping_cart 30213 30213 0 0.00 1 11648 37 0 0
sp_fill_shopping_cart_clo_res 451870 451870 0 0.00 0 16098 14 0 0
sp_get_clo_for_invoice 4876 4876 0 0.00 0 14018 301 0 0
sp_kill_cost_storno 17704 17704 0 0.00 0 16544 54 0 0
sp_kill_qstorno_ret_qs2qd 1193709 1193709 0 0.00 0 60594 30 0 0
sp_kill_qty_storno 627972 627972 0 0.00 0 60596 65 0 0
sp_lock_selected_doc 20107 20107 0 0.00 0 8840 7 0 0
sp_make_cost_storno 27069 27069 0 0.00 0 26310 55 0 0
sp_multiply_rows_for_pdistr 27069 27069 0 0.00 0 7526 26 0 0
sp_multiply_rows_for_qdistr 19593 19593 0 0.00 0 18838 177 0 0
sp_pay_from_customer 6849 6849 0 0.00 4 11138 102 0 0
sp_pay_to_supplier 5662 5662 0 0.00 5 10366 119 0 0
sp_payment_common 12511 12511 0 0.00 2 11101 22 0 0
sp_qd_handle_on_cancel_clo 1710 1710 0 0.00 0 11373 332 0 0
sp_qd_handle_on_invoice_upd_sts 10862 10862 0 0.00 0 19893 327 0 0
sp_qd_handle_on_reserve_upd_sts 9687 9687 0 0.00 0 19232 47 0 0
sp_reserve_write_off 9687 9687 0 0.00 6 26456 173 0 0
sp_supplier_invoice 5309 5309 0 0.00 5 92836 3177 0 0
sp_supplier_order 5316 5316 0 0.00 5 35728 1260 0 0
srv_aggregate_perf_data 94 94 0 0.00 2320 616696 25561 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 2788 131389 46868 0 0
srv_find_qd_qs_mism 1078417 1078417 0 0.00 0 12379 5 0 0
srv_make_invnt_saldo 498 498 0 0.00 22 104714 1225 0 0
srv_make_money_saldo 689 689 0 0.00 1 33624 71 0 0
srv_recalc_idx_stat 4 4 0 0.00 170337 479416 334894 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 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4877 4877 0 0.00 0 13423 179 0 0
t$perf-norm:sp_cancel_adding_invoice 400 400 0 0.00 0 6158 78 0 0
t$perf-norm:sp_cancel_client_order 1710 1710 0 0.00 0 933 9 0 0
t$perf-norm:sp_cancel_customer_reserve 680 680 0 0.00 0 575 8 0 0
t$perf-norm:sp_cancel_pay_from_customer 601 601 0 0.00 0 299 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 399 399 0 0.00 0 4981 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 388 388 0 0.00 0 493 12 0 0
t$perf-norm:sp_cancel_supplier_order 428 428 0 0.00 0 25940 1759 0 0
t$perf-norm:sp_cancel_write_off 943 943 0 0.00 0 5069 10 0 0
t$perf-norm:sp_client_order 19593 19593 0 0.00 0 9075 20 0 0
t$perf-norm:sp_customer_reserve 961 961 0 0.00 0 5959 14 0 0
t$perf-norm:sp_pay_from_customer 6849 6849 0 0.00 0 6332 11 0 0
t$perf-norm:sp_pay_to_supplier 5662 5662 0 0.00 0 6226 9 0 0
t$perf-norm:sp_reserve_write_off 9687 9687 0 0.00 0 6761 11 0 0
t$perf-norm:sp_supplier_invoice 5309 5309 0 0.00 0 5908 21 0 0
t$perf-norm:sp_supplier_order 5316 5316 0 0.00 0 11409 19 0 0
t$perf-norm:srv_aggregate_perf_data 94 94 0 0.00 0 668 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 334 14 0 0
t$perf-norm:srv_make_invnt_saldo 498 498 0 0.00 0 10373 45 0 0
t$perf-norm:srv_make_money_saldo 689 689 0 0.00 0 1321 16 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9964 9742 222 2.22 0 9594 26 0 222
v_all_customers 35858 35858 0 0.00 0 17 0 0 0
v_all_suppliers 29460 29460 0 0.00 0 19 1 0 0
v_all_wares 39182 39182 0 0.00 0 24 1 0 0
v_cancel_adding_invoice 800 800 0 0.00 0 2277 13 0 0
v_cancel_client_order 3420 3420 0 0.00 0 2718 6 0 0
v_cancel_customer_prepayment 1202 1202 0 0.00 0 1493 8 0 0
v_cancel_customer_reserve 1360 1360 0 0.00 0 262 3 0 0
v_cancel_payment_to_supplier 798 798 0 0.00 0 1035 4 0 0
v_cancel_supplier_invoice 796 776 20 2.51 0 2604 36 0 20
v_cancel_supplier_order 856 856 0 0.00 0 866 6 0 0
v_cancel_write_off 1886 1886 0 0.00 0 3041 21 0 0
v_max_id_clo_ord 5315 5315 0 0.00 0 1050 1 0 0
v_max_id_clo_res 961 961 0 0.00 0 6894 26 0 0
v_max_id_ord_sup 5307 5307 0 0.00 0 4057 4 0 0
v_max_non_paid_invoice 1550 1550 0 0.00 0 317 3 0 0
v_max_non_paid_realizn 3652 3652 0 0.00 0 4510 5 0 0
v_min_id_clo_ord 5315 5315 0 0.00 0 1239 4 0 0
v_min_id_clo_res 961 961 0 0.00 0 4275 12 0 0
v_min_id_ord_sup 5307 5307 0 0.00 0 6165 8 0 0
v_min_non_paid_invoice 5662 5662 0 0.00 0 10351 15 0 0
v_min_non_paid_realizn 6849 6849 0 0.00 0 11095 16 0 0
v_our_firm 9718 9718 0 0.00 0 57 1 0 0
v_reserve_write_off 19374 19374 0 0.00 0 3937 8 0 0
x_lock_depdocs_on_canc_invoice 12179 12179 0 0.00 0 17633 116 0 0
x_lock_depdocs_on_canc_sup_ord 428 428 0 0.00 0 6286 342 0 0
x_make_qstorno_1000_1200 5316 5316 0 0.00 1 35722 1182 0 0
x_make_qstorno_1200_2000 5309 5309 0 0.00 3 92823 3049 0 0
x_make_qstorno_2100_3300 451870 451870 0 0.00 0 119637 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-04-2022 16:40:20 to 27-04-2022 16:40:24.

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:08:20 9 8192000000 8806553872 8820396032 11514608 0.140 4 1 2 0 11631328 63216 53504
14:09:21 32768 8192000000 9903833328 10214133760 981925952 10.703 100 91 182 1 1012454960 6195872 24333136
14:10:59 22593 8192000000 9960363600 10284269568 1038511280 11.250 100 88 175 0 1069603088 9076608 22015200
14:12:43 19881 8192000000 10002365184 10339041280 1082067232 11.667 100 84 168 1 1114392592 11548400 20776960
14:14:06 10153 8192000000 10050720768 10401095680 1135757200 12.176 100 76 152 1 1165482464 11725952 17999312
14:15:19 10521 8192000000 10086205792 10448809984 1174691216 12.541 100 78 156 0 1205870256 12970992 18208048
14:16:31 7370 8192000000 10121513920 10489671680 1207449296 12.845 100 78 154 2 1238712512 14310784 16952432
14:17:39 3224 8192000000 10162860608 10539630592 1248456080 13.224 100 88 175 2 1285771392 17667520 19647792
14:18:46 1699 8192000000 10197316912 10578145280 1283186176 13.542 100 94 189 1 1318121664 13707200 21228288
14:19:48 32011 8192000000 10210312144 10607955968 1306273312 13.752 100 60 120 3 1330086992 9532336 14281344
14:21:21 8223 8192000000 10203224464 10612887552 1310703440 13.792 100 30 60 2 1324755024 7463600 6587984
14:22:34 8683 8192000000 10210733056 10629197824 1325163776 13.923 100 24 48 2 1337301056 6583728 5553552
14:23:43 54127 8192000000 10228430464 10641354752 1331642656 13.982 100 30 60 0 1347473072 8982032 6848384
14:25:38 5856 8192000000 10233207856 10650849280 1336513872 14.026 100 23 46 0 1347675264 6241168 4920224
14:26:47 6506 8192000000 10234815680 10655318016 1343211536 14.086 100 33 66 1 1356782208 5481232 8089440
14:27:55 8253 8192000000 10221181376 10636640256 1317885440 13.858 99 14 28 1 1327115824 6091824 3138560
14:29:07 28000 8192000000 10188397216 10605645824 1271910096 13.439 96 19 38 2 1281383632 4935120 4538416
14:30:36 5879 8192000000 10174973344 10581090304 1236236688 13.112 95 29 56 0 1248215040 5584736 6393616
14:31:43 6235 8192000000 10129595392 10527264768 1237464368 13.123 99 25 50 0 1249388176 6274752 5649056
14:32:51 3465 8192000000 10153912512 10553696256 1256709408 13.300 100 26 50 1 1268829936 5963968 6156560
14:33:54 41327 8192000000 10149961040 10554822656 1220505424 12.966 96 19 38 0 1229841872 4712592 4623856
14:35:36 7374 8192000000 10174119056 10590744576 1288065104 13.587 100 17 33 0 1297244064 5351568 3827392
14:36:46 10060 8192000000 10203002304 10612592640 1303766640 13.729 100 38 75 4 1318487360 5380928 9339792
14:37:57 62422 8192000000 10225429920 10638815232 1323001200 13.904 100 18 36 0 1332541968 4545824 4994944
14:40:11 19270 8192000000 10255550528 10668847104 1350055360 14.148 100 38 76 0 1367458640 8681232 8722048
14:41:35 13259 8192000000 10270958656 10690392064 1366003088 14.291 100 52 101 2 1388822736 11727648 11092000
14:42:53 17960 8192000000 10274842864 10703466496 1376208016 14.383 100 38 76 2 1395134848 9846560 9080272
14:44:24 15977 8192000000 10296980832 10718646272 1383504224 14.448 100 48 96 1 1406937680 11436768 11996688
14:45:46 8170 8192000000 10293704992 10715238400 1363773968 14.271 98 45 90 1 1388013936 13410736 10829232
14:47:11 5268 8192000000 10323259168 10745098240 1399662976 14.592 100 73 143 3 1436418544 19022560 17733008
14:48:28 2787 8192000000 10333730736 10753581056 1403575984 14.627 99 62 124 3 1443077232 23503808 15997440
14:49:41 9632 8192000000 10351578656 10778947584 1422676720 14.796 100 45 90 0 1457273216 23120816 11475680
14:50:57 4043 8192000000 10344109248 10768850944 1416862112 14.745 100 54 108 3 1457794320 27271952 13660256
14:52:05 4925 8192000000 10367579008 10790133760 1428529408 14.848 100 65 130 2 1474947072 30256224 16161440
14:53:17 7991 8192000000 10356114896 10772533248 1411512352 14.697 100 75 150 3 1464440784 33796816 19131616
14:54:34 5408 8192000000 10365370160 10783064064 1413341440 14.714 100 76 154 1 1473045520 40319360 19384720
14:55:45 4420 8192000000 10347283824 10758459392 1374596672 14.368 99 66 130 3 1439165168 47144672 17423824
14:56:57 11887 8192000000 10327836480 10763227136 1378688656 14.405 100 39 78 0 1442963184 53372080 10902448
14:58:23 7708 8192000000 10311098160 10725359616 1359020880 14.229 100 60 119 0 1414277520 39866304 15390336
14:59:37 3634 8192000000 10319785856 10730885120 1359231456 14.230 100 69 137 1 1422500208 45600640 17668112
15:00:43 18219 8192000000 10305978560 10732675072 1369808128 14.325 100 27 54 4 1410859648 33918672 7132848
15:02:03 13980 8192000000 10308284688 10742374400 1366549952 14.296 100 28 56 2 1414768240 40819552 7398736
15:03:24 11908 8192000000 10300622416 10737811456 1362666624 14.261 100 34 68 1 1408724352 37478096 8579632
15:04:40 23494 8192000000 10288435360 10724184064 1339112048 14.049 99 25 50 1 1384025968 37614720 7299200
15:06:06 36071 8192000000 10268609040 10714529792 1337649824 14.036 99 18 37 2 1376857904 34332336 4875744
15:07:43 44966 8192000000 10289776736 10744213504 1366067184 14.292 100 12 24 0 1392542880 23570976 2904720
15:10:35 33163 8192000000 10286159216 10706284544 1330085536 13.968 97 44 83 1 1349795504 10437248 9272720
15:12:18 102763 8192000000 10269261344 10717564928 1335545648 14.017 97 26 52 1 1353618640 11944928 6128064
15:15:02 84281 8192000000 10286661392 10725773312 1348025024 14.130 98 26 51 1 1363560112 9654976 5880112
15:18:00 120851 8192000000 10289465152 10754404352 1377077584 14.390 99 31 62 1 1402460544 17841136 7541824
15:21:14 18885 8192000000 10296750160 10755227648 1388700912 14.494 100 14 29 0 1407833104 15781312 3350880
15:22:34 50019 8192000000 10309869152 10758537216 1399206864 14.588 100 13 26 0 1416524880 14208208 3109808
15:24:27 110158 8192000000 10312469504 10776842240 1406587264 14.654 100 12 24 0 1430258224 20764688 2906272
15:27:18 63765 8192000000 10300604720 10758262784 1367391776 14.304 97 27 54 0 1383979424 10402000 6185648
15:30:53 41656 8192000000 10328584736 10780639232 1413764688 14.717 100 27 53 1 1433369360 13462576 6142096
15:32:44 51236 8192000000 10320736464 10782765056 1413950752 14.719 100 15 30 0 1436351312 19056336 3344224
15:34:36 76794 8192000000 10333273152 10795479040 1427688256 14.841 100 17 34 0 1452098032 21181872 3227904
15:36:59 37229 8192000000 10310684560 10756771840 1380006800 14.417 98 34 69 0 1397021792 9015776 7999216
15:38:44 32491 8192000000 10283455856 10733404160 1334403648 14.007 95 35 70 1 1356568480 14625312 7539520
15:40:18 81831 8192000000 10277751872 10732199936 1298795696 13.684 93 12 24 0 1312909232 11937264 2176272
15:42:42 104084 8192000000 10267291344 10704936960 1242561648 13.170 89 29 58 1 1261163776 11726448 6875680
15:45:33 44678 8192000000 10242608336 10696728576 1324541248 13.918 98 34 68 0 1342198752 9983376 7674128
15:47:23 56466 8192000000 10276125008 10717483008 1345440736 14.106 99 44 86 0 1370776544 14804304 10531504
15:49:24 38327 8192000000 10249181552 10708848640 1351370960 14.160 100 13 26 1 1364576832 10149088 3056784
15:51:44 84885 8192000000 10262479376 10723004416 1345980576 14.111 98 15 30 1 1364421296 14721840 3718880
15:54:45 72561 8192000000 10269880624 10728296448 1343852448 14.092 98 11 22 0 1368552720 21591808 3108464
15:57:06 19151 8192000000 10266206736 10706038784 1346808880 14.119 99 31 62 0 1360222224 6455664 6957680
15:58:27 71635 8192000000 10293932080 10731933696 1361163968 14.248 99 34 68 0 1382668768 14058000 7446800
16:00:42 41680 8192000000 10274146960 10715353088 1344881296 14.101 99 15 30 0 1361104128 12891888 3330944
16:02:33 54346 8192000000 10286324416 10722885632 1353486656 14.179 99 22 44 3 1368834016 10621728 4725632
16:04:28 34170 8192000000 10303969872 10746195968 1367891344 14.308 99 30 60 0 1393405072 18897504 6616224
16:06:03 57318 8192000000 10286142512 10730553344 1343276272 14.087 98 21 42 0 1369087248 21197184 4613792
16:08:01 105726 8192000000 10273322592 10733117440 1363471344 14.269 100 12 24 0 1386556880 20612064 2473472
16:11:07 67500 8192000000 10284814512 10758148096 1381518336 14.430 100 3 6 0 1401689728 19787024 384368
16:13:16 56872 8192000000 10305457920 10767892480 1394281408 14.544 100 16 32 0 1411349696 13351472 3716816
16:15:33 59235 8192000000 10306620016 10759929856 1383545136 14.448 99 15 30 1 1400893808 13741840 3606832
16:18:30 40193 8192000000 10323152000 10769944576 1409233184 14.677 100 37 74 1 1426830896 9192384 8405328
16:20:19 62034 8192000000 10316715712 10773921792 1407731328 14.664 100 22 44 0 1423656000 10901136 5023536
16:23:05 93372 8192000000 10313829696 10774130688 1365656112 14.288 97 8 15 1 1382762480 16000848 1105520
16:26:17 113190 8192000000 10311243824 10767630336 1342837472 14.083 96 9 18 0 1356258192 11447200 1973520
16:29:14 131389 8192000000 10301209088 10763694080 1384365888 14.456 99 24 46 3 1402073712 12027920 5679904
16:32:52 52152 8192000000 10259821344 10720784384 1354098944 14.184 98 11 22 0 1365546160 9147728 2299488
16:34:44 54291 8192000000 10281200576 10736254976 1366667872 14.297 99 16 32 1 1377848368 7327424 3853072
16:36:41 85137 8192000000 10262761408 10741645312 1368875920 14.317 100 13 27 0 1388041472 16162912 3002640

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-04-2022 16:40:26 to 27-04-2022 16:40:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 111 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-04-2022 16:40:41 to 27-04-2022 16:40:43.

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          235560
MON$OLDEST_ACTIVE               235561
MON$OLDEST_SNAPSHOT             235561
MON$NEXT_TRANSACTION            235561
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                       2033056
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'

27.04.22 16:40:44. 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-27 16:40:47 No severe PSQL-related problems occured
27.04.22 16:40:48. End of report.