Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.02.2023 22: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 15282780 kB
MemAvailable 30466008 kB
Buffers 0 kB
Cached 16450440 kB
SwapCached 3308 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.11
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 2023-02-17 22:38:41
DTS_END                         2023-02-17 22:38:41.7520
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/33776
TRN_ID                          238324
ATT_ID                          718
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9519.88 20641 1142386
customer order: creation 167.65 387 20118
customer order: refuse 14.25 391 1710
order to supplier: creation 44.77 1335 5372
order to supplier: removal 3.48 98294 418
invoice (draft): creation 44.99 3399 5399
invoice (draft): removal 55.46 713 6655
invoice accept: apply 40.78 17183 4893
invoice accept: cancel 50.50 736 6060
customer reserve: creation 3815.65 168 457878
customer reserve: removal 4986.45 54 598374
realization accept: apply 82.12 194 9854
realization accept: cancel 88.39 104 10607
payment from customer: creation 57.90 130 6948
payment from customer: removal 5.44 101 653
payment to supplier: creation 47.03 140 5643
payment to supplier: removal 3.66 96 439
service: total inventory turnovers 4.40 1136 528
service: total monetary turnovers 6.11 32 733
service: aggregate perf. data 0.83 22328 100
service: refresh index statistics 0.03 265903 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 17-02-2023 22:40:48 to 17-02-2023 22:40:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3316
TEST_TIME 2 2575
TEST_TIME 3 3762
TEST_TIME 4 4560
TEST_TIME 5 4190
TEST_TIME 6 4354
TEST_TIME 7 4526
TEST_TIME 8 4744
TEST_TIME 9 5414
TEST_TIME 10 5690
TEST_TIME 11 5505
TEST_TIME 12 5720
TEST_TIME 13 5599
TEST_TIME 14 5921
TEST_TIME 15 6257
TEST_TIME 16 6453
TEST_TIME 17 6382
TEST_TIME 18 7180
TEST_TIME 19 7432
TEST_TIME 20 7682
TEST_TIME 21 7905
TEST_TIME 22 8727
TEST_TIME 23 9227
TEST_TIME 24 9361
TEST_TIME 25 9531
TEST_TIME 26 10284
TEST_TIME 27 10104
TEST_TIME 28 10353
TEST_TIME 29 10411
TEST_TIME 30 10205
TEST_TIME 31 10153
TEST_TIME 32 10119
TEST_TIME 33 10051
TEST_TIME 34 9975
TEST_TIME 35 10066
TEST_TIME 36 9943
TEST_TIME 37 9805
TEST_TIME 38 9896
TEST_TIME 39 9960
TEST_TIME 40 9928
TEST_TIME 41 9829
TEST_TIME 42 9811
TEST_TIME 43 9776
TEST_TIME 44 9595
TEST_TIME 45 9614
TEST_TIME 46 9551
TEST_TIME 47 9499
TEST_TIME 48 9547
TEST_TIME 49 9446
TEST_TIME 50 9571
TEST_TIME 51 9622
TEST_TIME 52 9605
TEST_TIME 53 9757
TEST_TIME 54 9777
TEST_TIME 55 9736
TEST_TIME 56 9871
TEST_TIME 57 9843
TEST_TIME 58 9783
TEST_TIME 59 9847
TEST_TIME 60 9938
TEST_TIME 61 9944
TEST_TIME 62 9926
TEST_TIME 63 9803
TEST_TIME 64 9827
TEST_TIME 65 9761
TEST_TIME 66 9809
TEST_TIME 67 9730
TEST_TIME 68 9661
TEST_TIME 69 9589
TEST_TIME 70 9591
TEST_TIME 71 9675
TEST_TIME 72 9716
TEST_TIME 73 9628
TEST_TIME 74 9703
TEST_TIME 75 9643
TEST_TIME 76 9664
TEST_TIME 77 9866
TEST_TIME 78 9785
TEST_TIME 79 9794
TEST_TIME 80 9899
TEST_TIME 81 9917
TEST_TIME 82 9912
TEST_TIME 83 9872
TEST_TIME 84 9824
TEST_TIME 85 9801
TEST_TIME 86 9727
TEST_TIME 87 9767
TEST_TIME 88 9738
TEST_TIME 89 9707
TEST_TIME 90 9664
TEST_TIME 91 9608
TEST_TIME 92 9588
TEST_TIME 93 9562
TEST_TIME 94 9637
TEST_TIME 95 9625
TEST_TIME 96 9622
TEST_TIME 97 9670
TEST_TIME 98 9697
TEST_TIME 99 9706
TEST_TIME 100 9703
TEST_TIME 101 9756
TEST_TIME 102 9729
TEST_TIME 103 9763
TEST_TIME 104 9763
TEST_TIME 105 9804
TEST_TIME 106 9771
TEST_TIME 107 9827
TEST_TIME 108 9812
TEST_TIME 109 9761
TEST_TIME 110 9697
TEST_TIME 111 9683
TEST_TIME 112 9684
TEST_TIME 113 9635
TEST_TIME 114 9652
TEST_TIME 115 9650
TEST_TIME 116 9596
TEST_TIME 117 9569
TEST_TIME 118 9591
TEST_TIME 119 9572
TEST_TIME 120 9523

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-02-2023 22:40:52 to 17-02-2023 22:40:56.

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 1566845 1566845 0 0.00 0 34809 6 0 0
doc_list_biud 1566843 1566843 0 0.00 0 51020 26 0 0
sp_add_invoice_to_stock 5027 4893 134 2.66 25 160736 17184 0 134
sp_cancel_adding_invoice 6060 6060 0 0.00 4 54205 736 0 0
sp_cancel_client_order 1710 1710 0 0.00 4 13982 392 0 0
sp_cancel_customer_reserve 598374 598374 0 0.00 0 51022 55 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 3 9560 101 0 0
sp_cancel_pay_to_supplier 439 439 0 0.00 3 19564 96 0 0
sp_cancel_supplier_invoice 6666 6655 11 0.16 2 28359 713 0 11
sp_cancel_supplier_order 418 418 0 0.00 10 1026805 98294 0 0
sp_cancel_write_off 10607 10607 0 0.00 1 23535 105 0 0
sp_client_order 20118 20118 0 0.00 3 22187 387 0 0
sp_customer_reserve 457878 457878 0 0.00 0 51064 169 0 0
sp_fill_shopping_cart 30884 30884 0 0.00 1 12054 34 0 0
sp_fill_shopping_cart_clo_res 457869 457869 0 0.00 0 15340 16 0 0
sp_get_clo_for_invoice 4883 4883 0 0.00 0 25558 302 0 0
sp_kill_cost_storno 17759 17759 0 0.00 0 19558 50 0 0
sp_kill_qstorno_ret_qs2qd 1214428 1214428 0 0.00 0 51015 30 0 0
sp_kill_qty_storno 638561 638561 0 0.00 0 51020 64 0 0
sp_lock_selected_doc 20360 20360 0 0.00 0 9104 7 0 0
sp_make_cost_storno 27328 27328 0 0.00 0 34632 65 0 0
sp_multiply_rows_for_pdistr 27328 27328 0 0.00 0 20673 26 0 0
sp_multiply_rows_for_qdistr 20118 20118 0 0.00 0 15577 185 0 0
sp_pay_from_customer 6948 6948 0 0.00 4 25569 130 0 0
sp_pay_to_supplier 5643 5643 0 0.00 4 24208 141 0 0
sp_payment_common 12591 12591 0 0.00 1 25560 45 0 0
sp_qd_handle_on_cancel_clo 1710 1710 0 0.00 0 13974 348 0 0
sp_qd_handle_on_invoice_upd_sts 10943 10943 0 0.00 0 33890 280 0 0
sp_qd_handle_on_reserve_upd_sts 9854 9854 0 0.00 0 20599 64 0 0
sp_reserve_write_off 9854 9854 0 0.00 6 26353 194 0 0
sp_supplier_invoice 5399 5399 0 0.00 6 96176 3400 0 0
sp_supplier_order 5372 5372 0 0.00 5 71975 1336 0 0
srv_aggregate_perf_data 100 100 0 0.00 2613 663012 22328 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 2937 126590 45126 0 0
srv_find_qd_qs_mism 1095866 1095866 0 0.00 0 23142 4 0 0
srv_make_invnt_saldo 528 528 0 0.00 41 74296 1136 0 0
srv_make_money_saldo 733 733 0 0.00 1 8640 32 0 0
srv_recalc_idx_stat 4 4 0 0.00 81826 343601 265903 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4893 4893 0 0.00 0 13248 156 0 0
t$perf-norm:sp_cancel_adding_invoice 399 399 0 0.00 0 2837 94 0 0
t$perf-norm:sp_cancel_client_order 1710 1710 0 0.00 0 4215 14 0 0
t$perf-norm:sp_cancel_customer_reserve 635 635 0 0.00 0 3264 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 2394 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 439 439 0 0.00 0 1849 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 397 397 0 0.00 0 5402 30 0 0
t$perf-norm:sp_cancel_supplier_order 418 418 0 0.00 0 26292 1730 0 0
t$perf-norm:sp_cancel_write_off 972 972 0 0.00 0 3467 12 0 0
t$perf-norm:sp_client_order 20118 20118 0 0.00 0 7306 15 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 1514 7 0 0
t$perf-norm:sp_pay_from_customer 6948 6948 0 0.00 0 8264 13 0 0
t$perf-norm:sp_pay_to_supplier 5643 5643 0 0.00 0 11911 10 0 0
t$perf-norm:sp_reserve_write_off 9854 9854 0 0.00 0 12765 16 0 0
t$perf-norm:sp_supplier_invoice 5400 5400 0 0.00 0 4632 17 0 0
t$perf-norm:sp_supplier_order 5372 5372 0 0.00 0 7729 14 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 4348 52 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 80 3 0 0
t$perf-norm:srv_make_invnt_saldo 528 528 0 0.00 0 1001 15 0 0
t$perf-norm:srv_make_money_saldo 733 733 0 0.00 0 2674 13 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10034 9766 268 2.67 0 14046 22 0 268
v_all_customers 36934 36934 0 0.00 0 20 0 0 0
v_all_suppliers 29886 29886 0 0.00 0 15 1 0 0
v_all_wares 40236 40236 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 798 798 0 0.00 0 97 5 0 0
v_cancel_client_order 3420 3420 0 0.00 0 7123 10 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 3888 9 0 0
v_cancel_customer_reserve 1270 1270 0 0.00 0 4851 12 0 0
v_cancel_payment_to_supplier 878 878 0 0.00 0 990 4 0 0
v_cancel_supplier_invoice 816 794 22 2.69 0 1337 15 0 22
v_cancel_supplier_order 836 836 0 0.00 0 2055 5 0 0
v_cancel_write_off 1944 1944 0 0.00 0 5059 16 0 0
v_max_id_clo_ord 5371 5371 0 0.00 0 635 2 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 3640 24 0 0
v_max_id_ord_sup 5395 5395 0 0.00 0 1373 3 0 0
v_max_non_paid_invoice 1466 1466 0 0.00 0 459 3 0 0
v_max_non_paid_realizn 3674 3674 0 0.00 0 2124 5 0 0
v_min_id_clo_ord 5371 5371 0 0.00 0 7006 12 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 2009 9 0 0
v_min_id_ord_sup 5395 5395 0 0.00 0 3375 5 0 0
v_min_non_paid_invoice 5643 5643 0 0.00 0 20384 29 0 0
v_min_non_paid_realizn 6948 6948 0 0.00 0 25554 45 0 0
v_our_firm 9850 9850 0 0.00 0 20 1 0 0
v_reserve_write_off 19708 19708 0 0.00 0 6826 7 0 0
x_lock_depdocs_on_canc_invoice 12318 12318 0 0.00 0 13547 115 0 0
x_lock_depdocs_on_canc_sup_ord 418 418 0 0.00 0 13679 406 0 0
x_make_qstorno_1000_1200 5372 5372 0 0.00 1 71966 1262 0 0
x_make_qstorno_1200_2000 5399 5399 0 0.00 2 95853 3284 0 0
x_make_qstorno_2100_3300 457877 457877 0 0.00 0 51059 150 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-02-2023 22:40:57 to 17-02-2023 22:41:01.

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
20:08:45 2 8192000000 8797295440 8807940096 3955424 0.048 1 1 2 0 4053696 55488 42784
20:09:46 25305 8192000000 9922212528 10236928000 999299488 10.872 100 84 169 0 1026568864 5883072 21386304
20:11:16 19128 8192000000 9959225808 10285223936 1042441152 11.288 100 86 172 0 1073114064 8461456 22211456
20:12:40 13063 8192000000 9995897872 10331541504 1078718880 11.635 100 86 172 0 1110608336 10645584 21243872
20:13:59 8094 8192000000 10027557520 10376941568 1119467472 12.022 100 79 157 1 1149443392 11297664 18678256
20:15:09 6139 8192000000 10068530176 10423304192 1153267248 12.340 100 81 162 1 1185645328 13936800 18441280
20:16:17 3385 8192000000 10107316080 10473357312 1191005792 12.693 100 78 153 0 1221537712 13168400 17363520
20:17:23 2104 8192000000 10154057840 10523807744 1235596192 13.106 100 86 168 0 1268652224 13927056 19128976
20:18:26 1442 8192000000 10176521616 10555248640 1267061344 13.395 100 89 177 0 1300025808 13358768 19605696
20:19:29 933 8192000000 10218346320 10602909696 1300987024 13.704 100 89 178 0 1334551616 14127520 19437072
20:20:30 854 8192000000 10219925440 10607775744 1307884064 13.767 100 78 155 1 1336058496 11055200 17119232
20:21:33 12957 8192000000 10192927216 10609946624 1311813184 13.803 100 29 58 0 1324467712 5340624 7313904
20:22:46 4962 8192000000 10210378304 10621988864 1319993664 13.877 100 35 67 2 1335378000 8034960 7349376
20:23:53 3909 8192000000 10225489808 10639265792 1327521184 13.945 100 25 49 0 1348700064 16331248 4847632
20:24:58 11394 8192000000 10216419120 10635841536 1331313888 13.979 100 11 22 0 1339191376 5343952 2533536
20:26:10 22022 8192000000 10246319760 10651348992 1335213888 14.014 100 59 117 0 1355209712 5800752 14195072
20:27:32 6614 8192000000 10218096192 10634383360 1318930912 13.867 99 27 54 0 1330997376 5811696 6254768
20:28:39 7016 8192000000 10208983360 10626715648 1302997392 13.722 98 22 42 0 1313683104 5664928 5020784
20:29:48 64081 8192000000 10153067632 10555768832 1178607760 12.577 91 26 51 0 1189650560 5211664 5831136
20:31:53 7161 8192000000 10126984832 10526986240 1227388000 13.030 99 33 64 1 1241024480 6499408 7137072
20:33:01 7247 8192000000 10139611904 10541518848 1238528288 13.133 98 17 35 0 1247515088 5042544 3944256
20:34:09 51400 8192000000 10184124272 10576490496 1266505120 13.390 99 50 97 1 1283638224 5637440 11495664
20:36:00 5778 8192000000 10185081152 10592514048 1288826544 13.594 100 30 60 0 1301922896 5766304 7330048
20:37:09 9090 8192000000 10190737808 10607255552 1302020208 13.714 100 28 57 0 1313752096 5381040 6350848
20:38:19 11668 8192000000 10219685024 10635325440 1328245888 13.951 100 17 32 0 1336954000 5196096 3512016
20:39:42 23461 8192000000 10257383344 10663587840 1340334160 14.060 100 49 96 0 1359216432 7003696 11878576
20:41:07 32210 8192000000 10266050800 10686013440 1359596640 14.234 100 34 67 0 1379414656 11381408 8436608
20:42:44 21817 8192000000 10278249088 10699599872 1372823984 14.352 100 36 72 0 1393445024 11712176 8908864
20:44:09 40780 8192000000 10295491280 10720993280 1385987680 14.470 100 36 72 0 1411206496 16710944 8507872
20:45:54 11673 8192000000 10309108976 10734628864 1394063536 14.542 100 43 84 0 1423738176 19525216 10149424
20:47:09 2937 8192000000 10339189968 10759680000 1409744960 14.682 100 58 114 1 1447594608 24128304 13721344
20:48:18 10572 8192000000 10339226480 10773200896 1421936672 14.790 100 42 81 0 1456195104 23598320 10660112
20:49:32 12821 8192000000 10329535456 10754596864 1401826512 14.611 100 50 97 0 1442335920 28293456 12215952
20:50:52 5701 8192000000 10332734736 10752765952 1398210768 14.579 100 50 100 0 1443196544 31713200 13272576
20:52:05 4049 8192000000 10365226400 10787401728 1421280096 14.784 100 55 110 0 1473864064 38503072 14080896
20:53:14 4574 8192000000 10371118848 10785611776 1419913920 14.772 100 69 137 0 1473847776 36880656 17053200
20:54:30 6607 8192000000 10377284192 10796572672 1428995184 14.852 100 59 118 0 1486063488 42019920 15048384
20:55:45 4008 8192000000 10369978736 10782478336 1409158080 14.676 99 66 131 0 1475446384 49318400 16969904
20:57:04 11900 8192000000 10337171888 10773471232 1400029072 14.595 100 30 60 0 1447944464 39610800 8304592
20:58:21 8434 8192000000 10292324048 10708692992 1340822800 14.065 98 44 85 2 1397116112 44073120 12220192
20:59:35 11603 8192000000 10303209792 10730041344 1359525760 14.233 100 24 48 0 1408533520 41987280 7020480
21:01:04 18726 8192000000 10274719856 10704261120 1325626992 13.928 98 19 38 0 1365560208 34929600 5003616
21:02:25 34097 8192000000 10242559792 10668621824 1288262512 13.588 96 20 40 0 1325061184 31150640 5648032
21:04:01 28573 8192000000 10258847936 10690318336 1322430288 13.899 99 12 24 0 1350818880 25209344 3179248
21:05:31 32405 8192000000 10272270464 10713554944 1359939008 14.237 100 18 36 1 1384833152 20570688 4323456
21:07:03 41441 8192000000 10255511248 10690134016 1327912240 13.948 98 10 19 0 1341627632 11863168 1852224
21:08:46 67631 8192000000 10258386800 10687627264 1306482704 13.754 96 24 48 0 1325190384 12975264 5732416
21:10:54 84430 8192000000 10259311136 10700001280 1330868496 13.975 98 28 56 1 1346555680 9654432 6032752
21:13:22 79233 8192000000 10280862048 10720501760 1367888624 14.308 100 24 48 0 1384237184 10389008 5959552
21:15:41 19178 8192000000 10276187936 10715234304 1366334544 14.294 100 18 37 0 1384012384 13098496 4579344
21:17:15 3893 8192000000 10292057584 10721079296 1373705648 14.360 100 69 137 0 1402826640 14025728 15095264
21:18:24 66652 8192000000 10265231184 10716672000 1359510144 14.233 98 15 30 0 1372371552 9153520 3707888
21:20:37 22238 8192000000 10266437856 10717192192 1372519408 14.350 99 7 14 0 1381480176 7523664 1437104
21:21:59 57142 8192000000 10279356928 10725875712 1369048448 14.319 99 17 34 0 1383277856 10170048 4059360
21:24:03 50424 8192000000 10297613056 10745196544 1388247840 14.490 100 28 56 0 1406906016 12270176 6388000
21:25:54 44625 8192000000 10288342000 10752212992 1390784960 14.513 100 10 20 0 1408429024 15518896 2125168
21:27:40 32148 8192000000 10316750048 10770993152 1400250544 14.597 100 29 58 0 1423385152 16401104 6733504
21:29:20 33618 8192000000 10303238864 10751799296 1365562416 14.287 97 23 46 0 1383195984 13034416 4599152
21:30:55 90896 8192000000 10324207952 10769993728 1408705168 14.672 100 20 40 0 1426619376 13637088 4277120
21:33:26 126590 8192000000 10292390144 10750468096 1310851440 13.794 93 13 26 0 1329036400 15431776 2753184
21:36:33 64457 8192000000 10282484368 10736816128 1300266016 13.698 93 23 46 0 1321061984 15669840 5126128
21:38:51 40810 8192000000 10294420080 10736439296 1337442448 14.034 97 17 32 0 1351101904 10121024 3538432
21:40:35 93774 8192000000 10270920064 10718892032 1352918624 14.174 99 19 38 0 1369439616 12302288 4218704
21:43:33 44253 8192000000 10276100576 10720985088 1340750128 14.064 98 19 34 1 1355149088 10417664 3981296
21:45:25 58659 8192000000 10275029184 10731388928 1360697088 14.244 99 19 39 1 1380672096 15048464 4926544
21:47:26 51090 8192000000 10274722672 10719666176 1353781920 14.181 99 23 46 1 1375824416 16735712 5306784
21:49:24 26024 8192000000 10268430704 10716598272 1354849328 14.191 100 11 22 0 1376343984 18975120 2519536
21:50:52 61502 8192000000 10275863088 10718400512 1341424304 14.070 98 17 34 0 1361936208 16238672 4273232
21:52:57 37998 8192000000 10254638960 10693672960 1300584736 13.701 96 23 44 0 1317130720 11325856 5220128
21:54:36 78941 8192000000 10269875152 10734346240 1338179968 14.041 99 8 16 0 1370754880 30447200 2127712
21:56:56 12434 8192000000 10285243408 10710016000 1358988976 14.228 100 54 108 0 1381223936 10062592 12172368
21:58:13 98460 8192000000 10270956944 10720980992 1352821872 14.173 99 17 34 0 1379709488 22931648 3955968
22:00:52 61042 8192000000 10286019696 10718969856 1353902144 14.183 99 31 61 0 1371726592 11040848 6783600
22:02:59 98890 8192000000 10262925072 10703753216 1322778304 13.902 96 21 42 0 1340413184 12902976 4731904
22:05:39 36371 8192000000 10263821888 10721353728 1366152784 14.293 100 9 16 1 1378283616 10292144 1838688
22:07:16 65225 8192000000 10275602384 10730270720 1361430256 14.250 99 24 46 0 1377458176 11416288 4611632
22:09:21 83947 8192000000 10290394336 10749108224 1381695296 14.432 99 20 40 0 1399819472 13275712 4848464
22:11:48 83627 8192000000 10279861648 10741809152 1360899792 14.245 98 15 30 0 1379361984 14938048 3524144
22:14:19 32010 8192000000 10307669200 10765717504 1401900624 14.612 100 24 48 0 1419443264 11896256 5646384
22:15:51 82603 8192000000 10318715936 10783948800 1395221552 14.552 99 25 50 0 1421861808 20848720 5791536
22:18:16 27310 8192000000 10322655024 10782035968 1411929312 14.701 100 20 40 0 1430821968 14611520 4281136
22:19:44 36826 8192000000 10333245808 10781642752 1403587232 14.627 99 32 64 0 1422529728 11975696 6966800
22:21:22 46685 8192000000 10329819376 10779869184 1400128448 14.596 99 23 44 2 1416888784 12302992 4457344
22:23:18 76914 8192000000 10319892432 10773266432 1362126112 14.256 96 13 25 0 1377340576 12706288 2508176
22:25:36 48816 8192000000 10319025104 10791960576 1396469328 14.564 100 21 42 0 1425044736 23677648 4897760
22:27:34 77883 8192000000 10295566192 10741714944 1333242224 13.996 95 29 58 0 1357151504 17359152 6550128
22:30:01 95901 8192000000 10291636560 10763870208 1371110016 14.337 99 7 14 1 1390227408 17727456 1389936
22:33:21 74878 8192000000 10263967440 10715553792 1295202784 13.652 94 14 28 1 1306317632 7717488 3397360
22:36:31 67906 8192000000 10246021856 10708611072 1315274304 13.834 96 12 24 0 1328876560 11048272 2553984
22:38:40 20765 8192000000 9187614240 9454518272 354502784 4.147 26 3 6 0 358566304 3683328 380192

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-02-2023 22:41:03 to 17-02-2023 22:41:18.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-02-2023 22:41:19 to 17-02-2023 22:41:21.

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          237492
MON$OLDEST_ACTIVE               238749
MON$OLDEST_SNAPSHOT             238749
MON$NEXT_TRANSACTION            238749
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                       2034656
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                       
MON$SEC_DATABASE                Default


ISQL Version: LI-V3.0.11.33665 Firebird 3.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V3.0.11.33665 Firebird 3.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V3.0.11.33665 Firebird 3.0/tcp (mscs-test-161.budzdorov.ru)/P15:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V3.0.11.33665 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 ---
:136: 
:137: mscs-test-161.budzdorov.ru    Fri Feb 17 22:39:06 2023
:138:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:139: 
:140: 
:141: mscs-test-161.budzdorov.ru    Fri Feb 17 22:39:06 2023
:142:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:143: 
:144: 
:145: mscs-test-161.budzdorov.ru    Fri Feb 17 22:39:06 2023
:146:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:147: 
:148: 
:149: mscs-test-161.budzdorov.ru    Fri Feb 17 22:39:06 2023
:150:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:151: 
:152: 
:153: mscs-test-161.budzdorov.ru    Fri Feb 17 22:39:06 2023
:154:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:155: 
:156: 
:157: mscs-test-161.budzdorov.ru    Fri Feb 17 22:39:06 2023
:158:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3333
:159: 
--- end of diff output ---

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

17.02.23 22:41:22. 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
2023-02-17 22:41:25 No severe PSQL-related problems occured
17.02.23 22:41:26. End of report.