Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.01.2022 19:40
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16595112 kB
MemAvailable 31356612 kB
Buffers 0 kB
Cached 15107596 kB
SwapCached 1196 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.9
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-01-03 19:38:05
DTS_END                         2022-01-03 19:38:05.1780
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/53186
TRN_ID                          238688
ATT_ID                          718
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: 9171.88 24570 1100625
customer order: creation 171.20 354 20544
customer order: refuse 14.41 360 1729
order to supplier: creation 45.68 1208 5482
order to supplier: removal 3.38 102904 406
invoice (draft): creation 46.28 3504 5553
invoice (draft): removal 52.01 776 6241
invoice accept: apply 42.11 16857 5053
invoice accept: cancel 46.85 772 5622
customer reserve: creation 3847.10 167 461652
customer reserve: removal 4602.02 60 552243
realization accept: apply 83.88 175 10066
realization accept: cancel 87.49 97 10499
payment from customer: creation 59.10 112 7092
payment from customer: removal 5.85 74 702
payment to supplier: creation 48.77 115 5852
payment to supplier: removal 3.48 82 417
service: total inventory turnovers 4.72 925 567
service: total monetary turnovers 6.64 24 797
service: aggregate perf. data 0.87 17019 104
service: refresh index statistics 0.03 345806 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-01-2022 19:40:23 to 03-01-2022 19:40:25.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1816
TEST_TIME 2 4423
TEST_TIME 3 4096
TEST_TIME 4 4218
TEST_TIME 5 4154
TEST_TIME 6 4546
TEST_TIME 7 5031
TEST_TIME 8 5590
TEST_TIME 9 5988
TEST_TIME 10 6041
TEST_TIME 11 6734
TEST_TIME 12 6850
TEST_TIME 13 7548
TEST_TIME 14 7529
TEST_TIME 15 7510
TEST_TIME 16 8394
TEST_TIME 17 8626
TEST_TIME 18 9072
TEST_TIME 19 8875
TEST_TIME 20 9285
TEST_TIME 21 9167
TEST_TIME 22 9461
TEST_TIME 23 9311
TEST_TIME 24 9531
TEST_TIME 25 10329
TEST_TIME 26 10775
TEST_TIME 27 11190
TEST_TIME 28 11741
TEST_TIME 29 11368
TEST_TIME 30 11146
TEST_TIME 31 11143
TEST_TIME 32 11220
TEST_TIME 33 11075
TEST_TIME 34 10847
TEST_TIME 35 10683
TEST_TIME 36 10533
TEST_TIME 37 10445
TEST_TIME 38 10302
TEST_TIME 39 10235
TEST_TIME 40 10012
TEST_TIME 41 10066
TEST_TIME 42 10052
TEST_TIME 43 10137
TEST_TIME 44 10042
TEST_TIME 45 9894
TEST_TIME 46 9803
TEST_TIME 47 9829
TEST_TIME 48 9795
TEST_TIME 49 9663
TEST_TIME 50 9548
TEST_TIME 51 9457
TEST_TIME 52 9457
TEST_TIME 53 9500
TEST_TIME 54 9455
TEST_TIME 55 9495
TEST_TIME 56 9453
TEST_TIME 57 9428
TEST_TIME 58 9552
TEST_TIME 59 9544
TEST_TIME 60 9468
TEST_TIME 61 9510
TEST_TIME 62 9594
TEST_TIME 63 9564
TEST_TIME 64 9591
TEST_TIME 65 9581
TEST_TIME 66 9579
TEST_TIME 67 9638
TEST_TIME 68 9514
TEST_TIME 69 9536
TEST_TIME 70 9450
TEST_TIME 71 9395
TEST_TIME 72 9353
TEST_TIME 73 9344
TEST_TIME 74 9347
TEST_TIME 75 9378
TEST_TIME 76 9341
TEST_TIME 77 9370
TEST_TIME 78 9384
TEST_TIME 79 9352
TEST_TIME 80 9377
TEST_TIME 81 9393
TEST_TIME 82 9410
TEST_TIME 83 9390
TEST_TIME 84 9422
TEST_TIME 85 9440
TEST_TIME 86 9428
TEST_TIME 87 9458
TEST_TIME 88 9434
TEST_TIME 89 9378
TEST_TIME 90 9402
TEST_TIME 91 9367
TEST_TIME 92 9356
TEST_TIME 93 9293
TEST_TIME 94 9278
TEST_TIME 95 9264
TEST_TIME 96 9257
TEST_TIME 97 9271
TEST_TIME 98 9255
TEST_TIME 99 9219
TEST_TIME 100 9300
TEST_TIME 101 9274
TEST_TIME 102 9318
TEST_TIME 103 9325
TEST_TIME 104 9314
TEST_TIME 105 9402
TEST_TIME 106 9412
TEST_TIME 107 9368
TEST_TIME 108 9355
TEST_TIME 109 9502
TEST_TIME 110 9480
TEST_TIME 111 9423
TEST_TIME 112 9370
TEST_TIME 113 9359
TEST_TIME 114 9337
TEST_TIME 115 9326
TEST_TIME 116 9296
TEST_TIME 117 9263
TEST_TIME 118 9286
TEST_TIME 119 9238
TEST_TIME 120 9173

