Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 08.05.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 16567592 kB
MemAvailable 31523180 kB
Buffers 0 kB
Cached 15178124 kB
SwapCached 2572 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-05-08 19:38:15
DTS_END                         2022-05-08 19:38:15.5490
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/51624
TRN_ID                          247021
ATT_ID                          675
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: 9190.94 20284 1102913
customer order: creation 179.17 341 21500
customer order: refuse 14.43 311 1731
order to supplier: creation 48.02 1190 5762
order to supplier: removal 3.28 92436 393
invoice (draft): creation 47.64 3965 5717
invoice (draft): removal 50.86 693 6103
invoice accept: apply 43.96 17679 5275
invoice accept: cancel 45.83 742 5499
customer reserve: creation 3993.53 177 479223
customer reserve: removal 4465.87 53 535904
realization accept: apply 84.81 174 10177
realization accept: cancel 81.06 114 9727
payment from customer: creation 60.98 129 7317
payment from customer: removal 5.68 81 682
payment to supplier: creation 49.53 138 5944
payment to supplier: removal 3.69 85 443
service: total inventory turnovers 4.74 1069 569
service: total monetary turnovers 6.99 39 839
service: aggregate perf. data 0.87 22240 104
service: refresh index statistics 0.03 264020 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 08-05-2022 19:40:32 to 08-05-2022 19:40:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1308
TEST_TIME 2 2063
TEST_TIME 3 3509
TEST_TIME 4 4523
TEST_TIME 5 4640
TEST_TIME 6 5021
TEST_TIME 7 5302
TEST_TIME 8 5596
TEST_TIME 9 5449
TEST_TIME 10 5702
TEST_TIME 11 6349
TEST_TIME 12 6564
TEST_TIME 13 6506
TEST_TIME 14 6856
TEST_TIME 15 7201
TEST_TIME 16 7588
TEST_TIME 17 7748
TEST_TIME 18 7990
TEST_TIME 19 8695
TEST_TIME 20 8807
TEST_TIME 21 9284
TEST_TIME 22 9606
TEST_TIME 23 9760
TEST_TIME 24 9875
TEST_TIME 25 9879
TEST_TIME 26 10257
TEST_TIME 27 10550
TEST_TIME 28 10428
TEST_TIME 29 10338
TEST_TIME 30 10175
TEST_TIME 31 10259
TEST_TIME 32 10413
TEST_TIME 33 10433
TEST_TIME 34 10306
TEST_TIME 35 10421
TEST_TIME 36 10303
TEST_TIME 37 10206
TEST_TIME 38 10027
TEST_TIME 39 9982
TEST_TIME 40 9899
TEST_TIME 41 9752
TEST_TIME 42 9762
TEST_TIME 43 9578
TEST_TIME 44 9690
TEST_TIME 45 9681
TEST_TIME 46 9643
TEST_TIME 47 9647
TEST_TIME 48 9605
TEST_TIME 49 9558
TEST_TIME 50 9593
TEST_TIME 51 9715
TEST_TIME 52 9850
TEST_TIME 53 9779
TEST_TIME 54 9759
TEST_TIME 55 9753
TEST_TIME 56 9795
TEST_TIME 57 9873
TEST_TIME 58 9884
TEST_TIME 59 9848
TEST_TIME 60 9908
TEST_TIME 61 9962
TEST_TIME 62 9997
TEST_TIME 63 9986
TEST_TIME 64 9968
TEST_TIME 65 9825
TEST_TIME 66 9746
TEST_TIME 67 9762
TEST_TIME 68 9685
TEST_TIME 69 9680
TEST_TIME 70 9619
TEST_TIME 71 9594
TEST_TIME 72 9547
TEST_TIME 73 9464
TEST_TIME 74 9487
TEST_TIME 75 9484
TEST_TIME 76 9434
TEST_TIME 77 9405
TEST_TIME 78 9369
TEST_TIME 79 9479
TEST_TIME 80 9392
TEST_TIME 81 9412
TEST_TIME 82 9510
TEST_TIME 83 9476
TEST_TIME 84 9478
TEST_TIME 85 9524
TEST_TIME 86 9544
TEST_TIME 87 9515
TEST_TIME 88 9568
TEST_TIME 89 9591
TEST_TIME 90 9555
TEST_TIME 91 9568
TEST_TIME 92 9483
TEST_TIME 93 9467
TEST_TIME 94 9401
TEST_TIME 95 9401
TEST_TIME 96 9350
TEST_TIME 97 9380
TEST_TIME 98 9325
TEST_TIME 99 9341
TEST_TIME 100 9315
TEST_TIME 101 9287
TEST_TIME 102 9309
TEST_TIME 103 9281
TEST_TIME 104 9340
TEST_TIME 105 9356
TEST_TIME 106 9334
TEST_TIME 107 9380
TEST_TIME 108 9368
TEST_TIME 109 9381
TEST_TIME 110 9354
TEST_TIME 111 9422
TEST_TIME 112 9420
TEST_TIME 113 9433
TEST_TIME 114 9391
TEST_TIME 115 9396
TEST_TIME 116 9348
TEST_TIME 117 9316
TEST_TIME 118 9300
TEST_TIME 119 9258
TEST_TIME 120 9191

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 08-05-2022 19:40:35 to 08-05-2022 19:40:39.

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 1547172 1547172 0 0.00 0 27717 6 0 0
doc_list_biud 1547172 1547172 0 0.00 0 36365 24 0 0
sp_add_invoice_to_stock 5385 5275 110 2.04 29 175806 17679 0 110
sp_cancel_adding_invoice 5499 5499 0 0.00 4 76365 743 0 0
sp_cancel_client_order 1731 1731 0 0.00 4 9292 312 0 0
sp_cancel_customer_reserve 535904 535904 0 0.00 1 20579 53 0 0
sp_cancel_pay_from_customer 682 682 0 0.00 2 5378 82 0 0
sp_cancel_pay_to_supplier 443 443 0 0.00 3 4172 85 0 0
sp_cancel_supplier_invoice 6111 6103 8 0.13 1 13156 693 0 8
sp_cancel_supplier_order 393 393 0 0.00 9 922339 92436 0 0
sp_cancel_write_off 9727 9727 0 0.00 1 20471 114 0 0
sp_client_order 21500 21500 0 0.00 3 14530 342 0 0
sp_customer_reserve 479223 479223 0 0.00 0 120964 178 0 0
sp_fill_shopping_cart 32949 32949 0 0.00 1 21859 41 0 0
sp_fill_shopping_cart_clo_res 479223 479223 0 0.00 0 24105 13 0 0
sp_get_clo_for_invoice 5275 5275 0 0.00 0 32095 371 0 0
sp_kill_cost_storno 16351 16351 0 0.00 0 9293 56 0 0
sp_kill_qstorno_ret_qs2qd 1088031 1088031 0 0.00 0 20561 29 0 0
sp_kill_qty_storno 574809 574809 0 0.00 0 36363 63 0 0
sp_lock_selected_doc 21216 21216 0 0.00 0 8476 6 0 0
sp_make_cost_storno 28713 28713 0 0.00 0 27709 56 0 0
sp_multiply_rows_for_pdistr 28713 28713 0 0.00 0 8379 32 0 0
sp_multiply_rows_for_qdistr 21500 21500 0 0.00 0 12401 136 0 0
sp_pay_from_customer 7317 7317 0 0.00 4 13373 130 0 0
sp_pay_to_supplier 5944 5944 0 0.00 5 14962 138 0 0
sp_payment_common 13261 13261 0 0.00 1 10820 32 0 0
sp_qd_handle_on_cancel_clo 1731 1731 0 0.00 0 9282 277 0 0
sp_qd_handle_on_invoice_upd_sts 10774 10774 0 0.00 0 36361 310 0 0
sp_qd_handle_on_reserve_upd_sts 10177 10177 0 0.00 0 26740 58 0 0
sp_reserve_write_off 10177 10177 0 0.00 6 26781 175 0 0
sp_supplier_invoice 5717 5717 0 0.00 6 102999 3965 0 0
sp_supplier_order 5762 5762 0 0.00 5 29890 1190 0 0
srv_aggregate_perf_data 104 104 0 0.00 2651 389799 22241 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2131 134710 48047 0 0
srv_find_qd_qs_mism 1053805 1053805 0 0.00 0 16351 5 0 0
srv_make_invnt_saldo 569 569 0 0.00 38 75707 1070 0 0
srv_make_money_saldo 839 839 0 0.00 1 11467 39 0 0
srv_recalc_idx_stat 4 4 0 0.00 140084 391891 264020 0 0
t$perf-abend:sp_add_invoice_to_stock 110 110 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5275 5275 0 0.00 0 17090 168 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 5935 94 0 0
t$perf-norm:sp_cancel_client_order 1731 1731 0 0.00 0 2888 10 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 2089 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 682 682 0 0.00 0 2591 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 443 443 0 0.00 0 2918 10 0 0
t$perf-norm:sp_cancel_supplier_invoice 414 414 0 0.00 0 587 7 0 0
t$perf-norm:sp_cancel_supplier_order 393 393 0 0.00 0 25807 1628 0 0
t$perf-norm:sp_cancel_write_off 989 989 0 0.00 0 5442 16 0 0
t$perf-norm:sp_client_order 21500 21500 0 0.00 0 8487 15 0 0
t$perf-norm:sp_customer_reserve 1065 1065 0 0.00 0 594 5 0 0
t$perf-norm:sp_pay_from_customer 7317 7317 0 0.00 0 12020 10 0 0
t$perf-norm:sp_pay_to_supplier 5944 5944 0 0.00 0 4411 7 0 0
t$perf-norm:sp_reserve_write_off 10177 10177 0 0.00 0 3302 7 0 0
t$perf-norm:sp_supplier_invoice 5717 5717 0 0.00 0 10318 22 0 0
t$perf-norm:sp_supplier_order 5762 5762 0 0.00 0 3160 14 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 116 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 212 8 0 0
t$perf-norm:srv_make_invnt_saldo 569 569 0 0.00 0 2679 26 0 0
t$perf-norm:srv_make_money_saldo 839 839 0 0.00 0 1125 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 10770 10550 220 2.04 0 5558 21 0 220
v_all_customers 38818 38818 0 0.00 0 19 1 0 0
v_all_suppliers 30928 30928 0 0.00 0 16 1 0 0
v_all_wares 43000 43000 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 1942 15 0 0
v_cancel_client_order 3462 3462 0 0.00 0 4519 8 0 0
v_cancel_customer_prepayment 1364 1364 0 0.00 0 3605 10 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 1397 6 0 0
v_cancel_payment_to_supplier 886 886 0 0.00 0 3979 12 0 0
v_cancel_supplier_invoice 844 828 16 1.89 0 3305 17 0 16
v_cancel_supplier_order 786 786 0 0.00 0 365 3 0 0
v_cancel_write_off 1978 1978 0 0.00 0 1668 13 0 0
v_max_id_clo_ord 5762 5762 0 0.00 0 2117 2 0 0
v_max_id_clo_res 1065 1065 0 0.00 0 1702 6 0 0
v_max_id_ord_sup 5677 5677 0 0.00 0 2855 3 0 0
v_max_non_paid_invoice 1919 1919 0 0.00 0 3291 7 0 0
v_max_non_paid_realizn 3978 3978 0 0.00 0 4882 5 0 0
v_min_id_clo_ord 5762 5762 0 0.00 0 3154 5 0 0
v_min_id_clo_res 1065 1065 0 0.00 0 3621 17 0 0
v_min_id_ord_sup 5677 5677 0 0.00 0 3587 4 0 0
v_min_non_paid_invoice 5944 5944 0 0.00 0 8689 21 0 0
v_min_non_paid_realizn 7317 7317 0 0.00 1 10813 24 0 0
v_our_firm 10860 10860 0 0.00 0 11 1 0 0
v_reserve_write_off 20352 20352 0 0.00 0 7608 8 0 0
x_lock_depdocs_on_canc_invoice 11188 11188 0 0.00 0 13940 113 0 0
x_lock_depdocs_on_canc_sup_ord 393 393 0 0.00 0 9960 349 0 0
x_make_qstorno_1000_1200 5762 5762 0 0.00 1 29879 1114 0 0
x_make_qstorno_1200_2000 5717 5717 0 0.00 3 102980 3792 0 0
x_make_qstorno_2100_3300 479223 479223 0 0.00 0 120962 163 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 08-05-2022 19:40:40 to 08-05-2022 19:40:44.

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:19 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:19 17667 8192000000 9917735600 10229706752 994599904 10.826 100 89 178 0 1023448256 5795568 23052784
17:10:41 26095 8192000000 9956470384 10280071168 1037682368 11.242 100 89 176 1 1068873248 8739008 22451872
17:12:10 19967 8192000000 9985677840 10319056896 1070874608 11.560 100 88 176 1 1102872960 10298496 21699856
17:13:47 11920 8192000000 10048543584 10397990912 1134009344 12.159 100 83 166 0 1165004912 11512384 19483184
17:15:03 7942 8192000000 10098271552 10461016064 1184522800 12.632 100 77 154 0 1213653792 11323488 17807504
17:16:15 4648 8192000000 10149091600 10519965696 1230173872 13.056 100 75 148 0 1258385280 11785936 16425472
17:17:21 7215 8192000000 10166871168 10547060736 1257572272 13.308 100 68 136 0 1283649488 10838896 15238320
17:18:31 2391 8192000000 10214413696 10598973440 1300804592 13.703 100 94 187 2 1334771216 13210688 20755936
17:19:35 9281 8192000000 10220562976 10608287744 1305774352 13.748 100 78 156 0 1335309072 12627456 16907264
17:20:45 8092 8192000000 10198892624 10606776320 1310757488 13.793 100 25 50 0 1323131536 6369904 6004144
17:21:56 7589 8192000000 10205221168 10624192512 1317829344 13.857 100 23 46 2 1333434992 10614592 4991056
17:23:07 9432 8192000000 10218304032 10626711552 1325097824 13.923 100 27 54 1 1337600528 6632096 5870608
17:24:17 31375 8192000000 10237812592 10645114880 1329777104 13.965 100 67 135 1 1350755024 5307072 15670848
17:25:49 4763 8192000000 10231575488 10649530368 1333181488 13.996 100 38 76 2 1348637344 6877664 8578192
17:26:54 8207 8192000000 10235594624 10654380032 1338897280 14.047 100 19 38 1 1348521072 5224416 4399376
17:28:04 19575 8192000000 10200941856 10618040320 1271892336 13.439 96 44 87 5 1292468144 10196464 10379344
17:29:45 7551 8192000000 10193969424 10604974080 1297065136 13.669 100 31 62 3 1310958976 7352048 6541792
17:30:53 7607 8192000000 10157512656 10562162688 1256047824 13.294 99 21 43 1 1266298736 5619600 4631312
17:32:04 6542 8192000000 10138125824 10538594304 1245618048 13.198 100 20 39 3 1256352000 5759344 4974608
17:33:12 55847 8192000000 10163314560 10566565888 1252761296 13.264 99 30 60 1 1265540928 5794736 6984896
17:35:10 8535 8192000000 10182655904 10591830016 1291028880 13.614 100 34 67 3 1304885776 5542032 8314864
17:36:21 7803 8192000000 10188745200 10603585536 1299910608 13.694 100 22 44 0 1309707152 4546320 5250224
17:37:32 11465 8192000000 10209271072 10620256256 1311654784 13.801 100 19 38 2 1321833088 5159504 5018800
17:38:44 83071 8192000000 10240741552 10646999040 1313209296 13.815 98 51 99 3 1330788496 5394752 12184448
17:41:17 27550 8192000000 10265700624 10679840768 1355296656 14.195 100 57 114 1 1378545376 10934672 12314048
17:42:48 8006 8192000000 10291980336 10702065664 1371381584 14.339 100 61 122 1 1399385120 14325600 13677936
17:44:01 11161 8192000000 10310565088 10721570816 1372615040 14.350 99 72 143 0 1408899840 19562912 16721888
17:45:17 18603 8192000000 10326705744 10750926848 1394223264 14.544 100 44 87 1 1432942240 28456736 10262240
17:46:41 14172 8192000000 10341340016 10764083200 1407761440 14.664 100 58 114 1 1450156560 27880608 14514512
17:48:06 12181 8192000000 10343351456 10769170432 1410039264 14.684 100 57 111 1 1455948880 31822144 14087472
17:49:23 2870 8192000000 10356458064 10771738624 1405449280 14.643 100 76 152 0 1462263504 38230944 18583280
17:50:34 2450 8192000000 10342265184 10753331200 1395779776 14.557 100 65 130 0 1448058672 35560064 16718832
17:51:43 2720 8192000000 10314926592 10722377728 1346709056 14.118 98 76 152 0 1404951552 38775072 19467424
17:52:48 2498 8192000000 10333274256 10732388352 1367004512 14.300 100 94 186 2 1432698112 43035056 22658544
17:53:56 6295 8192000000 10329239088 10746687488 1367968160 14.309 99 54 107 1 1425489392 43239296 14281936
17:55:04 11402 8192000000 10306743248 10725019648 1363500368 14.269 100 45 90 2 1417233856 41420240 12313248
17:56:22 3994 8192000000 10302292032 10719244288 1342086144 14.076 99 51 100 0 1398475712 43273840 13115728
17:57:31 2130 8192000000 10352541328 10760724480 1384739984 14.459 100 84 168 1 1449999728 43362624 21897120
17:58:35 9277 8192000000 10333549232 10763911168 1383338080 14.446 100 44 88 0 1438650928 44299776 11013072
17:59:48 23388 8192000000 10322488992 10763685888 1375199136 14.374 99 22 44 0 1418754480 37706048 5849296
18:01:18 21178 8192000000 10315135664 10749489152 1376443344 14.385 100 41 77 2 1413052832 26475712 10133776
18:02:56 31195 8192000000 10286996544 10734112768 1352459072 14.170 99 28 56 1 1389895792 30607712 6829008
18:04:29 27931 8192000000 10287800864 10733121536 1363192400 14.266 100 24 48 2 1392812816 23833296 5787120
18:06:21 62463 8192000000 10278860848 10721566720 1350919856 14.156 99 27 55 2 1369778288 12108448 6749984
18:09:03 44167 8192000000 10275107952 10716385280 1345957008 14.111 98 19 38 1 1364555088 13331888 5266192
18:10:50 96191 8192000000 10265032704 10712559616 1311881616 13.803 96 6 12 0 1326524208 13450080 1192512
18:13:27 92615 8192000000 10298849568 10740350976 1353192112 14.176 98 10 20 1 1366613536 11431552 1989872
18:16:02 90918 8192000000 10304212864 10742128640 1375095456 14.373 99 45 90 0 1397310960 11341056 10874448
18:18:36 131907 8192000000 10328046144 10779729920 1379106096 14.409 98 16 32 0 1404539312 21674336 3758880
18:21:49 27532 8192000000 10344268128 10790862848 1409153392 14.676 100 55 110 2 1449043168 27111104 12778672
18:23:24 35171 8192000000 10327295168 10778775552 1408474064 14.670 100 27 55 0 1438118400 22637728 7006608
18:25:12 45682 8192000000 10318731824 10775695360 1394812560 14.549 99 13 26 1 1425960112 27868864 3278688
18:27:04 42778 8192000000 10301203744 10758344704 1371251296 14.338 98 12 24 1 1393112928 18442736 3418896
18:28:46 91839 8192000000 10306729328 10776276992 1393857184 14.540 100 17 35 0 1416403904 18217552 4329168
18:31:19 27555 8192000000 10303338480 10743382016 1375516080 14.376 99 53 107 2 1396027824 8388176 12123568
18:32:48 28695 8192000000 10285979824 10734194688 1354388752 14.187 98 25 50 1 1372399216 12561248 5449216
18:34:28 63274 8192000000 10293779152 10747256832 1358078112 14.220 98 24 48 1 1372849408 9592528 5178768
18:36:32 105370 8192000000 10274852992 10727919616 1301669760 13.710 94 13 26 1 1315666768 11357856 2639152
18:39:18 66644 8192000000 10284145936 10732855296 1323659072 13.910 96 18 36 0 1340754992 13523264 3572656
18:41:40 134710 8192000000 10279034576 10712080384 1299532064 13.691 94 25 50 0 1316757104 11813152 5411888
18:45:01 38525 8192000000 10279655232 10722983936 1355363936 14.196 99 29 58 0 1377732016 15663392 6704688
18:46:42 70108 8192000000 10263530256 10720231424 1349918304 14.147 98 34 67 0 1366403712 8798032 7687376
18:48:56 46996 8192000000 10263317072 10715078656 1321732688 13.892 96 20 40 3 1332802240 6201840 4867712
18:50:43 73294 8192000000 10282934096 10731388928 1350442976 14.151 98 20 40 1 1367080672 11687696 4950000
18:52:57 49697 8192000000 10268184704 10727075840 1372869440 14.353 100 17 34 0 1386508736 9616272 4023024
18:54:53 50252 8192000000 10281365008 10733613056 1358415552 14.223 98 22 44 0 1381403040 17531568 5455920
18:56:47 68028 8192000000 10274247504 10739466240 1345143712 14.104 98 13 24 1 1364404624 15942384 3318528
18:58:56 68867 8192000000 10290234336 10749693952 1378236464 14.401 100 34 67 2 1402110384 15912336 7961584
19:01:06 10089 8192000000 10283757040 10715090944 1352973984 14.174 99 48 96 3 1371083648 7758176 10351488
19:02:25 18155 8192000000 10279043968 10712363008 1336288864 14.024 98 56 110 1 1359797360 12228304 11280192
19:03:45 71444 8192000000 10281261104 10729705472 1343563600 14.090 98 26 52 2 1361800512 12857136 5379776
19:05:59 64311 8192000000 10297008912 10748092416 1382517904 14.439 100 18 36 0 1403771168 17322912 3930352
19:08:10 34451 8192000000 10297629024 10736115712 1340497104 14.062 96 27 52 1 1353604272 7735376 5371792
19:09:49 119713 8192000000 10287132320 10744569856 1378117152 14.400 99 23 46 0 1393223120 9961232 5144736
19:12:49 7266 8192000000 10312681872 10755096576 1393845376 14.540 100 67 134 0 1421584896 12865744 14873776
19:13:57 39920 8192000000 10297391920 10763116544 1403798048 14.629 100 10 20 0 1416829488 10817536 2213904
19:15:37 44971 8192000000 10288665104 10764107776 1372396832 14.349 98 6 12 0 1386926304 12948976 1580496
19:17:25 82287 8192000000 10279361152 10741751808 1367645344 14.306 98 5 10 1 1377524704 8869040 1010320
19:19:48 50895 8192000000 10292069984 10753187840 1372977376 14.354 99 16 31 1 1388224752 11095984 4151392
19:21:53 90904 8192000000 10297099120 10756214784 1370580576 14.332 98 20 40 1 1388665312 13602576 4482160
19:24:25 22737 8192000000 10280514384 10723692544 1359728016 14.235 99 31 62 0 1375216688 8580656 6908016
19:25:56 88097 8192000000 10281868560 10724974592 1308828176 13.775 95 13 24 1 1320186304 8775184 2582944
19:28:27 51871 8192000000 10259729888 10713661440 1340883392 14.065 98 29 58 1 1361051584 13123424 7044768
19:30:19 72972 8192000000 10284836592 10736041984 1346763104 14.118 98 21 42 1 1363237664 11795008 4679552
19:32:39 116068 8192000000 10286517040 10749767680 1348108048 14.130 98 26 52 0 1364808048 10536528 6163472
19:35:35 57063 8192000000 10271401568 10751209472 1369838864 14.326 100 6 12 1 1393024784 21866560 1319360
19:37:32 56323 8192000000 9151076688 9404248064 315689120 3.710 23 6 12 0 323933616 7008512 1235984

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 08-05-2022 19:40:46 to 08-05-2022 19:41:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 110 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 08-05-2022 19:41:02 to 08-05-2022 19:41:03.

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          245719
MON$OLDEST_ACTIVE               247439
MON$OLDEST_SNAPSHOT             247439
MON$NEXT_TRANSACTION            247439
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                       2036768
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.33587 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33587 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33587 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33587 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 ---
:43: 
:44: mscs-test-161.budzdorov.ru    Sun May  8 19:38:30 2022
:45:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:46: 
--- end of diff output ---

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

08.05.22 19:41:04. 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-05-08 19:41:07 No severe PSQL-related problems occured
08.05.22 19:41:08. End of report.