Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.03.2022 16: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 16794460 kB
MemAvailable 31697784 kB
Buffers 0 kB
Cached 14951424 kB
SwapCached 2972 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-03-22 16:38:08
DTS_END                         2022-03-22 16:38:08.5940
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(57:17)
IP                              ::1/41686
TRN_ID                          237678
ATT_ID                          698
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: 9297.24 20788 1115669
customer order: creation 171.58 377 20589
customer order: refuse 14.01 349 1681
order to supplier: creation 44.35 1259 5322
order to supplier: removal 3.38 106198 406
invoice (draft): creation 45.75 3544 5490
invoice (draft): removal 52.63 849 6315
invoice accept: apply 41.92 18784 5030
invoice accept: cancel 47.64 775 5717
customer reserve: creation 3905.14 184 468617
customer reserve: removal 4675.33 60 561039
realization accept: apply 82.44 174 9893
realization accept: cancel 85.28 106 10233
payment from customer: creation 58.31 111 6997
payment from customer: removal 5.77 60 692
payment to supplier: creation 48.12 129 5774
payment to supplier: removal 3.10 87 372
service: total inventory turnovers 4.83 972 580
service: total monetary turnovers 6.89 30 827
service: aggregate perf. data 0.76 19731 91
service: refresh index statistics 0.03 261981 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-03-2022 16:40:55 to 22-03-2022 16:40:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7007
TEST_TIME 2 5766
TEST_TIME 3 6119
TEST_TIME 4 5424
TEST_TIME 5 5488
TEST_TIME 6 5279
TEST_TIME 7 5718
TEST_TIME 8 5731
TEST_TIME 9 6416
TEST_TIME 10 6463
TEST_TIME 11 6770
TEST_TIME 12 6982
TEST_TIME 13 7075
TEST_TIME 14 7402
TEST_TIME 15 7382
TEST_TIME 16 7711
TEST_TIME 17 7953
TEST_TIME 18 7956
TEST_TIME 19 8371
TEST_TIME 20 8676
TEST_TIME 21 9196
TEST_TIME 22 9366
TEST_TIME 23 9330
TEST_TIME 24 9652
TEST_TIME 25 9753
TEST_TIME 26 10557
TEST_TIME 27 10908
TEST_TIME 28 11309
TEST_TIME 29 11352
TEST_TIME 30 11311
TEST_TIME 31 11161
TEST_TIME 32 11074
TEST_TIME 33 10796
TEST_TIME 34 10807
TEST_TIME 35 10666
TEST_TIME 36 10572
TEST_TIME 37 10535
TEST_TIME 38 10429
TEST_TIME 39 10349
TEST_TIME 40 10242
TEST_TIME 41 10236
TEST_TIME 42 10174
TEST_TIME 43 10411
TEST_TIME 44 10373
TEST_TIME 45 10188
TEST_TIME 46 10113
TEST_TIME 47 10162
TEST_TIME 48 10093
TEST_TIME 49 10122
TEST_TIME 50 10046
TEST_TIME 51 9962
TEST_TIME 52 9946
TEST_TIME 53 9896
TEST_TIME 54 9815
TEST_TIME 55 9694
TEST_TIME 56 9606
TEST_TIME 57 9566
TEST_TIME 58 9549
TEST_TIME 59 9528
TEST_TIME 60 9570
TEST_TIME 61 9574
TEST_TIME 62 9598
TEST_TIME 63 9616
TEST_TIME 64 9672
TEST_TIME 65 9672
TEST_TIME 66 9757
TEST_TIME 67 9712
TEST_TIME 68 9790
TEST_TIME 69 9808
TEST_TIME 70 9776
TEST_TIME 71 9863
TEST_TIME 72 9912
TEST_TIME 73 9874
TEST_TIME 74 9779
TEST_TIME 75 9686
TEST_TIME 76 9636
TEST_TIME 77 9734
TEST_TIME 78 9649
TEST_TIME 79 9615
TEST_TIME 80 9570
TEST_TIME 81 9566
TEST_TIME 82 9603
TEST_TIME 83 9573
TEST_TIME 84 9612
TEST_TIME 85 9568
TEST_TIME 86 9554
TEST_TIME 87 9654
TEST_TIME 88 9688
TEST_TIME 89 9669
TEST_TIME 90 9659
TEST_TIME 91 9660
TEST_TIME 92 9644
TEST_TIME 93 9622
TEST_TIME 94 9645
TEST_TIME 95 9572
TEST_TIME 96 9555
TEST_TIME 97 9514
TEST_TIME 98 9478
TEST_TIME 99 9408
TEST_TIME 100 9362
TEST_TIME 101 9391
TEST_TIME 102 9361
TEST_TIME 103 9387
TEST_TIME 104 9351
TEST_TIME 105 9339
TEST_TIME 106 9336
TEST_TIME 107 9359
TEST_TIME 108 9370
TEST_TIME 109 9378
TEST_TIME 110 9434
TEST_TIME 111 9448
TEST_TIME 112 9550
TEST_TIME 113 9539
TEST_TIME 114 9560
TEST_TIME 115 9511
TEST_TIME 116 9470
TEST_TIME 117 9436
TEST_TIME 118 9394
TEST_TIME 119 9384
TEST_TIME 120 9310

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-03-2022 16:40:58 to 22-03-2022 16:41:02.

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 1547959 1547959 0 0.00 0 22122 6 0 0
doc_list_biud 1547956 1547956 0 0.00 0 57825 28 0 0
sp_add_invoice_to_stock 5079 5030 49 0.96 43 193454 18784 0 49
sp_cancel_adding_invoice 5717 5717 0 0.00 5 59926 775 0 0
sp_cancel_client_order 1681 1681 0 0.00 4 11965 350 0 0
sp_cancel_customer_reserve 561039 561039 0 0.00 1 57828 61 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 3 2510 61 0 0
sp_cancel_pay_to_supplier 372 372 0 0.00 3 5551 87 0 0
sp_cancel_supplier_invoice 6319 6315 4 0.06 2 19286 850 0 4
sp_cancel_supplier_order 406 406 0 0.00 9 1045616 106199 0 0
sp_cancel_write_off 10233 10233 0 0.00 1 9621 106 0 0
sp_client_order 20589 20589 0 0.00 3 26169 377 0 0
sp_customer_reserve 468617 468617 0 0.00 0 47343 185 0 0
sp_fill_shopping_cart 31396 31396 0 0.00 1 22009 40 0 0
sp_fill_shopping_cart_clo_res 468606 468606 0 0.00 0 20210 15 0 0
sp_get_clo_for_invoice 5008 5008 0 0.00 0 21113 327 0 0
sp_kill_cost_storno 17014 17014 0 0.00 0 7466 55 0 0
sp_kill_qstorno_ret_qs2qd 1139032 1139032 0 0.00 0 57816 33 0 0
sp_kill_qty_storno 600292 600292 0 0.00 0 57824 71 0 0
sp_lock_selected_doc 20555 20555 0 0.00 0 7873 6 0 0
sp_make_cost_storno 27672 27672 0 0.00 0 16042 61 0 0
sp_multiply_rows_for_pdistr 27672 27672 0 0.00 0 9969 28 0 0
sp_multiply_rows_for_qdistr 20589 20589 0 0.00 0 20167 174 0 0
sp_pay_from_customer 6997 6997 0 0.00 4 11809 111 0 0
sp_pay_to_supplier 5774 5774 0 0.00 5 14658 129 0 0
sp_payment_common 12771 12771 0 0.00 2 11794 25 0 0
sp_qd_handle_on_cancel_clo 1681 1681 0 0.00 0 6085 315 0 0
sp_qd_handle_on_invoice_upd_sts 10725 10725 0 0.00 0 23613 307 0 0
sp_qd_handle_on_reserve_upd_sts 9893 9893 0 0.00 0 17539 53 0 0
sp_reserve_write_off 9893 9893 0 0.00 6 17585 175 0 0
sp_supplier_invoice 5490 5490 0 0.00 5 85648 3545 0 0
sp_supplier_order 5322 5322 0 0.00 5 27343 1259 0 0
srv_aggregate_perf_data 92 91 1 1.08 2983 271464 19732 0 1
srv_fill_mon_cache_memory 64 64 0 0.00 1819 116989 46205 0 0
srv_find_qd_qs_mism 1067213 1067213 0 0.00 0 22121 6 0 0
srv_make_invnt_saldo 580 580 0 0.00 42 18653 973 0 0
srv_make_money_saldo 827 827 0 0.00 0 8669 31 0 0
srv_recalc_idx_stat 4 4 0 0.00 153910 521536 261982 0 0
t$perf-abend:sp_add_invoice_to_stock 49 49 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 4 4 0 0.00 0 0 0 0 0
t$perf-abend:srv_aggregate_perf_data 1 1 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5030 5030 0 0.00 0 25174 189 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 15462 111 0 0
t$perf-norm:sp_cancel_client_order 1681 1681 0 0.00 0 2569 16 0 0
t$perf-norm:sp_cancel_customer_reserve 667 667 0 0.00 0 6488 28 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 5868 20 0 0
t$perf-norm:sp_cancel_pay_to_supplier 372 372 0 0.00 0 1326 5 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 518 9 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 20617 1506 0 0
t$perf-norm:sp_cancel_write_off 1018 1018 0 0.00 0 511 4 0 0
t$perf-norm:sp_client_order 20589 20589 0 0.00 0 14083 18 0 0
t$perf-norm:sp_customer_reserve 998 998 0 0.00 0 580 3 0 0
t$perf-norm:sp_pay_from_customer 6997 6997 0 0.00 0 12351 14 0 0
t$perf-norm:sp_pay_to_supplier 5774 5774 0 0.00 0 12722 9 0 0
t$perf-norm:sp_reserve_write_off 9893 9893 0 0.00 0 16219 14 0 0
t$perf-norm:sp_supplier_invoice 5491 5491 0 0.00 0 6615 17 0 0
t$perf-norm:sp_supplier_order 5322 5322 0 0.00 0 5308 13 0 0
t$perf-norm:srv_aggregate_perf_data 91 91 0 0.00 0 476 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1808 32 0 0
t$perf-norm:srv_make_invnt_saldo 580 580 0 0.00 0 707 15 0 0
t$perf-norm:srv_make_money_saldo 827 827 0 0.00 0 734 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 10114 10016 98 0.96 0 7468 18 0 98
v_all_customers 37678 37678 0 0.00 0 22 1 0 0
v_all_suppliers 30166 30166 0 0.00 0 221 1 0 0
v_all_wares 41178 41178 0 0.00 0 184 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 1605 8 0 0
v_cancel_client_order 3362 3362 0 0.00 0 5093 6 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 2476 6 0 0
v_cancel_customer_reserve 1334 1334 0 0.00 0 1378 4 0 0
v_cancel_payment_to_supplier 744 744 0 0.00 0 5538 16 0 0
v_cancel_supplier_invoice 816 808 8 0.98 0 4883 17 0 8
v_cancel_supplier_order 812 812 0 0.00 0 814 5 0 0
v_cancel_write_off 2036 2036 0 0.00 0 2786 13 0 0
v_max_id_clo_ord 5322 5322 0 0.00 0 4248 2 0 0
v_max_id_clo_res 998 998 0 0.00 0 4106 19 0 0
v_max_id_ord_sup 5485 5485 0 0.00 0 5297 5 0 0
v_max_non_paid_invoice 1498 1498 0 0.00 0 137 2 0 0
v_max_non_paid_realizn 3617 3617 0 0.00 0 2008 4 0 0
v_min_id_clo_ord 5322 5322 0 0.00 0 7783 7 0 0
v_min_id_clo_res 998 998 0 0.00 0 6424 9 0 0
v_min_id_ord_sup 5485 5485 0 0.00 0 7147 10 0 0
v_min_non_paid_invoice 5774 5774 0 0.00 0 6303 16 0 0
v_min_non_paid_realizn 6997 6997 0 0.00 0 11790 18 0 0
v_our_firm 10260 10260 0 0.00 0 18 1 0 0
v_reserve_write_off 19786 19786 0 0.00 0 2942 4 0 0
x_lock_depdocs_on_canc_invoice 11628 11628 0 0.00 0 22972 125 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 17926 390 0 0
x_make_qstorno_1000_1200 5322 5322 0 0.00 1 27323 1188 0 0
x_make_qstorno_1200_2000 5490 5490 0 0.00 2 85603 3389 0 0
x_make_qstorno_2100_3300 468616 468616 0 0.00 0 47341 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-03-2022 16:41:03 to 22-03-2022 16:41:07.

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:12 9 8192000000 8801031424 8813117440 6310704 0.076 2 1 2 0 6410336 56848 42784
14:09:12 33015 8192000000 9898701984 10206920704 976309568 10.648 100 87 174 0 1005504288 5908336 23286384
14:10:53 24159 8192000000 9945748880 10262667264 1022626288 11.097 100 90 180 0 1053325648 7151888 23547472
14:12:26 15225 8192000000 9978818800 10309427200 1060516128 11.461 100 81 162 0 1090017792 8863840 20637824
14:13:52 14541 8192000000 10047847856 10396241920 1127555696 12.098 100 70 140 1 1153968224 10179552 16232976
14:15:11 9299 8192000000 10103400320 10467180544 1190355248 12.687 100 75 150 0 1219245296 12221600 16668448
14:16:22 5364 8192000000 10146441440 10519035904 1237197984 13.120 100 81 161 2 1268294368 13310304 17786080
14:17:29 3208 8192000000 10172911456 10552045568 1261818416 13.347 100 91 182 0 1295662352 14196848 19647088
14:18:34 2505 8192000000 10203884304 10585620480 1289518768 13.600 100 89 178 0 1323443120 13976976 19947376
14:19:38 1090 8192000000 10220388624 10607341568 1305082048 13.741 100 87 175 1 1339116624 14244080 19790496
14:20:42 3908 8192000000 10215393488 10618355712 1315076688 13.832 100 57 114 1 1338652992 10816544 12759760
14:21:48 42717 8192000000 10205490880 10624536576 1318991600 13.868 100 28 56 0 1331477152 5545504 6940048
14:23:39 3966 8192000000 10233572960 10641526784 1330205600 13.969 100 42 82 0 1346603856 6980064 9418192
14:24:45 13895 8192000000 10214900368 10638868480 1331901568 13.984 100 23 44 1 1342618912 6097488 4619856
14:26:04 56647 8192000000 10242708160 10661564416 1341906208 14.075 100 52 104 0 1364253008 9619376 12727424
14:28:02 4624 8192000000 10212534864 10626420736 1299673808 13.692 97 31 62 0 1314226144 7841120 6711216
14:29:08 6311 8192000000 10185802624 10600079360 1270040416 13.422 97 26 51 2 1281746816 5851552 5854848
14:30:18 72408 8192000000 10165633056 10575982592 1237625472 13.124 96 27 54 1 1250137376 5392000 7119904
14:32:31 3934 8192000000 10142344816 10530201600 1218405984 12.947 97 36 72 1 1232532752 5756224 8370544
14:33:37 7239 8192000000 10165015152 10567966720 1258889824 13.320 99 27 54 1 1270191616 5195296 6106496
14:34:46 8451 8192000000 10177413584 10587430912 1288020784 13.586 100 36 72 1 1302897552 6686608 8190160
14:35:56 18075 8192000000 10183035680 10592043008 1290046192 13.605 100 25 48 1 1301352608 5431376 5875040
14:37:16 7324 8192000000 10199695568 10607689728 1304429504 13.735 100 33 66 2 1318490080 6418432 7642144
14:38:28 19810 8192000000 10219454352 10633183232 1323941696 13.912 100 34 67 1 1336917520 5621552 7354272
14:39:54 26254 8192000000 10261209760 10672156672 1347430880 14.124 100 58 114 2 1373812432 13230112 13151440
14:41:41 102028 8192000000 10270106160 10702118912 1367684912 14.306 100 52 104 0 1401636192 20554848 13396432
14:45:07 11418 8192000000 10321349472 10735325184 1389270128 14.499 100 61 122 0 1429370032 25700144 14399760
14:46:27 17014 8192000000 10329757904 10752409600 1399690224 14.592 100 60 120 2 1442699536 28704416 14304896
14:47:53 6372 8192000000 10340086640 10760003584 1409914704 14.683 100 68 136 1 1452423872 26715376 15793792
14:49:02 2506 8192000000 10349328560 10760937472 1404045536 14.631 100 77 153 2 1453199680 30954320 18199824
14:50:09 4649 8192000000 10347704064 10766831616 1391709440 14.521 98 68 134 2 1438361504 30208368 16443696
14:51:24 5552 8192000000 10359362912 10771484672 1402468480 14.617 99 76 152 0 1452511840 31233392 18809968
14:52:34 1818 8192000000 10381362880 10787606528 1422542416 14.795 100 89 179 1 1483952464 40002960 21407088
14:53:39 5134 8192000000 10378533232 10792132608 1415178256 14.730 100 86 170 2 1482580432 46143312 21258864
14:54:49 5686 8192000000 10352043728 10761932800 1394931440 14.550 100 72 142 0 1453167984 40511248 17725296
14:56:01 4895 8192000000 10331065024 10735558656 1368744368 14.316 100 68 137 1 1432704160 46852624 17107168
14:57:13 4686 8192000000 10337904176 10743910400 1376158464 14.382 99 75 150 1 1443827232 48973664 18695104
14:58:23 5517 8192000000 10341639072 10756444160 1383211744 14.445 100 56 111 0 1446989792 48852288 14925760
14:59:31 7718 8192000000 10326268000 10758746112 1381058944 14.426 100 35 70 0 1434605648 43584160 9962544
15:01:06 12633 8192000000 10336154560 10767589376 1388195664 14.490 100 40 80 1 1445400208 46396848 10807696
15:02:27 24939 8192000000 10318908416 10751086592 1362157536 14.257 99 31 60 0 1420102368 48805600 9139232
15:03:55 25500 8192000000 10321816224 10772123648 1368842400 14.317 100 21 43 1 1430588096 55359856 6385840
15:05:21 85710 8192000000 10293959664 10754056192 1368876544 14.317 100 8 16 0 1403414176 32413376 2124256
15:07:47 106919 8192000000 10292770304 10733985792 1350037232 14.148 98 11 22 0 1372300992 19786624 2477136
15:11:00 89393 8192000000 10276882288 10714935296 1325382544 13.925 96 35 70 2 1343175440 10377424 7415472
15:13:30 20345 8192000000 10297830592 10727727104 1357346496 14.214 99 49 98 0 1379676368 11362032 10967840
15:14:56 23310 8192000000 10287954624 10738233344 1377677376 14.396 100 16 32 2 1394301552 13072128 3552048
15:16:22 26394 8192000000 10313141488 10757980160 1391458288 14.519 100 26 51 1 1412150704 14482704 6209712
15:17:49 34330 8192000000 10318529184 10766827520 1399756944 14.593 100 46 91 3 1429960176 19380848 10822384
15:19:26 51113 8192000000 10299373792 10756538368 1387305376 14.482 100 20 40 0 1416224896 23862336 5057184
15:21:50 22600 8192000000 10293458544 10751369216 1376432752 14.385 99 12 24 2 1389358768 9900560 3025456
15:23:15 46278 8192000000 10315970624 10770358272 1399118160 14.587 100 17 35 1 1414679632 11417008 4144464
15:25:03 92975 8192000000 10319888608 10774175744 1400395824 14.599 100 30 61 1 1423029184 15030128 7603232
15:27:40 40015 8192000000 10297800288 10754097152 1381018960 14.426 99 26 52 2 1395095856 8344416 5732480
15:29:24 29895 8192000000 10284890064 10742034432 1382576688 14.440 99 15 30 0 1393360592 6968800 3815104
15:30:54 61796 8192000000 10279966720 10755919872 1367984656 14.309 98 7 14 0 1380646000 11037728 1623616
15:32:57 68917 8192000000 10281162800 10746159104 1386444288 14.474 100 16 31 1 1396040848 6136928 3459632
15:35:18 72508 8192000000 10287594128 10769571840 1393554944 14.538 100 5 10 0 1406723984 12145280 1023760
15:37:30 33197 8192000000 10278768096 10737717248 1361893072 14.254 98 15 30 1 1373108560 8100288 3115200
15:39:10 57075 8192000000 10297446592 10758864896 1367481232 14.304 98 16 32 0 1377681968 6755168 3445568
15:41:09 57633 8192000000 10295989488 10760073216 1363567056 14.269 98 12 24 0 1377365760 11154304 2644400
15:43:36 30086 8192000000 10301751200 10743267328 1381843440 14.433 100 26 52 1 1397602192 10288576 5470176
15:45:07 18228 8192000000 10291277520 10729066496 1367527712 14.305 100 40 78 1 1385621056 8742960 9350384
15:46:30 54515 8192000000 10240672880 10687082496 1294027424 13.641 96 20 40 2 1312236368 13926352 4282592
15:48:25 59816 8192000000 10257907488 10707275776 1320847072 13.884 98 24 48 1 1345339488 18955680 5536736
15:50:33 105071 8192000000 10248388144 10687655936 1299780992 13.693 96 21 42 0 1318951888 14555952 4614944
15:53:34 73057 8192000000 10259138304 10706849792 1329153312 13.960 98 23 45 1 1351809760 17617424 5039024
15:55:50 76925 8192000000 10247975008 10703872000 1324433088 13.917 98 9 18 1 1342828480 16519040 1876352
15:58:11 52833 8192000000 10249443936 10700017664 1335545232 14.017 99 15 30 0 1352251360 12279696 4426432
16:00:10 100545 8192000000 10264274544 10712338432 1320873280 13.885 97 17 34 1 1338570608 13498976 4198352
16:02:52 52301 8192000000 10279655920 10725724160 1356158368 14.203 99 15 30 0 1371823872 12098144 3567360
16:04:53 78799 8192000000 10260826096 10730643456 1332442928 13.989 97 9 18 1 1352477152 17798192 2236032
16:07:17 48682 8192000000 10280282128 10713595904 1337286688 14.033 98 30 60 2 1352875712 8980976 6608048
16:09:09 64429 8192000000 10276805952 10726010880 1343576672 14.090 98 26 52 0 1358854944 9819056 5459216
16:11:18 13483 8192000000 10286686880 10721804288 1357247920 14.213 99 40 80 0 1374724224 8967152 8509152
16:12:32 9191 8192000000 10296483952 10736230400 1368975344 14.318 99 34 68 0 1386470128 10263216 7231568
16:13:41 116989 8192000000 10277317760 10734129152 1359677632 14.234 98 19 38 0 1374537504 10581248 4278624
16:16:39 82508 8192000000 10280920560 10737496064 1365274128 14.285 99 11 22 0 1379256848 11435920 2546800
16:19:02 46212 8192000000 10271751072 10737987584 1367409360 14.304 99 11 22 1 1386510224 16244448 2856416
16:20:48 112852 8192000000 10286618848 10754068480 1376562656 14.386 99 16 32 0 1392437600 12063856 3811088
16:24:00 22688 8192000000 10287422432 10742390784 1371582848 14.341 99 23 46 0 1387852448 11169024 5100576
16:25:35 90562 8192000000 10288170608 10740285440 1322917536 13.903 95 15 30 0 1347384384 20861968 3604880
16:28:20 52221 8192000000 10258676432 10715377664 1297176608 13.670 95 7 14 1 1309814096 11241968 1395520
16:30:13 110781 8192000000 10271776720 10720264192 1335417856 14.016 98 14 28 2 1355948400 17235920 3294624
16:33:03 98278 8192000000 10257239440 10703003648 1329483664 13.962 98 16 31 0 1345757456 12482496 3791296
16:36:03 17420 8192000000 10267064912 10705043456 1344775616 14.100 99 38 75 0 1361363520 7896400 8691504
16:37:22 53998 8192000000 9779831568 10166804480 895265136 9.851 66 8 16 0 909331488 12415280 1651072

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 22-03-2022 16:41:09 to 22-03-2022 16:41:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 1 srv_aggregate_perf_data 335544517
except 49 v_add_invoice_to_stock 335544517
except 4 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-03-2022 16:41:24 to 22-03-2022 16:41:26.

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          237887
MON$OLDEST_ACTIVE               237888
MON$OLDEST_SNAPSHOT             237888
MON$NEXT_TRANSACTION            237888
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                       2029728
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.33574 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33574 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33574 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33574 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 ---
:250: 
:251: mscs-test-161.budzdorov.ru    Tue Mar 22 16:38:55 2022
:252:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:253: 
:254: 
:255: mscs-test-161.budzdorov.ru    Tue Mar 22 16:38:55 2022
:256:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:257: 
:258: 
:259: mscs-test-161.budzdorov.ru    Tue Mar 22 16:38:55 2022
:260:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:261: 
--- end of diff output ---

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

22.03.22 16:41:27. 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-03-22 16:41:30 No severe PSQL-related problems occured
22.03.22 16:41:31. End of report.