Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.02.2023 04: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 15090824 kB
MemAvailable 30360284 kB
Buffers 20 kB
Cached 16519956 kB
SwapCached 3140 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-02-20 04:37:25
DTS_END                         2023-02-20 04:37:25.9180
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/54860
TRN_ID                          219321
ATT_ID                          618
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: 9034.03 27159 1084083
customer order: creation 165.45 454 19854
customer order: refuse 14.83 353 1779
order to supplier: creation 44.23 1294 5308
order to supplier: removal 3.50 164405 420
invoice (draft): creation 44.46 2663 5335
invoice (draft): removal 50.56 1028 6067
invoice accept: apply 41.18 27048 4942
invoice accept: cancel 45.41 1072 5449
customer reserve: creation 3911.59 256 469391
customer reserve: removal 4432.58 100 531909
realization accept: apply 81.59 231 9791
realization accept: cancel 72.12 168 8654
payment from customer: creation 57.37 146 6884
payment from customer: removal 5.44 104 653
payment to supplier: creation 48.15 164 5778
payment to supplier: removal 3.23 98 388
service: total inventory turnovers 4.02 3333 482
service: total monetary turnovers 7.33 320 880
service: aggregate perf. data 0.96 15338 115
service: refresh index statistics 0.03 324598 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-02-2023 04:38:29 to 20-02-2023 04:38:31.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7072
TEST_TIME 2 4463
TEST_TIME 3 4423
TEST_TIME 4 5618
TEST_TIME 5 5502
TEST_TIME 6 5575
TEST_TIME 7 5427
TEST_TIME 8 5454
TEST_TIME 9 5540
TEST_TIME 10 5545
TEST_TIME 11 5630
TEST_TIME 12 5786
TEST_TIME 13 5910
TEST_TIME 14 6213
TEST_TIME 15 6257
TEST_TIME 16 6622
TEST_TIME 17 6769
TEST_TIME 18 6848
TEST_TIME 19 7365
TEST_TIME 20 7591
TEST_TIME 21 7624
TEST_TIME 22 7540
TEST_TIME 23 7552
TEST_TIME 24 7863
TEST_TIME 25 7999
TEST_TIME 26 8373
TEST_TIME 27 8722
TEST_TIME 28 9861
TEST_TIME 29 10230
TEST_TIME 30 10785
TEST_TIME 31 11074
TEST_TIME 32 11052
TEST_TIME 33 10863
TEST_TIME 34 10883
TEST_TIME 35 10880
TEST_TIME 36 10663
TEST_TIME 37 10510
TEST_TIME 38 10370
TEST_TIME 39 10184
TEST_TIME 40 10099
TEST_TIME 41 10002
TEST_TIME 42 9882
TEST_TIME 43 9875
TEST_TIME 44 9873
TEST_TIME 45 9868
TEST_TIME 46 9895
TEST_TIME 47 9854
TEST_TIME 48 9761
TEST_TIME 49 9698
TEST_TIME 50 9737
TEST_TIME 51 9784
TEST_TIME 52 9823
TEST_TIME 53 9850
TEST_TIME 54 9779
TEST_TIME 55 9821
TEST_TIME 56 9783
TEST_TIME 57 9731
TEST_TIME 58 9795
TEST_TIME 59 9784
TEST_TIME 60 9921
TEST_TIME 61 9830
TEST_TIME 62 9820
TEST_TIME 63 9760
TEST_TIME 64 9701
TEST_TIME 65 9673
TEST_TIME 66 9605
TEST_TIME 67 9554
TEST_TIME 68 9446
TEST_TIME 69 9422
TEST_TIME 70 9331
TEST_TIME 71 9350
TEST_TIME 72 9401
TEST_TIME 73 9362
TEST_TIME 74 9387
TEST_TIME 75 9372
TEST_TIME 76 9368
TEST_TIME 77 9385
TEST_TIME 78 9339
TEST_TIME 79 9331
TEST_TIME 80 9342
TEST_TIME 81 9387
TEST_TIME 82 9498
TEST_TIME 83 9533
TEST_TIME 84 9565
TEST_TIME 85 9558
TEST_TIME 86 9461
TEST_TIME 87 9405
TEST_TIME 88 9367
TEST_TIME 89 9325
TEST_TIME 90 9290
TEST_TIME 91 9225
TEST_TIME 92 9246
TEST_TIME 93 9227
TEST_TIME 94 9191
TEST_TIME 95 9192
TEST_TIME 96 9249
TEST_TIME 97 9202
TEST_TIME 98 9222
TEST_TIME 99 9244
TEST_TIME 100 9220
TEST_TIME 101 9232
TEST_TIME 102 9207
TEST_TIME 103 9304
TEST_TIME 104 9273
TEST_TIME 105 9243
TEST_TIME 106 9191
TEST_TIME 107 9208
TEST_TIME 108 9172
TEST_TIME 109 9164
TEST_TIME 110 9135
TEST_TIME 111 9119
TEST_TIME 112 9091
TEST_TIME 113 9086
TEST_TIME 114 9083
TEST_TIME 115 9117
TEST_TIME 116 9102
TEST_TIME 117 9112
TEST_TIME 118 9104
TEST_TIME 119 9093
TEST_TIME 120 9055

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 20-02-2023 04:38:33 to 20-02-2023 04:38:36.

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 1515630 1515630 0 0.00 0 14205 14 0 0
doc_list_biud 1515624 1515624 0 0.00 0 39119 40 0 0
sp_add_invoice_to_stock 5134 4942 192 3.73 48 218539 27049 0 192
sp_cancel_adding_invoice 5449 5449 0 0.00 6 68666 1073 0 0
sp_cancel_client_order 1779 1779 0 0.00 6 8610 353 0 0
sp_cancel_customer_reserve 531909 531909 0 0.00 1 24253 101 0 0
sp_cancel_pay_from_customer 653 653 0 0.00 4 3173 105 0 0
sp_cancel_pay_to_supplier 388 388 0 0.00 4 1156 98 0 0
sp_cancel_supplier_invoice 6087 6067 20 0.32 3 13880 1029 0 20
sp_cancel_supplier_order 420 420 0 0.00 14 1554194 164405 0 0
sp_cancel_write_off 8654 8654 0 0.00 2 9611 169 0 0
sp_client_order 19854 19854 0 0.00 5 25468 454 0 0
sp_customer_reserve 469391 469391 0 0.00 1 49405 257 0 0
sp_fill_shopping_cart 30493 30493 0 0.00 1 9850 69 0 0
sp_fill_shopping_cart_clo_res 469369 469369 0 0.00 0 19761 19 0 0
sp_get_clo_for_invoice 4910 4910 0 0.00 0 19452 281 0 0
sp_kill_cost_storno 15144 15144 0 0.00 0 6981 71 0 0
sp_kill_qstorno_ret_qs2qd 1078959 1078959 0 0.00 0 39113 44 0 0
sp_kill_qty_storno 568979 568979 0 0.00 0 39118 100 0 0
sp_lock_selected_doc 20262 20262 0 0.00 0 7773 6 0 0
sp_make_cost_storno 27363 27363 0 0.00 0 14203 69 0 0
sp_multiply_rows_for_pdistr 27363 27363 0 0.00 0 6407 33 0 0
sp_multiply_rows_for_qdistr 19854 19854 0 0.00 0 13249 192 0 0
sp_pay_from_customer 6884 6884 0 0.00 5 11831 147 0 0
sp_pay_to_supplier 5778 5778 0 0.00 6 9236 165 0 0
sp_payment_common 12661 12661 0 0.00 2 7091 35 0 0
sp_qd_handle_on_cancel_clo 1779 1779 0 0.00 0 8599 295 0 0
sp_qd_handle_on_invoice_upd_sts 10359 10359 0 0.00 0 14342 473 0 0
sp_qd_handle_on_reserve_upd_sts 9791 9791 0 0.00 0 12340 52 0 0
sp_reserve_write_off 9791 9791 0 0.00 8 14414 232 0 0
sp_supplier_invoice 5335 5335 0 0.00 9 76929 2663 0 0
sp_supplier_order 5308 5308 0 0.00 7 50094 1295 0 0
srv_aggregate_perf_data 115 115 0 0.00 2375 153105 15338 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 746 143903 32740 0 0
srv_find_qd_qs_mism 1035457 1035457 0 0.00 0 13462 12 0 0
srv_make_invnt_saldo 482 482 0 0.00 40 79631 3334 0 0
srv_make_money_saldo 880 880 0 0.00 1 9507 320 0 0
srv_recalc_idx_stat 4 4 0 0.00 164339 517656 324598 0 0
t$perf-abend:sp_add_invoice_to_stock 192 192 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 20 20 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4945 4945 0 0.00 1 42347 781 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 2 7580 363 0 0
t$perf-norm:sp_cancel_client_order 1779 1779 0 0.00 0 1891 25 0 0
t$perf-norm:sp_cancel_customer_reserve 629 629 0 0.00 0 1001 24 0 0
t$perf-norm:sp_cancel_pay_from_customer 653 653 0 0.00 0 870 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 388 388 0 0.00 0 5888 29 0 0
t$perf-norm:sp_cancel_supplier_invoice 354 354 0 0.00 0 3231 35 0 0
t$perf-norm:sp_cancel_supplier_order 420 420 0 0.00 0 79514 6759 0 0
t$perf-norm:sp_cancel_write_off 956 956 0 0.00 0 849 16 0 0
t$perf-norm:sp_client_order 19854 19854 0 0.00 0 9271 30 0 0
t$perf-norm:sp_customer_reserve 997 997 0 0.00 0 1488 13 0 0
t$perf-norm:sp_pay_from_customer 6884 6884 0 0.00 0 18552 20 0 0
t$perf-norm:sp_pay_to_supplier 5778 5778 0 0.00 0 7483 18 0 0
t$perf-norm:sp_reserve_write_off 9791 9791 0 0.00 0 12007 20 0 0
t$perf-norm:sp_supplier_invoice 5335 5335 0 0.00 0 6407 25 0 0
t$perf-norm:sp_supplier_order 5308 5308 0 0.00 0 1768 26 0 0
t$perf-norm:srv_aggregate_perf_data 115 115 0 0.00 0 217 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 229 9 0 0
t$perf-norm:srv_make_invnt_saldo 482 482 0 0.00 0 529 13 0 0
t$perf-norm:srv_make_money_saldo 880 880 0 0.00 0 2759 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 3 291 82 0 0
v_add_invoice_to_stock 10200 9816 384 3.76 0 2127 23 0 384
v_all_customers 36320 36320 0 0.00 0 85 3 0 0
v_all_suppliers 30294 30294 0 0.00 0 74 4 0 0
v_all_wares 39708 39708 0 0.00 0 108 3 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 1999 14 0 0
v_cancel_client_order 3558 3558 0 0.00 0 2407 8 0 0
v_cancel_customer_prepayment 1306 1306 0 0.00 0 1933 12 0 0
v_cancel_customer_reserve 1258 1258 0 0.00 0 2142 11 0 0
v_cancel_payment_to_supplier 776 776 0 0.00 0 382 10 0 0
v_cancel_supplier_invoice 748 708 40 5.34 0 338 22 0 40
v_cancel_supplier_order 840 840 0 0.00 0 694 12 0 0
v_cancel_write_off 1912 1912 0 0.00 0 2049 21 0 0
v_max_id_clo_ord 5307 5307 0 0.00 0 891 4 0 0
v_max_id_clo_res 997 997 0 0.00 0 682 9 0 0
v_max_id_ord_sup 5332 5332 0 0.00 0 3428 7 0 0
v_max_non_paid_invoice 1270 1270 0 0.00 0 62 7 0 0
v_max_non_paid_realizn 3635 3635 0 0.00 0 3760 7 0 0
v_min_id_clo_ord 5307 5307 0 0.00 0 2708 8 0 0
v_min_id_clo_res 997 997 0 0.00 0 9080 19 0 0
v_min_id_ord_sup 5332 5332 0 0.00 0 3808 6 0 0
v_min_non_paid_invoice 5778 5778 0 0.00 0 1987 9 0 0
v_min_non_paid_realizn 6883 6883 0 0.00 0 7085 14 0 0
v_our_firm 9884 9884 0 0.00 0 67 4 0 0
v_reserve_write_off 19580 19580 0 0.00 0 2573 8 0 0
x_lock_depdocs_on_canc_invoice 11162 11162 0 0.00 0 12957 126 0 0
x_lock_depdocs_on_canc_sup_ord 420 420 0 0.00 0 12430 361 0 0
x_make_qstorno_1000_1200 5308 5308 0 0.00 2 50085 1157 0 0
x_make_qstorno_1200_2000 5335 5335 0 0.00 4 74826 2469 0 0
x_make_qstorno_2100_3300 469391 469391 0 0.00 0 49385 227 0 0

