Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.01.2023 04:37
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 14632108 kB
MemAvailable 30371652 kB
Buffers 20 kB
Cached 16972532 kB
SwapCached 4248 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 4.0.3
DB_NAME                         /home/bases/oltp40-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/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/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=3400
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/oltp40-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-oltp40
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-01-30 04:37:25
DTS_END                         2023-01-30 04:37:25.5890
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/42062
TRN_ID                          220988
ATT_ID                          597
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: 9585.64 25457 1150277
customer order: creation 171.75 483 20610
customer order: refuse 15.03 394 1804
order to supplier: creation 46.02 1328 5522
order to supplier: removal 3.68 172617 442
invoice (draft): creation 46.08 2754 5529
invoice (draft): removal 57.02 911 6842
invoice accept: apply 42.25 23387 5070
invoice accept: cancel 51.32 1106 6158
customer reserve: creation 3836.33 226 460359
customer reserve: removal 5019.92 101 602390
realization accept: apply 83.46 282 10015
realization accept: cancel 84.94 161 10193
payment from customer: creation 58.78 178 7053
payment from customer: removal 5.83 118 699
payment to supplier: creation 48.35 188 5802
payment to supplier: removal 3.31 111 397
service: total inventory turnovers 3.58 4270 429
service: total monetary turnovers 7.11 387 853
service: aggregate perf. data 0.88 18162 106
service: refresh index statistics 0.03 281981 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-01-2023 04:38:11 to 30-01-2023 04:38:13.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6417
TEST_TIME 2 5203
TEST_TIME 3 5519
TEST_TIME 4 5467
TEST_TIME 5 5461
TEST_TIME 6 5309
TEST_TIME 7 5255
TEST_TIME 8 5746
TEST_TIME 9 5808
TEST_TIME 10 5935
TEST_TIME 11 6511
TEST_TIME 12 6773
TEST_TIME 13 7369
TEST_TIME 14 7460
TEST_TIME 15 7593
TEST_TIME 16 7711
TEST_TIME 17 7760
TEST_TIME 18 7829
TEST_TIME 19 7914
TEST_TIME 20 8024
TEST_TIME 21 8039
TEST_TIME 22 8370
TEST_TIME 23 8274
TEST_TIME 24 8552
TEST_TIME 25 8705
TEST_TIME 26 8898
TEST_TIME 27 8954
TEST_TIME 28 8877
TEST_TIME 29 9172
TEST_TIME 30 10184
TEST_TIME 31 10491
TEST_TIME 32 10782
TEST_TIME 33 11163
TEST_TIME 34 11370
TEST_TIME 35 11320
TEST_TIME 36 11215
TEST_TIME 37 11003
TEST_TIME 38 10773
TEST_TIME 39 10515
TEST_TIME 40 10765
TEST_TIME 41 11102
TEST_TIME 42 11000
TEST_TIME 43 10887
TEST_TIME 44 10726
TEST_TIME 45 10589
TEST_TIME 46 10600
TEST_TIME 47 10477
TEST_TIME 48 10481
TEST_TIME 49 10428
TEST_TIME 50 10472
TEST_TIME 51 10413
TEST_TIME 52 10364
TEST_TIME 53 10306
TEST_TIME 54 10295
TEST_TIME 55 10416
TEST_TIME 56 10354
TEST_TIME 57 10251
TEST_TIME 58 10355
TEST_TIME 59 10452
TEST_TIME 60 10349
TEST_TIME 61 10346
TEST_TIME 62 10379
TEST_TIME 63 10451
TEST_TIME 64 10370
TEST_TIME 65 10379
TEST_TIME 66 10357
TEST_TIME 67 10384
TEST_TIME 68 10475
TEST_TIME 69 10392
TEST_TIME 70 10275
TEST_TIME 71 10159
TEST_TIME 72 10140
TEST_TIME 73 10046
TEST_TIME 74 10002
TEST_TIME 75 9908
TEST_TIME 76 9874
TEST_TIME 77 9803
TEST_TIME 78 9779
TEST_TIME 79 9718
TEST_TIME 80 9734
TEST_TIME 81 9767
TEST_TIME 82 9753
TEST_TIME 83 9731
TEST_TIME 84 9731
TEST_TIME 85 9770
TEST_TIME 86 9761
TEST_TIME 87 9731
TEST_TIME 88 9768
TEST_TIME 89 9735
TEST_TIME 90 9781
TEST_TIME 91 9791
TEST_TIME 92 9740
TEST_TIME 93 9682
TEST_TIME 94 9641
TEST_TIME 95 9617
TEST_TIME 96 9578
TEST_TIME 97 9524
TEST_TIME 98 9500
TEST_TIME 99 9487
TEST_TIME 100 9482
TEST_TIME 101 9456
TEST_TIME 102 9431
TEST_TIME 103 9423
TEST_TIME 104 9470
TEST_TIME 105 9455
TEST_TIME 106 9438
TEST_TIME 107 9449
TEST_TIME 108 9427
TEST_TIME 109 9510
TEST_TIME 110 9561
TEST_TIME 111 9612
TEST_TIME 112 9734
TEST_TIME 113 9775
TEST_TIME 114 9730
TEST_TIME 115 9740
TEST_TIME 116 9752
TEST_TIME 117 9733
TEST_TIME 118 9671
TEST_TIME 119 9645
TEST_TIME 120 9614

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 30-01-2023 04:38:15 to 30-01-2023 04:38:18.

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 1576153 1576153 0 0.00 0 14853 12 0 0
doc_list_biud 1576140 1576140 0 0.00 0 35602 44 0 0
sp_add_invoice_to_stock 5221 5070 151 2.89 41 228791 23387 0 151
sp_cancel_adding_invoice 6158 6158 0 0.00 6 90482 1106 0 0
sp_cancel_client_order 1804 1804 0 0.00 6 7604 395 0 0
sp_cancel_customer_reserve 602390 602390 0 0.00 1 35626 101 0 0
sp_cancel_pay_from_customer 699 699 0 0.00 3 2281 118 0 0
sp_cancel_pay_to_supplier 397 397 0 0.00 4 1899 111 0 0
sp_cancel_supplier_invoice 6855 6842 13 0.18 2 33403 912 0 13
sp_cancel_supplier_order 442 442 0 0.00 15 1385377 172618 0 0
sp_cancel_write_off 10193 10193 0 0.00 1 7743 162 0 0
sp_client_order 20610 20610 0 0.00 5 39288 483 0 0
sp_customer_reserve 460359 460359 0 0.00 0 96242 226 0 0
sp_fill_shopping_cart 31652 31652 0 0.00 1 18679 81 0 0
sp_fill_shopping_cart_clo_res 460328 460328 0 0.00 0 13931 17 0 0
sp_get_clo_for_invoice 5027 5027 0 0.00 0 20065 247 0 0
sp_kill_cost_storno 17447 17447 0 0.00 0 8356 70 0 0
sp_kill_qstorno_ret_qs2qd 1222257 1222257 0 0.00 0 35572 44 0 0
sp_kill_qty_storno 642866 642866 0 0.00 0 35599 100 0 0
sp_lock_selected_doc 20906 20906 0 0.00 0 6892 7 0 0
sp_make_cost_storno 27894 27894 0 0.00 0 13387 77 0 0
sp_multiply_rows_for_pdistr 27891 27891 0 0.00 0 6781 38 0 0
sp_multiply_rows_for_qdistr 20609 20609 0 0.00 0 38723 202 0 0
sp_pay_from_customer 7053 7053 0 0.00 5 15872 178 0 0
sp_pay_to_supplier 5802 5802 0 0.00 6 11230 188 0 0
sp_payment_common 12853 12853 0 0.00 2 11065 49 0 0
sp_qd_handle_on_cancel_clo 1804 1804 0 0.00 0 7519 313 0 0
sp_qd_handle_on_invoice_upd_sts 11184 11184 0 0.00 0 10357 498 0 0
sp_qd_handle_on_reserve_upd_sts 10011 10011 0 0.00 0 16145 56 0 0
sp_reserve_write_off 10015 10015 0 0.00 8 16393 283 0 0
sp_supplier_invoice 5529 5529 0 0.00 10 97714 2754 0 0
sp_supplier_order 5522 5522 0 0.00 7 56255 1328 0 0
srv_aggregate_perf_data 106 106 0 0.00 2120 184809 18163 0 0
srv_fill_mon_cache_memory 69 69 0 0.00 760 116097 33793 0 0
srv_find_qd_qs_mism 1102540 1102540 0 0.00 0 14806 9 0 0
srv_make_invnt_saldo 429 429 0 0.00 57 72149 4270 0 0
srv_make_money_saldo 853 853 0 0.00 2 11740 388 0 0
srv_recalc_idx_stat 4 4 0 0.00 58296 453217 281981 0 0
t$perf-abend:sp_add_invoice_to_stock 151 151 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 13 13 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5075 5075 0 0.00 1 9693 950 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 1 24592 449 0 0
t$perf-norm:sp_cancel_client_order 1804 1804 0 0.00 0 8815 30 0 0
t$perf-norm:sp_cancel_customer_reserve 731 731 0 0.00 0 5825 35 0 0
t$perf-norm:sp_cancel_pay_from_customer 699 699 0 0.00 0 498 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 397 397 0 0.00 0 4834 26 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 2743 44 0 0
t$perf-norm:sp_cancel_supplier_order 442 442 0 0.00 0 99495 7264 0 0
t$perf-norm:sp_cancel_write_off 1004 1004 0 0.00 0 1265 18 0 0
t$perf-norm:sp_client_order 20611 20611 0 0.00 0 9088 38 0 0
t$perf-norm:sp_customer_reserve 971 971 0 0.00 0 3724 20 0 0
t$perf-norm:sp_pay_from_customer 7053 7053 0 0.00 0 18488 28 0 0
t$perf-norm:sp_pay_to_supplier 5802 5802 0 0.00 0 16942 24 0 0
t$perf-norm:sp_reserve_write_off 10015 10015 0 0.00 0 12132 29 0 0
t$perf-norm:sp_supplier_invoice 5529 5529 0 0.00 0 5447 34 0 0
t$perf-norm:sp_supplier_order 5522 5522 0 0.00 0 4175 35 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 567 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 69 69 0 0.00 0 2407 40 0 0
t$perf-norm:srv_make_invnt_saldo 429 429 0 0.00 0 3937 28 0 0
t$perf-norm:srv_make_money_saldo 853 853 0 0.00 0 366 5 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 353 106 0 0
v_add_invoice_to_stock 10344 10042 302 2.91 0 7743 27 0 302
v_all_customers 37672 37672 0 0.00 0 64 4 0 0
v_all_suppliers 30744 30744 0 0.00 0 68 5 0 0
v_all_wares 41212 41212 0 0.00 0 101 4 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 920 14 0 0
v_cancel_client_order 3608 3608 0 0.00 0 2799 11 0 0
v_cancel_customer_prepayment 1398 1398 0 0.00 0 530 10 0 0
v_cancel_customer_reserve 1462 1462 0 0.00 0 2677 11 0 0
v_cancel_payment_to_supplier 794 794 0 0.00 0 1813 14 0 0
v_cancel_supplier_invoice 794 768 26 3.27 0 1408 26 0 26
v_cancel_supplier_order 884 884 0 0.00 0 1007 13 0 0
v_cancel_write_off 2008 2008 0 0.00 0 1806 23 0 0
v_max_id_clo_ord 5516 5516 0 0.00 0 1532 6 0 0
v_max_id_clo_res 970 970 0 0.00 0 2216 19 0 0
v_max_id_ord_sup 5525 5525 0 0.00 0 3467 8 0 0
v_max_non_paid_invoice 1468 1468 0 0.00 0 4677 12 0 0
v_max_non_paid_realizn 3717 3717 0 0.00 0 4656 10 0 0
v_min_id_clo_ord 5516 5516 0 0.00 0 6609 10 0 0
v_min_id_clo_res 970 970 0 0.00 0 3604 11 0 0
v_min_id_ord_sup 5525 5525 0 0.00 0 1837 6 0 0
v_min_non_paid_invoice 5800 5800 0 0.00 0 6374 16 0 0
v_min_non_paid_realizn 7053 7053 0 0.00 0 6376 19 0 0
v_our_firm 10212 10212 0 0.00 0 59 5 0 0
v_reserve_write_off 20018 20018 0 0.00 0 7854 11 0 0
x_lock_depdocs_on_canc_invoice 12616 12616 0 0.00 0 13393 103 0 0
x_lock_depdocs_on_canc_sup_ord 442 442 0 0.00 0 2880 301 0 0
x_make_qstorno_1000_1200 5522 5522 0 0.00 2 47383 1150 0 0
x_make_qstorno_1200_2000 5529 5529 0 0.00 4 97698 2560 0 0
x_make_qstorno_2100_3300 460357 460357 0 0.00 0 96240 197 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-01-2023 04:38:19 to 30-01-2023 04:38:23.

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
02:07:29 32 8192000000 8811509488 8827756544 11148976 0.135 6 2 3 0 11323296 112960 61360
02:08:30 36810 8192000000 9726002544 10023391232 799723248 8.893 100 85 170 0 828002464 7297168 20982048
02:10:11 13728 8192000000 9761720976 10068848640 835992464 9.260 100 91 180 1 869985440 12381696 21611280
02:11:32 9326 8192000000 9794450224 10111963136 864210064 9.542 100 79 158 0 906132128 23871584 18050480
02:12:47 10226 8192000000 9855469568 10190655488 914982896 10.047 100 78 152 0 969455904 37543824 16929184
02:14:04 4149 8192000000 9902442256 10243809280 960838832 10.497 100 91 181 1 1022050416 42713200 18498384
02:15:11 2582 8192000000 9953343520 10301485056 992143328 10.802 100 94 184 1 1072568224 61554048 18870848
02:16:17 1881 8192000000 10012351504 10368315392 1011814480 10.993 100 89 174 0 1131081600 101909776 17357344
02:17:22 1583 8192000000 9989675168 10357325824 1031904832 11.187 100 87 170 1 1112744752 62947072 17892848
02:18:25 1170 8192000000 10022075520 10393939968 1059094816 11.448 100 95 179 2 1146280080 69005392 18179872
02:19:28 1808 8192000000 10043846320 10422050816 1067819200 11.531 100 94 185 0 1161326576 73690864 19816512
02:20:32 1387 8192000000 10040519904 10418081792 1072305904 11.574 100 89 170 1 1158094800 68805104 16983792
02:21:36 1618 8192000000 10023944800 10404552704 1074599648 11.596 100 73 138 3 1148655568 60666160 13389760
02:22:39 1036 8192000000 10040979152 10421489664 1079390880 11.642 100 91 178 2 1163731200 66223328 18116992
02:23:42 1065 8192000000 10047798480 10435891200 1079493600 11.643 100 94 176 0 1171006160 73511264 18001296
02:24:45 1265 8192000000 10041157856 10427924480 1085615312 11.701 100 95 181 4 1164179504 59430592 19133600
02:25:48 1164 8192000000 10064180416 10451431424 1092712736 11.768 100 94 182 1 1185776928 74835872 18228320
02:26:51 1337 8192000000 10088248416 10479751168 1093459584 11.776 100 95 183 2 1210206176 97954912 18791680
02:27:53 1020 8192000000 10050132080 10438873088 1087526864 11.719 100 88 165 0 1174893120 70230816 17135440
02:28:54 1080 8192000000 10046554656 10433609728 1091953744 11.761 100 92 177 0 1167516528 57560784 18002000
02:29:57 1164 8192000000 10037341504 10426032128 1088412368 11.728 100 90 169 3 1158363600 52722848 17228384
02:30:59 1159 8192000000 10040507904 10423066624 1057594656 11.433 99 94 174 2 1162178224 85485200 19098368
02:32:01 1400 8192000000 10047741616 10433212416 1079645792 11.644 100 95 182 1 1172019776 73330944 19043040
02:33:04 1025 8192000000 10006829088 10381717504 1045584352 11.318 99 95 179 1 1127698336 62350992 19762992
02:34:06 1163 8192000000 9994679120 10369462272 1033996592 11.207 100 91 164 2 1118299664 67050448 17252624
02:35:08 1136 8192000000 10010936608 10388496384 1050643968 11.367 100 96 178 2 1133026848 63854048 18528832
02:36:10 2950 8192000000 9950994784 10330468352 1029402832 11.163 100 67 124 2 1088052816 46376320 12273664
02:37:14 1031 8192000000 10015573696 10393255936 1047265520 11.334 100 89 168 1 1141649120 77144688 17238912
02:38:23 22197 8192000000 10018893440 10402271232 1058146688 11.439 100 69 138 0 1138412192 64701536 15563968
02:39:55 2898 8192000000 10038680896 10423074816 1083741536 11.683 100 77 142 3 1163105280 64185024 15178720
02:41:01 1098 8192000000 10084911072 10470166528 1099001456 11.828 100 87 163 1 1206143040 88163248 18978336
02:42:04 849 8192000000 10121224896 10507341824 1108804928 11.921 100 80 150 2 1242885344 118449248 15631168
02:43:08 4415 8192000000 10123297520 10516434944 1113934928 11.970 100 65 126 1 1240522336 113034240 13553168
02:44:15 6227 8192000000 10167820208 10567356416 1126973888 12.093 100 70 137 0 1285490704 143895056 14621760
02:45:25 1343 8192000000 10214302512 10615439360 1144185872 12.255 100 90 171 1 1324782384 162273568 18322944
02:46:31 1070 8192000000 10247632160 10640506880 1146988640 12.281 100 91 172 2 1362148432 196925488 18234304
02:47:36 1242 8192000000 10269690272 10683449344 1156750400 12.373 100 93 177 0 1386721328 209623808 20347120
02:48:41 1184 8192000000 10252122816 10668232704 1156809648 12.373 99 92 178 2 1363457216 186331856 20315712
02:49:46 894 8192000000 10307331952 10722488320 1170318912 12.500 100 96 185 0 1419100608 228165632 20616064
02:50:50 1199 8192000000 10282019568 10706468864 1178357824 12.575 100 90 173 1 1389378112 191647760 19372528
02:51:55 1184 8192000000 10325581344 10731319296 1188150800 12.666 100 92 178 1 1429145872 221178816 19816256
02:52:58 1555 8192000000 10343917280 10762133504 1190677696 12.690 100 84 163 2 1449652272 239700752 19273824
02:54:06 854 8192000000 10355101456 10754355200 1181114896 12.601 99 94 184 0 1461683472 258439824 22128752
02:55:09 2181 8192000000 10413574928 10833346560 1193992864 12.721 100 94 183 0 1519662768 303285120 22384784
02:56:14 749 8192000000 10433528208 10841858048 1194600816 12.726 100 94 179 1 1539418832 322106800 22711216
02:57:18 1557 8192000000 10476818560 10888171520 1200669616 12.783 100 92 174 1 1581181424 360849216 19662592
02:58:22 1273 8192000000 10487915088 10935296000 1202229920 12.797 100 94 181 2 1599136064 375340704 21565440
02:59:27 999 8192000000 10500743504 10943397888 1204773024 12.821 100 93 185 0 1608940736 380676784 23490928
03:00:31 2726 8192000000 10519460864 10949992448 1204707904 12.820 100 57 109 0 1629080608 409816416 14556288
03:02:04 6398 8192000000 10532733488 10963161088 1187537120 12.660 100 57 114 0 1634671664 432405104 14729440
03:03:12 17264 8192000000 10493343520 10965565440 1182581328 12.614 100 39 76 1 1596590176 403585648 10423200
03:04:35 28090 8192000000 10500607952 10956402688 1155333120 12.360 98 43 82 0 1599719200 433513600 10872480
03:06:17 852 8192000000 10409606224 10954792960 1170171568 12.498 100 92 179 0 1511247200 319758496 21317136
03:07:20 10617 8192000000 10350780512 10852065280 1177558704 12.567 100 48 96 0 1462623792 273675440 11389648
03:08:48 42879 8192000000 10196034976 10692902912 1145294816 12.265 99 18 36 0 1317192240 167410384 4487040
03:11:34 61964 8192000000 10059253712 10491805696 1066186096 11.516 93 17 34 0 1110150576 40694064 3270416
03:13:38 33995 8192000000 10117562160 10553405440 1141859824 12.233 99 38 72 0 1194825776 44909040 8056912
03:15:53 67256 8192000000 10214181760 10680340480 1136100720 12.179 98 51 99 0 1304105104 156658800 11345584
03:18:53 82727 8192000000 10063123184 10511990784 1091404272 11.756 95 20 34 0 1121874480 26921824 3548384
03:21:19 69540 8192000000 10112358064 10564620288 1144760896 12.260 100 34 64 0 1217733344 66442000 6530448
03:23:31 17018 8192000000 10163507520 10609410048 1152849632 12.336 100 45 88 0 1267312496 104147632 10315232
03:24:50 11364 8192000000 10197193952 10619101184 1154691248 12.354 100 73 140 2 1288402672 118190096 15521328
03:26:02 106074 8192000000 10170508544 10667053056 1119425008 12.022 97 24 49 0 1261854304 136497168 5932128
03:28:57 36805 8192000000 10127612864 10599837696 1135330544 12.172 100 13 26 1 1234156208 96299664 2526000
03:32:33 85890 8192000000 10103694224 10568638464 1130958592 12.130 100 16 28 1 1192280192 58188112 3133488
03:34:59 65584 8192000000 10129714112 10619912192 1136390256 12.182 100 34 68 0 1245385728 101141376 7854096
03:37:56 29142 8192000000 10137436336 10581467136 1120928912 12.036 98 56 109 1 1191654160 57494208 13231040
03:39:29 97526 8192000000 10061734800 10513223680 1088468128 11.728 96 10 20 0 1131839856 41796400 1575328
03:42:16 69589 8192000000 10130247472 10583851008 1116735264 11.996 98 28 54 0 1232958976 110517488 5706224
03:44:26 116097 8192000000 10064434928 10538176512 1114493712 11.975 97 20 38 2 1183593872 65238240 3861920
03:47:27 85521 8192000000 10086488544 10525773824 1128169088 12.104 99 40 80 0 1170977824 34444816 8363920
03:49:54 24268 8192000000 10078699184 10544918528 1136841264 12.186 100 30 58 0 1180641280 37993696 5806320
03:51:27 72021 8192000000 10098180608 10553753600 1141746784 12.232 100 11 22 0 1198931408 55056528 2128096
03:53:43 50971 8192000000 10097189872 10577436672 1106180880 11.896 97 14 28 0 1179893840 70578752 3134208
03:55:35 60886 8192000000 10119947232 10574286848 1128250672 12.105 99 15 30 0 1212646960 80871440 3524848
03:57:49 46553 8192000000 10048585360 10500845568 1110320304 11.935 98 15 30 0 1145275200 31983536 2971360
03:59:36 74326 8192000000 10121310784 10573914112 1105875264 11.893 97 25 50 0 1162236896 51250416 5111216
04:01:51 26527 8192000000 10077635056 10521968640 1110759936 11.940 98 34 66 0 1176053616 57278688 8014992
04:03:18 17864 8192000000 10081204992 10515750912 1132763904 12.147 100 43 82 0 1172148016 31830880 7553232
04:04:36 31219 8192000000 10094997808 10538381312 1111821376 11.950 98 29 58 0 1178202800 60417968 5963456
04:06:27 10630 8192000000 10115309648 10536742912 1118752800 12.015 98 61 119 0 1200250048 69797296 11699952
04:07:39 59829 8192000000 10075379872 10498379776 1078813232 11.636 96 43 82 1 1119331968 31884944 8633792
04:09:40 75491 8192000000 10052291888 10520043520 1109634576 11.929 99 19 37 1 1150233792 37211776 3387440
04:11:58 77028 8192000000 10046272032 10526420992 1104867568 11.884 98 15 30 0 1176528656 68630016 3031072
04:14:16 53573 8192000000 10089268560 10530861056 1111624000 11.948 98 24 47 1 1170548432 53808784 5115648
04:16:12 22117 8192000000 10146816464 10594562048 1138637776 12.203 99 28 53 0 1229612592 84839840 6134976
04:17:38 38762 8192000000 10173741824 10617294848 1117634256 12.005 97 35 68 0 1236248688 110497920 8116512
04:19:24 16733 8192000000 10204459872 10637844480 1152115792 12.329 100 41 79 0 1298395472 137003392 9276288
04:20:43 13929 8192000000 10233925408 10665369600 1138156416 12.198 99 45 90 0 1327475328 178739376 10579536
04:22:02 35311 8192000000 10229883056 10666610688 1132310928 12.143 99 45 88 1 1311739536 169112720 10315888
04:23:52 22140 8192000000 10302125168 10759327744 1155281808 12.359 100 43 86 0 1389165568 223856720 10027040
04:25:29 24806 8192000000 10336464768 10780442624 1136999840 12.187 98 33 66 1 1412920528 267946400 7974288
04:26:55 16803 8192000000 10303186384 10810572800 1147122208 12.282 100 22 44 0 1393696432 240712048 5862176
04:29:03 103023 8192000000 10112021296 10770673664 1126270032 12.086 98 8 16 0 1260731280 132671376 1789872
04:31:48 76694 8192000000 10093684464 10590167040 1124552656 12.070 98 11 22 1 1206415856 79220336 2642864
04:34:27 72291 8192000000 10078691984 10515976192 1118625632 12.014 98 27 52 2 1148369952 24412928 5331392
04:36:41 7794 8192000000 10110895184 10540363776 1134122224 12.160 100 47 94 0 1192759072 49386736 9250112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 30-01-2023 04:38:25 to 30-01-2023 04:38:42.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 151 v_add_invoice_to_stock 335544517
except 13 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-01-2023 04:38:43 to 30-01-2023 04:38:45.

