Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.05.2022 19:38
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 16524988 kB
MemAvailable 31494676 kB
Buffers 0 kB
Cached 15165520 kB
SwapCached 2536 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-05 19:38:16
DTS_END                         2022-05-05 19:38:16.5200
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/33852
TRN_ID                          240829
ATT_ID                          632
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: 9572.25 18633 1148670
customer order: creation 173.94 333 20873
customer order: refuse 14.48 356 1737
order to supplier: creation 45.84 1266 5501
order to supplier: removal 3.49 97910 419
invoice (draft): creation 46.21 3174 5545
invoice (draft): removal 55.03 754 6604
invoice accept: apply 42.34 17418 5081
invoice accept: cancel 49.77 802 5972
customer reserve: creation 3936.02 170 472322
customer reserve: removal 4901.16 54 588139
realization accept: apply 86.56 184 10387
realization accept: cancel 84.96 97 10195
payment from customer: creation 60.48 108 7257
payment from customer: removal 6.12 82 734
payment to supplier: creation 49.62 139 5954
payment to supplier: removal 3.99 66 479
service: total inventory turnovers 4.66 1222 559
service: total monetary turnovers 6.70 28 804
service: aggregate perf. data 0.87 16123 104
service: refresh index statistics 0.03 232377 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 05-05-2022 19:39:12 to 05-05-2022 19:39:15.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5873
TEST_TIME 2 5262
TEST_TIME 3 5687
TEST_TIME 4 6141
TEST_TIME 5 5827
TEST_TIME 6 5587
TEST_TIME 7 5462
TEST_TIME 8 5808
TEST_TIME 9 5948
TEST_TIME 10 5969
TEST_TIME 11 5969
TEST_TIME 12 6418
TEST_TIME 13 6500
TEST_TIME 14 6618
TEST_TIME 15 6943
TEST_TIME 16 7042
TEST_TIME 17 7346
TEST_TIME 18 7613
TEST_TIME 19 7952
TEST_TIME 20 8301
TEST_TIME 21 9338
TEST_TIME 22 9269
TEST_TIME 23 9253
TEST_TIME 24 9491
TEST_TIME 25 9790
TEST_TIME 26 10602
TEST_TIME 27 10809
TEST_TIME 28 10630
TEST_TIME 29 10797
TEST_TIME 30 10789
TEST_TIME 31 10696
TEST_TIME 32 10685
TEST_TIME 33 10578
TEST_TIME 34 10838
TEST_TIME 35 10828
TEST_TIME 36 10680
TEST_TIME 37 10601
TEST_TIME 38 10408
TEST_TIME 39 10231
TEST_TIME 40 10044
TEST_TIME 41 10069
TEST_TIME 42 9869
TEST_TIME 43 10162
TEST_TIME 44 10187
TEST_TIME 45 10082
TEST_TIME 46 9962
TEST_TIME 47 9861
TEST_TIME 48 9827
TEST_TIME 49 9954
TEST_TIME 50 9797
TEST_TIME 51 9753
TEST_TIME 52 9663
TEST_TIME 53 9606
TEST_TIME 54 9515
TEST_TIME 55 9468
TEST_TIME 56 9533
TEST_TIME 57 9480
TEST_TIME 58 9502
TEST_TIME 59 9471
TEST_TIME 60 9582
TEST_TIME 61 9537
TEST_TIME 62 9631
TEST_TIME 63 9657
TEST_TIME 64 9715
TEST_TIME 65 9767
TEST_TIME 66 9723
TEST_TIME 67 9707
TEST_TIME 68 9865
TEST_TIME 69 9885
TEST_TIME 70 9979
TEST_TIME 71 9975
TEST_TIME 72 9901
TEST_TIME 73 9813
TEST_TIME 74 9831
TEST_TIME 75 9807
TEST_TIME 76 9797
TEST_TIME 77 9726
TEST_TIME 78 9704
TEST_TIME 79 9687
TEST_TIME 80 9769
TEST_TIME 81 9691
TEST_TIME 82 9644
TEST_TIME 83 9757
TEST_TIME 84 9731
TEST_TIME 85 9705
TEST_TIME 86 9644
TEST_TIME 87 9647
TEST_TIME 88 9589
TEST_TIME 89 9645
TEST_TIME 90 9673
TEST_TIME 91 9689
TEST_TIME 92 9702
TEST_TIME 93 9768
TEST_TIME 94 9777
TEST_TIME 95 9774
TEST_TIME 96 9756
TEST_TIME 97 9790
TEST_TIME 98 9813
TEST_TIME 99 9781
TEST_TIME 100 9877
TEST_TIME 101 9861
TEST_TIME 102 9799
TEST_TIME 103 9734
TEST_TIME 104 9718
TEST_TIME 105 9718
TEST_TIME 106 9688
TEST_TIME 107 9650
TEST_TIME 108 9654
TEST_TIME 109 9613
TEST_TIME 110 9628
TEST_TIME 111 9590
TEST_TIME 112 9572
TEST_TIME 113 9562
TEST_TIME 114 9596
TEST_TIME 115 9599
TEST_TIME 116 9642
TEST_TIME 117 9613
TEST_TIME 118 9636
TEST_TIME 119 9655
TEST_TIME 120 9582

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-05-2022 19:39:16 to 05-05-2022 19:39:19.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1585511 1585511 0 0.00 0 21083 6 0 0
doc_list_biud 1585510 1585510 0 0.00 0 54899 26 0 0
sp_add_invoice_to_stock 5196 5081 115 2.21 45 173008 17419 0 115
sp_cancel_adding_invoice 5972 5972 0 0.00 5 62904 803 0 0
sp_cancel_client_order 1737 1737 0 0.00 4 18452 357 0 0
sp_cancel_customer_reserve 588139 588139 0 0.00 1 54902 54 0 0
sp_cancel_pay_from_customer 734 734 0 0.00 3 4234 82 0 0
sp_cancel_pay_to_supplier 479 479 0 0.00 3 2257 67 0 0
sp_cancel_supplier_invoice 6611 6604 7 0.10 2 14001 755 0 7
sp_cancel_supplier_order 419 419 0 0.00 7 1118967 97910 0 0
sp_cancel_write_off 10195 10195 0 0.00 1 7005 98 0 0
sp_client_order 20873 20873 0 0.00 3 20140 334 0 0
sp_customer_reserve 472322 472322 0 0.00 0 77225 171 0 0
sp_fill_shopping_cart 31914 31914 0 0.00 1 10020 36 0 0
sp_fill_shopping_cart_clo_res 472317 472317 0 0.00 0 15337 12 0 0
sp_get_clo_for_invoice 5075 5075 0 0.00 0 31046 255 0 0
sp_kill_cost_storno 17380 17380 0 0.00 0 8952 53 0 0
sp_kill_qstorno_ret_qs2qd 1193496 1193496 0 0.00 0 54412 29 0 0
sp_kill_qty_storno 628528 628528 0 0.00 0 54899 64 0 0
sp_lock_selected_doc 21308 21308 0 0.00 0 10134 6 0 0
sp_make_cost_storno 28673 28673 0 0.00 0 15617 54 0 0
sp_multiply_rows_for_pdistr 28673 28673 0 0.00 0 8246 32 0 0
sp_multiply_rows_for_qdistr 20873 20873 0 0.00 0 10614 147 0 0
sp_pay_from_customer 7257 7257 0 0.00 4 14394 109 0 0
sp_pay_to_supplier 5954 5954 0 0.00 5 15710 139 0 0
sp_payment_common 13211 13211 0 0.00 1 15334 33 0 0
sp_qd_handle_on_cancel_clo 1737 1737 0 0.00 0 18443 326 0 0
sp_qd_handle_on_invoice_upd_sts 11047 11047 0 0.00 0 24617 326 0 0
sp_qd_handle_on_reserve_upd_sts 10387 10387 0 0.00 0 20000 63 0 0
sp_reserve_write_off 10387 10387 0 0.00 6 20049 185 0 0
sp_supplier_invoice 5545 5545 0 0.00 6 97864 3174 0 0
sp_supplier_order 5501 5501 0 0.00 5 30240 1266 0 0
srv_aggregate_perf_data 104 104 0 0.00 2433 97322 16124 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2443 135619 45971 0 0
srv_find_qd_qs_mism 1099812 1099812 0 0.00 0 21082 5 0 0
srv_make_invnt_saldo 559 559 0 0.00 27 61173 1222 0 0
srv_make_money_saldo 804 804 0 0.00 0 2812 29 0 0
srv_recalc_idx_stat 4 4 0 0.00 115655 384269 232378 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5084 5084 0 0.00 0 12813 186 0 0
t$perf-norm:sp_cancel_adding_invoice 401 401 0 0.00 0 4670 108 0 0
t$perf-norm:sp_cancel_client_order 1737 1737 0 0.00 0 3226 13 0 0
t$perf-norm:sp_cancel_customer_reserve 708 708 0 0.00 0 1624 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 734 734 0 0.00 0 3639 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 479 479 0 0.00 0 234 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 383 383 0 0.00 0 772 12 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 26581 1763 0 0
t$perf-norm:sp_cancel_write_off 985 985 0 0.00 0 468 5 0 0
t$perf-norm:sp_client_order 20873 20873 0 0.00 0 17181 21 0 0
t$perf-norm:sp_customer_reserve 1070 1070 0 0.00 0 1632 5 0 0
t$perf-norm:sp_pay_from_customer 7257 7257 0 0.00 0 8637 12 0 0
t$perf-norm:sp_pay_to_supplier 5954 5954 0 0.00 0 7149 12 0 0
t$perf-norm:sp_reserve_write_off 10387 10387 0 0.00 0 19413 14 0 0
t$perf-norm:sp_supplier_invoice 5545 5545 0 0.00 0 12028 19 0 0
t$perf-norm:sp_supplier_order 5501 5501 0 0.00 0 12526 22 0 0
t$perf-norm:srv_aggregate_perf_data 104 104 0 0.00 0 455 9 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 127 4 0 0
t$perf-norm:srv_make_invnt_saldo 559 559 0 0.00 0 1744 22 0 0
t$perf-norm:srv_make_money_saldo 804 804 0 0.00 0 1988 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 4 0 0
v_add_invoice_to_stock 10380 10150 230 2.21 0 7065 17 0 230
v_all_customers 37948 37948 0 0.00 0 19 1 0 0
v_all_suppliers 30564 30564 0 0.00 0 18 1 0 0
v_all_wares 41744 41744 0 0.00 0 72 1 0 0
v_cancel_adding_invoice 802 802 0 0.00 0 1995 8 0 0
v_cancel_client_order 3474 3474 0 0.00 0 4140 5 0 0
v_cancel_customer_prepayment 1468 1468 0 0.00 0 2579 4 0 0
v_cancel_customer_reserve 1416 1416 0 0.00 0 4422 9 0 0
v_cancel_payment_to_supplier 958 958 0 0.00 0 2027 6 0 0
v_cancel_supplier_invoice 780 766 14 1.79 0 2904 17 0 14
v_cancel_supplier_order 838 838 0 0.00 0 4789 9 0 0
v_cancel_write_off 1970 1970 0 0.00 0 2807 13 0 0
v_max_id_clo_ord 5498 5498 0 0.00 0 3540 3 0 0
v_max_id_clo_res 1070 1070 0 0.00 0 4636 29 0 0
v_max_id_ord_sup 5544 5544 0 0.00 0 6669 5 0 0
v_max_non_paid_invoice 1714 1714 0 0.00 0 4332 9 0 0
v_max_non_paid_realizn 4053 4053 0 0.00 0 7791 8 0 0
v_min_id_clo_ord 5498 5498 0 0.00 0 9146 10 0 0
v_min_id_clo_res 1070 1070 0 0.00 0 6768 16 0 0
v_min_id_ord_sup 5544 5544 0 0.00 0 6438 9 0 0
v_min_non_paid_invoice 5954 5954 0 0.00 0 15331 23 0 0
v_min_non_paid_realizn 7257 7257 0 0.00 0 14344 20 0 0
v_our_firm 10204 10204 0 0.00 0 21 1 0 0
v_reserve_write_off 20774 20774 0 0.00 0 4696 5 0 0
x_lock_depdocs_on_canc_invoice 12193 12193 0 0.00 0 19077 127 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 19689 491 0 0
x_make_qstorno_1000_1200 5501 5501 0 0.00 1 30206 1184 0 0
x_make_qstorno_1200_2000 5545 5545 0 0.00 3 97854 3048 0 0
x_make_qstorno_2100_3300 472322 472322 0 0.00 0 77224 157 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-05-2022 19:39:20 to 05-05-2022 19:39:24.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
17:08:20 2 8192000000 8797116400 8807743488 3955424 0.048 1 1 2 0 4053696 55488 42784
17:09:21 19503 8192000000 9901171440 10209505280 983925600 10.722 100 87 174 1 1016424016 10193728 22304688
17:10:48 22109 8192000000 9953769072 10275778560 1031538896 11.183 100 85 171 0 1065950672 12447776 21964000
17:12:15 21258 8192000000 9991190192 10324492288 1075160224 11.601 100 87 174 0 1106532560 9839200 21533136
17:13:38 11355 8192000000 10034010032 10380763136 1123674224 12.062 100 78 156 0 1152378944 10074272 18630448
17:14:52 10257 8192000000 10089426352 10449068032 1178307680 12.574 100 89 176 1 1211094032 12509344 20277008
17:16:06 5782 8192000000 10131553584 10500988928 1216165824 12.926 100 81 162 1 1248243072 13731856 18345392
17:17:15 3448 8192000000 10170292880 10546753536 1251925920 13.256 100 90 180 0 1285574544 14107472 19541152
17:18:21 1566 8192000000 10211556928 10593558528 1287952016 13.586 100 93 187 0 1327200864 18520096 20728752
17:19:23 2900 8192000000 10211878960 10607104000 1304080272 13.732 100 69 140 3 1329618928 9880096 15658560
17:20:27 8076 8192000000 10210572288 10621767680 1316262400 13.843 100 17 33 1 1326150800 6099280 3789120
17:21:35 16169 8192000000 10206276768 10622214144 1322853776 13.903 100 31 63 0 1336382000 6490240 7037984
17:22:55 25728 8192000000 10216553856 10641833984 1322706288 13.901 100 15 30 0 1331138272 5142240 3289744
17:24:24 4558 8192000000 10228238112 10649673728 1335157792 14.014 100 21 42 0 1345839392 5873536 4808064
17:25:29 5522 8192000000 10231728640 10656624640 1342743584 14.082 100 24 48 1 1354580656 6486576 5350496
17:26:35 10910 8192000000 10230286208 10650386432 1317137120 13.851 98 19 38 0 1327630416 5900864 4592432
17:27:48 32949 8192000000 10209237952 10631086080 1281019312 13.522 96 20 40 1 1291055392 5632784 4403296
17:29:22 8945 8192000000 10189510080 10607722496 1302849056 13.721 100 27 53 3 1313628912 5139648 5640208
17:30:33 13220 8192000000 10136853984 10539614208 1228204848 13.037 98 19 36 1 1238297808 5256256 4836704
17:31:51 58192 8192000000 10132711168 10534514688 1212936944 12.896 97 26 52 1 1224263360 4864800 6461616
17:33:50 8355 8192000000 10165671712 10571718656 1274800992 13.466 100 31 62 2 1289050112 6047264 8201856
17:35:01 6086 8192000000 10177319552 10587811840 1284865328 13.557 100 29 58 0 1298096928 6458400 6773200
17:36:11 11834 8192000000 10181442400 10599170048 1292315600 13.625 100 11 22 1 1304225200 9676336 2233264
17:37:24 34480 8192000000 10177554048 10597613568 1293936208 13.640 100 9 18 0 1300914288 4983408 1994672
17:39:03 12609 8192000000 10233850656 10641223680 1323787808 13.911 100 55 111 1 1345212544 8992496 12432240
17:40:20 23625 8192000000 10251331136 10661916672 1338919616 14.048 100 55 110 1 1365153888 13213616 13020656
17:41:48 4914 8192000000 10278565872 10686787584 1352939552 14.174 100 62 124 2 1382728576 15712208 14076816
17:43:02 35339 8192000000 10277303536 10705010688 1368179344 14.311 100 41 82 1 1396915440 18799120 9936976
17:44:44 9769 8192000000 10295558096 10716299264 1382298128 14.437 100 44 88 2 1410676704 17875248 10503328
17:46:00 12840 8192000000 10311718640 10733494272 1383073040 14.444 99 59 118 2 1418475856 21318720 14084096
17:47:18 6540 8192000000 10313150640 10732933120 1389417008 14.501 100 55 110 2 1425794752 22893072 13484672
17:48:37 7672 8192000000 10331384448 10759122944 1410756368 14.691 100 40 80 0 1444488320 22852464 10879488
17:49:47 4011 8192000000 10350428400 10763915264 1409809920 14.682 99 71 143 0 1458721792 31650320 17261552
17:50:56 7803 8192000000 10351007808 10763616256 1408017072 14.666 100 73 146 2 1454270224 28535664 17717488
17:52:08 5641 8192000000 10360348560 10772156416 1414424848 14.723 100 80 157 4 1466832768 32833632 19574288
17:53:21 2440 8192000000 10331111376 10745688064 1389823392 14.504 99 64 127 0 1438232528 32039136 16370000
17:54:31 5205 8192000000 10340546416 10756988928 1390925632 14.514 100 66 131 3 1441942160 34184544 16831984
17:55:47 4280 8192000000 10341443248 10755485696 1388734736 14.495 100 63 126 2 1447452992 42118512 16599744
17:56:59 6088 8192000000 10341102608 10742562816 1371218128 14.338 99 81 164 0 1438713840 45949648 21546064
17:58:10 8476 8192000000 10321875856 10741137408 1367613008 14.306 100 52 104 2 1434987520 52719712 14654800
17:59:27 14831 8192000000 10311575472 10746388480 1371959344 14.345 100 28 56 0 1419283136 39535664 7788128
18:00:59 23658 8192000000 10287643888 10710847488 1355944976 14.201 100 29 58 0 1396801872 32920496 7936400
18:02:26 33769 8192000000 10269334256 10695573504 1314262912 13.825 98 19 36 0 1355145664 35825104 5057648
18:04:12 6560 8192000000 10271808448 10689380352 1321763168 13.893 100 36 72 0 1371709312 40152016 9794128
18:05:21 31294 8192000000 10239854064 10664022016 1305949824 13.749 98 18 36 3 1330336912 19871040 4516048
18:06:58 18308 8192000000 10241176432 10675019776 1315885024 13.839 99 36 69 0 1338500000 14517136 8097840
18:08:29 16840 8192000000 10245329168 10675654656 1327753200 13.947 99 21 42 0 1338619360 5757424 5108736
18:09:52 42131 8192000000 10262938080 10703683584 1346272768 14.114 99 22 42 2 1359900144 8931744 4695632
18:11:35 32423 8192000000 10282124032 10724491264 1363310400 14.267 100 11 22 0 1384203168 18227648 2665120
18:13:08 89878 8192000000 10288989904 10724491264 1319658240 13.874 96 14 27 1 1332103216 10108416 2336560
18:15:41 135619 8192000000 10283237152 10734018560 1375589136 14.377 100 30 56 1 1392504064 10559248 6355680
18:19:02 65243 8192000000 10304510608 10750312448 1384363216 14.456 100 23 45 2 1405493920 15751040 5379664
18:21:09 57543 8192000000 10282237936 10756055040 1369851696 14.326 99 9 18 0 1387713776 15126560 2735520
18:23:09 106570 8192000000 10300187936 10756272128 1378469680 14.403 99 16 32 0 1392526032 10390560 3665792
18:25:56 81432 8192000000 10296293600 10765737984 1376937888 14.389 98 14 28 0 1392982080 12522960 3521232
18:28:59 75969 8192000000 10282171504 10752643072 1380231056 14.419 99 14 28 1 1392124448 8362096 3531296
18:31:16 32223 8192000000 10278606224 10741141504 1366461696 14.295 98 11 22 0 1376161168 7410608 2288864
18:32:59 58972 8192000000 10293779584 10765770752 1394660464 14.547 100 11 22 0 1408824240 11469984 2693792
18:35:21 37834 8192000000 10288754080 10745491456 1365987296 14.291 98 11 22 0 1379269008 10933456 2348256
18:37:03 32319 8192000000 10298409552 10743091200 1362060336 14.256 98 39 78 2 1381560864 11110720 8389808
18:38:35 34377 8192000000 10269120960 10717728768 1323700384 13.910 96 17 33 1 1336084976 8864640 3519952
18:40:11 57517 8192000000 10257612736 10702909440 1264532992 13.372 92 15 30 0 1274801136 7264400 3003744
18:42:15 41647 8192000000 10249082336 10707914752 1313333440 13.816 97 27 54 0 1334645152 15014000 6297712
18:43:56 54006 8192000000 10263204464 10700644352 1296676272 13.665 95 19 38 0 1314001104 12998304 4326528
18:45:51 74842 8192000000 10253055616 10696540160 1245937936 13.201 92 13 26 0 1259360432 10268672 3153824
18:48:06 64882 8192000000 10251752592 10708058112 1309669072 13.783 97 13 26 0 1325635616 13219904 2746640
18:50:17 72223 8192000000 10251681104 10693095424 1330656464 13.973 99 17 34 0 1348378576 13825728 3896384
18:52:29 61082 8192000000 10258821904 10722754560 1340784224 14.064 99 16 32 0 1363862864 19867696 3210944
18:54:31 67914 8192000000 10262946080 10716303360 1325729904 13.929 97 16 32 0 1338359280 9033552 3595824
18:56:39 73685 8192000000 10272392912 10729050112 1360231648 14.239 99 13 26 0 1377599968 14426544 2941776
18:58:57 51222 8192000000 10267912400 10726514688 1360021696 14.238 99 12 24 0 1372146800 9406912 2718192
19:00:49 93702 8192000000 10284860352 10742218752 1382543808 14.439 100 16 32 0 1401209792 14943632 3722352
19:03:44 69493 8192000000 10270480624 10740510720 1354928336 14.192 98 7 14 0 1367253808 10327568 1997904
19:06:02 48809 8192000000 10294293792 10741321728 1353738464 14.181 98 24 48 1 1371909328 12518928 5651936
19:07:54 53056 8192000000 10299825248 10753888256 1375497952 14.376 99 24 48 2 1389886320 9086240 5302128
19:09:47 50971 8192000000 10310186496 10772189184 1372806880 14.352 98 29 58 2 1388372976 9587008 5979088
19:11:39 72560 8192000000 10297220384 10747588608 1362335360 14.258 98 24 46 1 1379207376 11346704 5525312
19:13:55 53057 8192000000 10317377424 10769928192 1386584416 14.475 99 30 60 1 1406681360 13158640 6938304
19:15:49 48446 8192000000 10317447728 10764722176 1377205824 14.392 98 34 68 1 1395117136 10064656 7846656
19:17:40 62955 8192000000 10290135088 10766585856 1337257392 14.033 95 8 16 1 1351572880 12568896 1746592
19:19:46 105793 8192000000 10307438704 10757861376 1381180928 14.427 99 22 44 0 1397167104 10910000 5076176
19:22:51 59602 8192000000 10312373248 10787074048 1401618144 14.609 100 16 32 0 1425279152 20499376 3161632
19:24:52 78143 8192000000 10295910128 10760470528 1367945904 14.309 97 11 22 0 1378157312 7609632 2601776
19:27:15 53151 8192000000 10291054304 10757677056 1388498944 14.492 99 7 14 0 1397564688 7633552 1432192
19:29:15 66928 8192000000 10297985968 10770010112 1402311408 14.616 100 12 24 2 1415002048 9700864 2989776
19:31:32 100961 8192000000 10268804480 10735218688 1316273776 13.843 95 11 22 1 1327796432 8856592 2666064
19:34:17 81994 8192000000 10255960816 10715668480 1249995056 13.238 91 13 26 0 1266624096 13554000 3075040
19:36:40 38372 8192000000 10269070464 10730770432 1364999408 14.282 100 26 52 1 1383105600 12403344 5702848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-05-2022 19:39:26 to 05-05-2022 19:39:41.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 115 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-05-2022 19:39:42 to 05-05-2022 19:39:44.

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          240140
MON$OLDEST_ACTIVE               241612
MON$OLDEST_SNAPSHOT             241612
MON$NEXT_TRANSACTION            241612
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                       2038176
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.33585 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.10.33585 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.10.33585 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.10.33585 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 ---
:713: 
:714: mscs-test-161.budzdorov.ru    Thu May  5 19:38:24 2022
:715:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:716: 
--- end of diff output ---

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

05.05.22 19:39:45. 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-05 19:39:48 No severe PSQL-related problems occured
05.05.22 19:39:49. End of report.