Performance per minute, chart


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

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 1528896 1528896 0 0.00 0 21146 6 0 0
doc_list_biud 1528896 1528896 0 0.00 0 101402 27 0 0
sp_add_invoice_to_stock 5184 5053 131 2.52 28 162897 16857 0 131
sp_cancel_adding_invoice 5622 5622 0 0.00 6 56474 773 0 0
sp_cancel_client_order 1729 1729 0 0.00 5 29428 360 0 0
sp_cancel_customer_reserve 552243 552243 0 0.00 1 28170 61 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 2 4709 74 0 0
sp_cancel_pay_to_supplier 417 417 0 0.00 3 3521 82 0 0
sp_cancel_supplier_invoice 6252 6241 11 0.17 2 101408 776 0 11
sp_cancel_supplier_order 406 406 0 0.00 7 967817 102905 0 0
sp_cancel_write_off 10499 10499 0 0.00 1 10900 98 0 0
sp_client_order 20544 20544 0 0.00 3 25666 355 0 0
sp_customer_reserve 461652 461652 0 0.00 0 84364 168 0 0
sp_fill_shopping_cart 31565 31565 0 0.00 1 35154 42 0 0
sp_fill_shopping_cart_clo_res 461648 461648 0 0.00 0 35332 15 0 0
sp_get_clo_for_invoice 5048 5048 0 0.00 0 45795 390 0 0
sp_kill_cost_storno 17240 17240 0 0.00 0 5759 50 0 0
sp_kill_qstorno_ret_qs2qd 1121632 1121632 0 0.00 0 101398 33 0 0
sp_kill_qty_storno 591854 591854 0 0.00 0 101399 70 0 0
sp_lock_selected_doc 20944 20944 0 0.00 0 7701 4 0 0
sp_make_cost_storno 28058 28058 0 0.00 0 21134 48 0 0
sp_multiply_rows_for_pdistr 28058 28058 0 0.00 0 9011 26 0 0
sp_multiply_rows_for_qdistr 20544 20544 0 0.00 0 18633 150 0 0
sp_pay_from_customer 7092 7092 0 0.00 4 17160 112 0 0
sp_pay_to_supplier 5852 5852 0 0.00 5 15080 115 0 0
sp_payment_common 12944 12944 0 0.00 1 17155 24 0 0
sp_qd_handle_on_cancel_clo 1729 1729 0 0.00 0 29419 331 0 0
sp_qd_handle_on_invoice_upd_sts 10670 10670 0 0.00 0 14156 299 0 0
sp_qd_handle_on_reserve_upd_sts 10066 10066 0 0.00 0 16173 67 0 0
sp_reserve_write_off 10066 10066 0 0.00 5 16190 176 0 0
sp_supplier_invoice 5553 5553 0 0.00 6 100834 3505 0 0
sp_supplier_order 5482 5482 0 0.00 5 36836 1209 0 0
srv_aggregate_perf_data 104 104 0 0.00 2634 108064 17019 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 2420 141491 49484 0 0
srv_find_qd_qs_mism 1053351 1053351 0 0.00 0 13753 5 0 0
srv_make_invnt_saldo 567 567 0 0.00 34 28388 926 0 0
srv_make_money_saldo 797 797 0 0.00 0 1597 25 0 0
srv_recalc_idx_stat 4 4 0 0.00 206674 436119 345807 0 0
t$perf-abend:sp_add_invoice_to_stock 131 131 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5053 5053 0 0.00 0 12133 148 0 0
t$perf-norm:sp_cancel_adding_invoice 388 388 0 0.00 1 6988 118 0 0
t$perf-norm:sp_cancel_client_order 1729 1729 0 0.00 0 3619 16 0 0
t$perf-norm:sp_cancel_customer_reserve 704 704 0 0.00 0 675 5 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 5040 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 417 417 0 0.00 0 560 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 401 401 0 0.00 0 796 13 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 19672 1491 0 0
t$perf-norm:sp_cancel_write_off 1083 1083 0 0.00 0 8701 13 0 0
t$perf-norm:sp_client_order 20544 20544 0 0.00 0 8410 19 0 0
t$perf-norm:sp_customer_reserve 1021 1021 0 0.00 0 4403 9 0 0
t$perf-norm:sp_pay_from_customer 7092 7092 0 0.00 0 12619 13 0 0
t$perf-norm:sp_pay_to_supplier 5852 5852 0 0.00 0 3729 8 0 0
t$perf-norm:sp_reserve_write_off 10066 10066 0 0.00 0 14194 16 0 0
t$perf-norm:sp_supplier_invoice 5553 5553 0 0.00 0 6085 17 0 0
t$perf-norm:sp_supplier_order 5482 5482 0 0.00 0 6711 16 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 425 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 130 3 0 0
t$perf-norm:srv_make_invnt_saldo 567 567 0 0.00 0 1300 23 0 0
t$perf-norm:srv_make_money_saldo 797 797 0 0.00 0 3532 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 10358 10096 262 2.52 0 9121 22 0 262
v_all_customers 37354 37354 0 0.00 0 26 1 0 0
v_all_suppliers 30408 30408 0 0.00 0 24 1 0 0
v_all_wares 41088 41088 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 776 776 0 0.00 0 2948 13 0 0
v_cancel_client_order 3458 3458 0 0.00 0 2773 7 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 1600 5 0 0
v_cancel_customer_reserve 1408 1408 0 0.00 0 2873 9 0 0
v_cancel_payment_to_supplier 834 834 0 0.00 0 1684 9 0 0
v_cancel_supplier_invoice 824 802 22 2.66 0 534 14 0 22
v_cancel_supplier_order 812 812 0 0.00 0 532 5 0 0
v_cancel_write_off 2166 2166 0 0.00 0 1875 13 0 0
v_max_id_clo_ord 5482 5482 0 0.00 0 1653 1 0 0
v_max_id_clo_res 1021 1021 0 0.00 0 1794 14 0 0
v_max_id_ord_sup 5539 5539 0 0.00 0 2511 2 0 0
v_max_non_paid_invoice 1669 1669 0 0.00 0 599 2 0 0
v_max_non_paid_realizn 3793 3793 0 0.00 0 5496 6 0 0
v_min_id_clo_ord 5482 5482 0 0.00 0 5710 7 0 0
v_min_id_clo_res 1021 1021 0 0.00 0 10220 16 0 0
v_min_id_ord_sup 5539 5539 0 0.00 0 8783 9 0 0
v_min_non_paid_invoice 5852 5852 0 0.00 0 15069 17 0 0
v_min_non_paid_realizn 7092 7092 0 0.00 0 17149 17 0 0
v_our_firm 10332 10332 0 0.00 0 73 1 0 0
v_reserve_write_off 20132 20132 0 0.00 0 4704 7 0 0
x_lock_depdocs_on_canc_invoice 11462 11462 0 0.00 0 13023 115 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 6923 337 0 0
x_make_qstorno_1000_1200 5482 5482 0 0.00 1 36823 1134 0 0
x_make_qstorno_1200_2000 5553 5553 0 0.00 3 100752 3340 0 0
x_make_qstorno_2100_3300 461652 461652 0 0.00 0 84364 151 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-01-2022 19:40:31 to 03-01-2022 19:40:35.

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:08 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:10 29911 8192000000 9916251280 10232705024 995473328 10.835 100 87 174 0 1024834560 6576160 22785072
17:10:45 22241 8192000000 9963566688 10290802688 1042454960 11.288 100 89 178 0 1073948240 9023024 22470256
17:12:14 15443 8192000000 9987406144 10325434368 1074773184 11.598 100 86 172 1 1107239920 11174848 21291888
17:13:34 10863 8192000000 10039292368 10388549632 1122512720 12.051 100 82 164 1 1154416976 12621568 19282688
17:14:48 9132 8192000000 10081003248 10439327744 1169989728 12.497 100 86 170 2 1202412608 12959168 19463712
17:15:59 5586 8192000000 10131471264 10498564096 1215879552 12.924 100 85 171 1 1248977696 14198864 18899280
17:17:06 4209 8192000000 10170444096 10544611328 1251957024 13.256 100 85 168 1 1284138864 13652368 18529472
17:18:12 2549 8192000000 10189917760 10570854400 1274211696 13.460 100 85 171 2 1307175440 13700192 19263552
17:19:17 3608 8192000000 10202001072 10586996736 1289554160 13.600 100 86 172 2 1321373072 11561120 20257792
17:20:22 4459 8192000000 10217953920 10610044928 1306321184 13.753 100 86 172 0 1336368720 9607216 20440320
17:21:29 2965 8192000000 10207430864 10615218176 1313110624 13.814 100 50 100 1 1333303104 8861744 11330736
17:22:33 8128 8192000000 10200073920 10618384384 1319090336 13.868 100 21 42 1 1329429104 5942880 4395888
17:23:45 9467 8192000000 10205788832 10625773568 1322614752 13.900 100 13 27 1 1331049552 5744432 2690368
17:24:54 46735 8192000000 10246173088 10652839936 1331841376 13.984 100 56 112 0 1351138416 5351232 13945808
17:26:41 5725 8192000000 10237423328 10659463168 1340676848 14.064 100 31 62 4 1353803904 5501248 7625808
17:27:49 12487 8192000000 10233394032 10656190464 1341948240 14.075 100 19 36 2 1352133248 5950256 4234752
17:29:07 22233 8192000000 10211262512 10633154560 1293408848 13.635 97 18 36 0 1302429328 4865104 4155376
17:30:30 8090 8192000000 10162358208 10567897088 1242221984 13.167 97 27 52 1 1253212480 5255760 5734736
17:31:38 9282 8192000000 10110205552 10508820480 1201032768 12.786 97 20 41 2 1211409952 5758144 4619040
17:32:48 63235 8192000000 10143624752 10550861824 1253981360 13.275 100 33 66 2 1267104432 5758208 7364864
17:34:55 5513 8192000000 10169031744 10573692928 1273214896 13.451 100 37 74 0 1288955040 7377488 8362656
17:36:01 8622 8192000000 10173682816 10583658496 1281684336 13.528 100 29 58 1 1294819632 6138944 6996352
17:37:12 12907 8192000000 10176401008 10590900224 1291149120 13.615 100 14 28 1 1299851376 5455744 3246512
17:38:25 85524 8192000000 10226684544 10635595776 1310090336 13.787 100 37 74 1 1328043568 9501104 8452128
17:41:41 14331 8192000000 10265890448 10677211136 1350582416 14.153 100 46 92 0 1376760240 15657824 10520000
17:43:07 27695 8192000000 10273230112 10692755456 1360228720 14.239 100 38 77 3 1388882512 19354176 9299616
17:44:38 12728 8192000000 10290070560 10706407424 1369578144 14.323 100 45 88 2 1400760176 20732720 10449312
17:46:00 3601 8192000000 10316814048 10728112128 1383041440 14.444 100 63 124 1 1422160912 24516848 14602624
17:47:09 4414 8192000000 10332422464 10745896960 1392391104 14.527 100 74 148 3 1438044048 28696736 16956208
17:48:18 5001 8192000000 10339632768 10762358784 1407382592 14.661 100 61 122 0 1453668496 31593424 14692480
17:49:28 5288 8192000000 10352178720 10774351872 1410264480 14.686 100 60 120 0 1459609936 34576656 14768800
17:50:39 2418 8192000000 10348463360 10758193152 1394872176 14.549 100 77 154 0 1451270432 37485712 18912544
17:51:50 7085 8192000000 10334157680 10758320128 1394590496 14.547 100 45 88 1 1445400112 38797120 12012496
17:53:04 2655 8192000000 10337094192 10746724352 1377886032 14.398 99 70 138 2 1438604448 43099792 17618624
17:54:12 3424 8192000000 10369683792 10782978048 1394918960 14.550 100 75 149 0 1472682976 58391744 19372272
17:55:20 5610 8192000000 10343243264 10764992512 1387683232 14.485 100 56 112 0 1447357872 45282800 14391840
17:56:31 7330 8192000000 10310126176 10729177088 1344566448 14.099 98 37 74 1 1404235008 50046576 9621984
17:57:41 3107 8192000000 10329685440 10746200064 1375260576 14.374 100 57 114 4 1436249616 46593808 14395232
17:58:49 9080 8192000000 10317289456 10739048448 1360192816 14.239 99 35 69 2 1417477696 47768208 9516672
18:00:04 8404 8192000000 10354372592 10781364224 1391787952 14.522 100 38 76 1 1454428928 51823664 10817312
18:01:26 9976 8192000000 10350822432 10782457856 1390299232 14.509 100 34 68 5 1455912288 56259712 9353344
18:02:44 15945 8192000000 10336111792 10768560128 1374958512 14.371 100 27 54 1 1441806000 58853888 7993600
18:04:04 22214 8192000000 10308229616 10747387904 1342166736 14.077 99 23 46 0 1401790592 53057792 6566064
18:05:27 29734 8192000000 10285245184 10733469696 1339288000 14.051 99 22 43 1 1387093984 41794992 6010992
18:07:00 51179 8192000000 10284300624 10712166400 1350710704 14.154 99 49 97 2 1373822160 12168976 10942480
18:08:52 8580 8192000000 10300273840 10724646912 1368523824 14.314 100 64 126 3 1398171136 13867760 15779552
18:10:02 22582 8192000000 10293004240 10751680512 1381370944 14.429 100 9 19 2 1396502928 13242160 1889824
18:11:30 84272 8192000000 10284939568 10740707328 1378140432 14.400 100 14 27 3 1394650416 14440096 2069888
18:13:55 94766 8192000000 10282667744 10728923136 1363935440 14.273 98 16 32 0 1376438512 8928624 3574448
18:16:37 13180 8192000000 10292213440 10738028544 1381912864 14.434 100 22 43 1 1399926608 13156544 4857200
18:17:51 101137 8192000000 10289126800 10732560384 1360437280 14.241 98 28 56 0 1379264000 11672016 7154704
18:20:36 81051 8192000000 10283202896 10740543488 1388131776 14.489 100 19 38 2 1408273184 15899424 4241984
18:22:58 35759 8192000000 10277745696 10726899712 1376890816 14.389 99 22 44 0 1389592368 7565840 5135712
18:24:35 39349 8192000000 10313400800 10753966080 1395754960 14.557 100 21 42 0 1410174272 9481568 4937744
18:26:29 45570 8192000000 10307335376 10765967360 1400230560 14.597 100 17 34 1 1413901296 9685264 3985472
18:28:17 70612 8192000000 10292894064 10755452928 1373414064 14.358 98 24 48 2 1394453040 14990960 6048016
18:30:30 32547 8192000000 10293792720 10758909952 1381530464 14.430 99 9 18 0 1395437072 12133248 1773360
18:32:03 77708 8192000000 10276164464 10742628352 1325412288 13.926 95 12 24 0 1341562096 13849472 2300336
18:34:46 57571 8192000000 10294377424 10750971904 1388401056 14.492 100 32 64 2 1409999392 14352656 7245680
18:36:45 94393 8192000000 10270243040 10718347264 1322559488 13.900 95 11 22 0 1336409184 11795648 2054048
18:39:20 121434 8192000000 10287471344 10739195904 1304380208 13.735 94 14 28 0 1325819984 18225792 3213984
18:42:32 34718 8192000000 10279369136 10725085184 1327259360 13.942 96 45 91 3 1350616304 13567312 9789632
18:44:08 93333 8192000000 10265521072 10697740288 1277760240 13.493 93 22 44 0 1296922672 14209296 4953136
18:46:42 86802 8192000000 10237187424 10681147392 1318028144 13.859 98 40 80 0 1342653600 15560704 9064752
18:49:11 7597 8192000000 10255479248 10693652480 1347596192 14.126 100 44 83 1 1364113232 6898576 9618464
18:50:19 47995 8192000000 10252588992 10705481728 1344336560 14.096 99 11 22 1 1358236560 11075808 2824192
18:52:07 48734 8192000000 10242167776 10685747200 1302652736 13.719 96 16 32 0 1318385008 11942160 3790112
18:53:56 74659 8192000000 10257626288 10710683648 1328389952 13.953 98 13 26 0 1341388688 9852416 3146320
18:56:33 57523 8192000000 10249576944 10692038656 1332164304 13.987 99 24 49 1 1350888896 13366032 5358560
18:58:43 109053 8192000000 10264836048 10714734592 1302756480 13.720 96 10 20 1 1316497296 11660800 2080016
19:01:33 29158 8192000000 10283947616 10722783232 1357838352 14.218 99 38 76 0 1379995632 12682992 9474288
19:03:09 100093 8192000000 10286155280 10731704320 1375659552 14.378 100 22 44 1 1390995568 10703536 4632480
19:05:55 141491 8192000000 10288138080 10742292480 1373842880 14.361 99 20 40 1 1393307568 14990768 4473920
19:09:41 86469 8192000000 10287508048 10751983616 1371157760 14.337 98 11 22 1 1383443664 9636720 2649184
19:12:08 72671 8192000000 10284324160 10746798080 1345814944 14.110 96 8 16 0 1361537536 14550624 1171968
19:14:21 76887 8192000000 10280438944 10735726592 1336394656 14.025 96 25 50 0 1351659216 8726176 6538384
19:16:40 53004 8192000000 10320749808 10764034048 1387867696 14.487 99 24 48 0 1402431600 9221120 5342784
19:19:04 70149 8192000000 10311010144 10769530880 1400064768 14.596 100 14 28 0 1416946272 13458144 3423360
19:21:23 102307 8192000000 10304370416 10767978496 1355998352 14.201 97 10 20 0 1371269808 13149072 2122384
19:24:09 106961 8192000000 10329347952 10769743872 1371357072 14.339 98 14 28 0 1386256368 11890032 3009264
19:27:03 47428 8192000000 10309477936 10778292224 1350750096 14.154 97 7 14 0 1379384400 26903728 1730576
19:29:01 104998 8192000000 10291418176 10748006400 1348318800 14.132 97 24 48 2 1371788496 17895920 5573776
19:31:47 71897 8192000000 10276556512 10742136832 1378908720 14.407 100 8 16 0 1393849408 13175376 1765312
19:34:00 89791 8192000000 10264563008 10715611136 1300417440 13.699 94 16 32 0 1314463552 10160512 3885600
19:36:35 36973 8192000000 10270503824 10721423360 1368886848 14.317 100 15 28 0 1379075440 6978016 3210576

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 03-01-2022 19:40:37 to 03-01-2022 19:40:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 131 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-01-2022 19:40:52 to 03-01-2022 19:40:54.

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          237067
MON$OLDEST_ACTIVE               238801
MON$OLDEST_SNAPSHOT             238801
MON$NEXT_TRANSACTION            238801
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                       2024064
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.9.33548 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.9.33548 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.9.33548 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.9.33548 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'

03.01.22 19:40:55. 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-01-03 19:40:58 No severe PSQL-related problems occured
03.01.22 19:40:59. End of report.