Completed "Performance in DETAILS". Done for 00:00:05, from 20-02-2023 04:38:37 to 20-02-2023 04:38:42.

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 4 8192000000 8802671904 8815370240 7054992 0.086 3 2 2 0 7161024 70256 35776
02:08:31 29097 8192000000 9717657488 10012950528 793289904 8.828 100 92 184 0 823689936 7686048 22713984
02:10:04 23453 8192000000 9760041168 10067759104 836313200 9.263 100 88 176 0 868997232 11624704 21059328
02:11:34 10692 8192000000 9785414096 10107584512 861285200 9.513 100 78 156 0 899632352 20299552 18047600
02:12:52 4805 8192000000 9851082784 10192072704 919205472 10.088 100 72 144 0 966842624 32146416 15490736
02:14:00 3084 8192000000 9920240528 10263941120 969247600 10.579 100 87 173 0 1036325888 49204560 17873728
02:15:06 2851 8192000000 9968438752 10323193856 997666992 10.856 100 88 169 1 1080047376 65635184 16745200
02:16:11 937 8192000000 9998484464 10359328768 1025219184 11.122 100 95 188 3 1120504176 76363040 18921952
02:17:14 950 8192000000 10013488288 10386317312 1044072528 11.304 100 96 191 1 1132339376 69299488 18967360
02:18:16 1175 8192000000 10035401456 10409304064 1055517376 11.414 100 95 182 0 1153632192 79802432 18312384
02:19:18 865 8192000000 10030447808 10406002688 1065381904 11.508 100 93 183 0 1150691568 66132208 19177456
02:20:23 1033 8192000000 10049235264 10435764224 1067976336 11.533 100 94 183 1 1167301664 79956288 19369040
02:21:26 962 8192000000 10056324304 10437828608 1075765664 11.607 100 94 179 0 1176739264 80408384 20565216
02:22:28 1677 8192000000 10055203072 10447159296 1080574928 11.653 100 84 158 3 1179548768 83300336 15673504
02:23:31 992 8192000000 10067067744 10455609344 1086162480 11.706 100 97 179 0 1191005600 85389024 19454096
02:24:33 1186 8192000000 10070508624 10458759168 1092473152 11.766 100 97 182 0 1191949136 81465776 18010208
02:25:36 1232 8192000000 10067366384 10460160000 1093218480 11.773 100 96 184 2 1190764944 78550032 18996432
02:26:37 1157 8192000000 10078652720 10468610048 1096579744 11.805 100 98 187 2 1199666688 83187136 19899808
02:27:40 3761 8192000000 10035701632 10422476800 1095494384 11.795 100 80 145 4 1153050240 42958400 14597456
02:28:45 1012 8192000000 10058552320 10449625088 1096311168 11.803 100 94 173 1 1182892416 68192240 18389008
02:29:47 915 8192000000 10055602464 10445336576 1089322080 11.736 100 88 170 0 1177793584 70810464 17661040
02:30:48 1123 8192000000 10024871712 10407743488 1072842880 11.579 100 92 176 0 1149172096 57702080 18627136
02:31:52 1684 8192000000 9983083744 10372157440 1067757872 11.531 100 66 119 3 1106419344 24663632 13997840
02:32:55 2411 8192000000 9992635536 10368335872 1037099744 11.237 100 78 142 1 1113183248 61614064 14469440
02:33:58 1082 8192000000 9999209248 10372816896 1038800832 11.253 100 98 176 0 1126527584 68260416 19466336
02:35:00 1385 8192000000 9983125776 10352099328 1021839744 11.090 100 96 182 3 1106192960 66196176 18157040
02:36:04 2512 8192000000 9989492080 10371747840 1027946416 11.149 100 67 126 3 1101363776 59939504 13477856
02:37:07 1122 8192000000 9993716784 10368565248 1043105120 11.294 100 93 177 0 1117241664 55051328 19085216
02:38:14 1247 8192000000 9997043888 10374258688 1045201536 11.315 99 77 140 0 1115094880 56381072 13512272
02:39:19 12297 8192000000 10013720976 10394898432 1065460336 11.509 100 69 134 0 1132338432 53325488 13552608
02:40:37 16782 8192000000 10067604496 10462003200 1083642672 11.682 100 58 116 0 1189786112 94076416 12067024
02:42:03 838 8192000000 10106266448 10494668800 1107471280 11.908 100 84 163 1 1229352896 102855440 19026176
02:43:13 11869 8192000000 10135375360 10529517568 1113246320 11.963 99 62 116 2 1244236864 118709248 12281296
02:44:29 7181 8192000000 10178662864 10579677184 1127346912 12.096 100 66 125 2 1297316112 155768752 14200448
02:45:48 1092 8192000000 10223669344 10618281984 1140658304 12.222 100 81 151 3 1335049632 177493104 16898224
02:46:54 1785 8192000000 10273454256 10674159616 1156454080 12.370 100 77 154 1 1382150800 207566160 18130560
02:48:00 2235 8192000000 10313754848 10710671360 1177443904 12.566 100 95 183 1 1425109184 227325520 20339760
02:49:05 1395 8192000000 10358200400 10761891840 1190635568 12.689 100 89 171 1 1472324272 262211056 19477648
02:50:11 1171 8192000000 10398088864 10804744192 1195458256 12.734 100 90 179 1 1510259264 293529088 21271920
02:51:16 3359 8192000000 10426431456 10846117888 1201991984 12.795 100 88 170 0 1534741536 312812816 19936736
02:52:23 1551 8192000000 10463522848 10876153856 1207362944 12.845 100 95 183 0 1571882528 343444000 21075584
02:53:27 779 8192000000 10462237184 10921623552 1207986560 12.850 100 94 173 0 1569132176 341111936 20033680
02:54:30 1807 8192000000 10475222176 10898915328 1214970128 12.915 100 94 177 1 1577134432 342151552 20012752
02:55:33 736 8192000000 10509105344 10929913856 1214858736 12.914 100 100 195 0 1613561248 375127632 23574880
02:56:37 762 8192000000 10538781488 11005505536 1213623312 12.903 100 98 194 1 1643807200 406095712 24088176
02:57:41 1730 8192000000 10551165168 10968989696 1222405760 12.984 100 86 167 1 1653739200 411423472 19909968
02:58:46 1179 8192000000 10612666400 11026489344 1225723216 13.015 100 94 177 0 1715299232 466912864 22663152
02:59:50 3563 8192000000 10656768336 11088957440 1209678016 12.866 99 71 141 1 1746757584 519500416 17579152
03:00:58 4144 8192000000 10689704656 11141251072 1218986368 12.952 100 63 126 0 1791192000 555102624 17103008
03:02:05 4054 8192000000 10690566128 11128479744 1193666736 12.717 99 56 112 0 1785974080 576629696 15677648
03:03:20 16245 8192000000 10621645920 11093946368 1189142848 12.675 100 48 96 0 1730826704 528285424 13398432
03:04:38 6998 8192000000 10520332832 11074678784 1181876976 12.608 100 53 104 0 1629011744 433811856 13322912
03:06:04 28709 8192000000 10349814896 10871599104 1175329440 12.547 99 30 60 0 1506672432 324097408 7245584
03:08:34 135796 8192000000 10103705264 10570694656 1074806784 11.598 93 10 20 0 1143725744 67042096 1876864
03:12:05 72812 8192000000 10079792672 10514190336 1117221760 12.001 98 17 30 1 1158232048 37982800 3027488
03:14:18 107269 8192000000 10059777760 10527518720 1106030880 11.895 98 25 49 0 1164699888 53383856 5285152
03:18:25 11241 8192000000 10097879440 10529996800 1115637072 11.986 99 39 75 0 1189880224 66941536 7301616
03:19:40 26780 8192000000 10128893872 10572636160 1102529456 11.862 98 49 93 0 1220906640 108773376 9603808
03:21:12 38574 8192000000 10090552784 10575040512 1085069216 11.696 98 13 27 0 1176415120 88709328 2636576
03:22:52 27598 8192000000 10073107824 10545807360 1069654160 11.549 97 20 38 0 1179652656 105347376 4651120
03:24:20 42535 8192000000 10088124576 10526203904 1109290480 11.926 99 30 57 0 1170953680 55509488 6153712
03:26:06 48337 8192000000 10095716800 10552901632 1096413632 11.804 98 24 49 0 1187963120 86627552 4921936
03:27:59 31661 8192000000 10077627504 10527072256 1122701392 12.053 100 27 54 0 1175979856 47731216 5547248
03:29:33 27017 8192000000 10075327600 10520387584 1121577680 12.042 100 28 55 0 1177006144 49959712 5468752
03:31:07 42169 8192000000 10096285120 10521739264 1104301536 11.878 98 35 60 2 1161771072 51198848 6270688
03:32:51 7768 8192000000 10076661472 10500907008 1122267264 12.048 100 53 102 2 1178631552 45896496 10467792
03:34:01 12140 8192000000 10145266016 10574925824 1133174160 12.151 100 48 96 0 1220887968 78208032 9505776
03:35:18 95054 8192000000 10135250784 10606034944 1088536816 11.729 96 15 30 0 1161290528 69436416 3317296
03:37:55 143903 8192000000 10087036064 10574159872 1099706528 11.835 97 29 58 0 1175232640 69718720 5807392
03:41:20 16529 8192000000 10089674960 10514792448 1123326080 12.058 100 54 97 1 1175758672 41850208 10582384
03:42:38 64872 8192000000 10045004096 10491015168 1070128512 11.553 95 16 32 0 1113147056 40105360 2913184
03:44:43 39963 8192000000 10073306464 10526486528 1122228944 12.048 100 9 19 0 1174350320 49891232 2230144
03:47:06 82085 8192000000 10093070144 10565066752 1109249344 11.925 98 12 24 1 1184959568 72983008 2727216
03:49:28 58507 8192000000 10091342928 10545373184 1138344720 12.200 100 15 28 2 1178292432 37086080 2861632
03:51:32 44457 8192000000 10119811008 10582511616 1133821392 12.157 99 37 72 0 1202604768 61703616 7079760
03:53:20 43525 8192000000 10108094928 10584145920 1108404032 11.917 97 27 54 0 1186376208 72553872 5418304
03:55:08 46537 8192000000 10177584240 10613481472 1112089104 11.952 97 44 86 0 1217975472 97461744 8424624
03:57:00 26968 8192000000 10153787712 10592849920 1126000432 12.084 99 27 52 1 1234358080 102314720 6042928
03:58:27 13374 8192000000 10130797056 10579542016 1127578496 12.099 100 51 100 2 1229130192 91452720 10098976
03:59:43 41464 8192000000 10074399408 10547703808 1096935104 11.809 98 22 42 0 1155356848 54338272 4083472
04:01:26 81316 8192000000 10074847536 10544754688 1109085616 11.924 98 26 48 0 1160087104 45670784 5330704
04:04:42 6219 8192000000 10054907424 10491285504 1112582000 11.957 99 34 64 1 1147668688 28961536 6125152
04:05:52 43245 8192000000 10095401360 10548727808 1133612960 12.155 100 20 40 0 1217130432 79574368 3943104
04:07:36 35442 8192000000 10099354528 10575237120 1136295040 12.181 99 13 26 0 1195124448 56072560 2756848
04:09:12 44552 8192000000 10096298000 10614673408 1140767264 12.223 100 8 16 0 1206905872 64232544 1906064
04:10:56 53925 8192000000 10090524032 10534576128 1126470256 12.088 99 20 31 0 1184987904 54858160 3659488
04:12:51 31833 8192000000 10085845184 10529226752 1086082528 11.705 96 23 44 0 1162049088 71219328 4747232
04:14:28 15960 8192000000 10064838400 10492985344 1106640528 11.901 98 53 100 0 1144978592 27780080 10557984
04:15:44 57988 8192000000 10073848656 10553671680 1094346768 11.784 97 23 46 1 1154370832 55812112 4211952
04:17:49 32888 8192000000 10078504704 10524082176 1091952416 11.761 97 39 74 0 1153184064 53916704 7314944
04:19:24 22273 8192000000 10139270848 10575024128 1122261840 12.048 99 47 90 1 1210468864 79060400 9146624
04:22:05 21263 8192000000 10128468480 10544660480 1119690240 12.024 99 60 114 0 1204002320 73105296 11206784
04:23:26 60329 8192000000 10107407232 10554306560 1132238640 12.142 100 34 60 0 1224008928 85980944 5789344
04:25:28 64752 8192000000 10087705488 10551259136 1148334112 12.294 100 16 25 1 1186828416 35955280 2539024
04:27:37 70032 8192000000 10056632064 10505633792 1072487200 11.576 93 22 44 0 1106333216 29744336 4101680
04:29:47 13436 8192000000 10099940016 10541522944 1113323744 11.964 97 36 70 0 1176531856 56041888 7166224
04:31:06 94776 8192000000 10095203600 10549456896 1122205264 12.048 98 20 40 0 1195875664 69565072 4105328
04:33:48 38690 8192000000 10111796704 10558902272 1132944160 12.149 99 35 68 2 1196776656 57138704 6693792
04:35:33 52966 8192000000 10170263184 10612002816 1141696704 12.231 99 19 38 0 1216458000 70628512 4132784

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:17, from 20-02-2023 04:38:43 to 20-02-2023 04:39:00.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 192 v_add_invoice_to_stock 335544517
except 20 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-02-2023 04:39:01 to 20-02-2023 04:39:03.

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          216338
MON$OLDEST_ACTIVE               219469
MON$OLDEST_SNAPSHOT             219469
MON$NEXT_TRANSACTION            219469
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                       2044576
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             771
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.3.2903 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.3.2903 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.3.2903 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.3.2903 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 ---
:25: 
:26: mscs-test-161.budzdorov.ru    Mon Feb 20 04:37:26 2023
:27:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:28: 
:29: 
:30: mscs-test-161.budzdorov.ru    Mon Feb 20 04:37:33 2023
:31:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:32: 
:33: 
:34: mscs-test-161.budzdorov.ru    Mon Feb 20 04:37:39 2023
:35:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:36: 
:37: 
:38: mscs-test-161.budzdorov.ru    Mon Feb 20 04:37:40 2023
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
--- end of diff output ---

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

20.02.23 04:39:04. 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-02-20 04:39:07 No severe PSQL-related problems occured
20.02.23 04:39:08. End of report.