Content of mon$database and FB version

MON$DATABASE_NAME               /home/bases/oltp40-etalone.fdb
MON$PAGE_SIZE                   8192
MON$ODS_MAJOR                   13
MON$ODS_MINOR                   0
MON$OLDEST_TRANSACTION          217240
MON$OLDEST_ACTIVE               221171
MON$OLDEST_SNAPSHOT             221171
MON$NEXT_TRANSACTION            221171
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-30 07:30:08.6030 GMT
MON$PAGES                       2102080
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2894 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2894 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2894 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2894 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
on disk structure version 13.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 ---
:165: 
:166: mscs-test-161.budzdorov.ru    Mon Jan 30 04:37:33 2023
:167:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:168: 
:169: 
:170: mscs-test-161.budzdorov.ru    Mon Jan 30 04:37:38 2023
:171:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:172: 
:173: 
:174: mscs-test-161.budzdorov.ru    Mon Jan 30 04:37:38 2023
:175:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:176: 
:177: 
:178: mscs-test-161.budzdorov.ru    Mon Jan 30 04:37:38 2023
:179:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:180: 
--- end of diff output ---

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

30.01.23 04:38:46. There are 3 logs in /var/tmp/logs-oltp40 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-01-30 04:38:48 No severe PSQL-related problems occured
30.01.23 04:38:50. End of report.