Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 30.07.2022 04:39
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 15282356 kB
MemAvailable 31007916 kB
Buffers 20 kB
Cached 16388268 kB
SwapCached 2512 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.2
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 2022-07-30 04:37:20
DTS_END                         2022-07-30 04:37:20.5810
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/55826
TRN_ID                          212550
ATT_ID                          599
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: 8384.15 33968 1006098
customer order: creation 156.18 494 18741
customer order: refuse 13.74 474 1649
order to supplier: creation 42.08 1460 5049
order to supplier: removal 3.08 210981 370
invoice (draft): creation 43.25 3728 5190
invoice (draft): removal 47.56 1194 5707
invoice accept: apply 39.08 29627 4690
invoice accept: cancel 42.43 1555 5091
customer reserve: creation 3548.09 286 425771
customer reserve: removal 4168.63 120 500236
realization accept: apply 76.76 305 9211
realization accept: cancel 81.89 196 9827
payment from customer: creation 55.15 191 6618
payment from customer: removal 5.43 142 651
payment to supplier: creation 45.70 209 5484
payment to supplier: removal 3.20 138 384
service: total inventory turnovers 3.53 5384 424
service: total monetary turnovers 7.48 515 898
service: aggregate perf. data 0.85 15950 102
service: refresh index statistics 0.04 406417 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 30-07-2022 04:39:42 to 30-07-2022 04:39:44.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6688
TEST_TIME 2 5977
TEST_TIME 3 5889
TEST_TIME 4 5630
TEST_TIME 5 5883
TEST_TIME 6 5959
TEST_TIME 7 5837
TEST_TIME 8 5660
TEST_TIME 9 5923
TEST_TIME 10 6311
TEST_TIME 11 6473
TEST_TIME 12 6291
TEST_TIME 13 6400
TEST_TIME 14 6371
TEST_TIME 15 6637
TEST_TIME 16 6758
TEST_TIME 17 6783
TEST_TIME 18 6981
TEST_TIME 19 7150
TEST_TIME 20 7219
TEST_TIME 21 7254
TEST_TIME 22 7347
TEST_TIME 23 7431
TEST_TIME 24 7598
TEST_TIME 25 7649
TEST_TIME 26 7842
TEST_TIME 27 7902
TEST_TIME 28 7878
TEST_TIME 29 8183
TEST_TIME 30 8536
TEST_TIME 31 8473
TEST_TIME 32 8839
TEST_TIME 33 9017
TEST_TIME 34 9282
TEST_TIME 35 9642
TEST_TIME 36 9829
TEST_TIME 37 9914
TEST_TIME 38 10078
TEST_TIME 39 10078
TEST_TIME 40 10017
TEST_TIME 41 10027
TEST_TIME 42 9989
TEST_TIME 43 10016
TEST_TIME 44 9955
TEST_TIME 45 9737
TEST_TIME 46 9708
TEST_TIME 47 9929
TEST_TIME 48 9937
TEST_TIME 49 9873
TEST_TIME 50 9739
TEST_TIME 51 9610
TEST_TIME 52 9483
TEST_TIME 53 9432
TEST_TIME 54 9448
TEST_TIME 55 9447
TEST_TIME 56 9351
TEST_TIME 57 9259
TEST_TIME 58 9149
TEST_TIME 59 9077
TEST_TIME 60 9018
TEST_TIME 61 9097
TEST_TIME 62 9006
TEST_TIME 63 8923
TEST_TIME 64 8974
TEST_TIME 65 8937
TEST_TIME 66 8833
TEST_TIME 67 8765
TEST_TIME 68 8720
TEST_TIME 69 8734
TEST_TIME 70 8655
TEST_TIME 71 8608
TEST_TIME 72 8574
TEST_TIME 73 8577
TEST_TIME 74 8611
TEST_TIME 75 8546
TEST_TIME 76 8565
TEST_TIME 77 8528
TEST_TIME 78 8487
TEST_TIME 79 8504
TEST_TIME 80 8547
TEST_TIME 81 8578
TEST_TIME 82 8651
TEST_TIME 83 8580
TEST_TIME 84 8561
TEST_TIME 85 8669
TEST_TIME 86 8663
TEST_TIME 87 8663
TEST_TIME 88 8706
TEST_TIME 89 8715
TEST_TIME 90 8647
TEST_TIME 91 8671
TEST_TIME 92 8648
TEST_TIME 93 8604
TEST_TIME 94 8574
TEST_TIME 95 8542
TEST_TIME 96 8537
TEST_TIME 97 8496
TEST_TIME 98 8488
TEST_TIME 99 8466
TEST_TIME 100 8429
TEST_TIME 101 8379
TEST_TIME 102 8428
TEST_TIME 103 8427
TEST_TIME 104 8381
TEST_TIME 105 8387
TEST_TIME 106 8365
TEST_TIME 107 8356
TEST_TIME 108 8375
TEST_TIME 109 8385
TEST_TIME 110 8385
TEST_TIME 111 8435
TEST_TIME 112 8489
TEST_TIME 113 8542
TEST_TIME 114 8596
TEST_TIME 115 8581
TEST_TIME 116 8588
TEST_TIME 117 8533
TEST_TIME 118 8490
TEST_TIME 119 8477
TEST_TIME 120 8418

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 30-07-2022 04:39:45 to 30-07-2022 04:39:49.

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 1399263 1399263 0 0.00 0 32196 15 0 0
doc_list_biud 1399250 1399250 0 0.00 0 66249 50 0 0
sp_add_invoice_to_stock 4822 4690 132 2.73 73 261651 29628 0 132
sp_cancel_adding_invoice 5091 5091 0 0.00 8 81998 1555 0 0
sp_cancel_client_order 1649 1649 0 0.00 5 8099 474 0 0
sp_cancel_customer_reserve 500236 500236 0 0.00 1 40134 121 0 0
sp_cancel_pay_from_customer 651 651 0 0.00 4 7176 143 0 0
sp_cancel_pay_to_supplier 384 384 0 0.00 4 1955 138 0 0
sp_cancel_supplier_invoice 5717 5707 10 0.17 4 66256 1194 0 10
sp_cancel_supplier_order 370 370 0 0.00 23 1693876 210981 0 0
sp_cancel_write_off 9827 9827 0 0.00 2 10831 196 0 0
sp_client_order 18741 18741 0 0.00 5 29906 494 0 0
sp_customer_reserve 425771 425771 0 0.00 1 66133 286 0 0
sp_fill_shopping_cart 28968 28968 0 0.00 1 16712 99 0 0
sp_fill_shopping_cart_clo_res 425739 425739 0 0.00 0 13119 21 0 0
sp_get_clo_for_invoice 4643 4643 0 0.00 0 35137 349 0 0
sp_kill_cost_storno 15953 15953 0 0.00 0 9893 90 0 0
sp_kill_qstorno_ret_qs2qd 1016376 1016376 0 0.00 0 66247 53 0 0
sp_kill_qty_storno 536733 536733 0 0.00 0 66248 122 0 0
sp_lock_selected_doc 19270 19270 0 0.00 0 8331 7 0 0
sp_make_cost_storno 25956 25956 0 0.00 0 26676 91 0 0
sp_multiply_rows_for_pdistr 25955 25955 0 0.00 0 8487 41 0 0
sp_multiply_rows_for_qdistr 18741 18741 0 0.00 0 16932 165 0 0
sp_pay_from_customer 6618 6618 0 0.00 6 17513 191 0 0
sp_pay_to_supplier 5484 5484 0 0.00 6 10408 210 0 0
sp_payment_common 12101 12101 0 0.00 2 8573 48 0 0
sp_qd_handle_on_cancel_clo 1649 1649 0 0.00 0 8084 394 0 0
sp_qd_handle_on_invoice_upd_sts 9733 9733 0 0.00 0 56925 692 0 0
sp_qd_handle_on_reserve_upd_sts 9211 9211 0 0.00 0 15342 73 0 0
sp_reserve_write_off 9211 9211 0 0.00 8 15375 306 0 0
sp_supplier_invoice 5190 5190 0 0.00 12 131279 3729 0 0
sp_supplier_order 5049 5049 0 0.00 8 29503 1461 0 0
srv_aggregate_perf_data 102 102 0 0.00 2348 186702 15950 0 0
srv_fill_mon_cache_memory 72 72 0 0.00 733 116300 31119 0 0
srv_find_qd_qs_mism 961442 961442 0 0.00 0 11083 11 0 0
srv_make_invnt_saldo 424 424 0 0.00 51 76196 5385 0 0
srv_make_money_saldo 898 898 0 0.00 1 20675 516 0 0
srv_recalc_idx_stat 5 5 0 0.00 202815 675686 406417 0 0
t$perf-abend:sp_add_invoice_to_stock 132 132 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4696 4696 0 0.00 1 21454 1053 0 0
t$perf-norm:sp_cancel_adding_invoice 380 380 0 0.00 2 7226 552 0 0
t$perf-norm:sp_cancel_client_order 1649 1649 0 0.00 0 4988 36 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 10227 54 0 0
t$perf-norm:sp_cancel_pay_from_customer 651 651 0 0.00 0 1316 16 0 0
t$perf-norm:sp_cancel_pay_to_supplier 384 384 0 0.00 0 984 21 0 0
t$perf-norm:sp_cancel_supplier_invoice 386 386 0 0.00 0 3647 46 0 0
t$perf-norm:sp_cancel_supplier_order 370 370 0 0.00 0 114604 9148 0 0
t$perf-norm:sp_cancel_write_off 957 957 0 0.00 0 3488 25 0 0
t$perf-norm:sp_client_order 18741 18741 0 0.00 0 9827 43 0 0
t$perf-norm:sp_customer_reserve 903 903 0 0.00 0 5294 22 0 0
t$perf-norm:sp_pay_from_customer 6618 6618 0 0.00 0 5572 21 0 0
t$perf-norm:sp_pay_to_supplier 5484 5484 0 0.00 0 5375 21 0 0
t$perf-norm:sp_reserve_write_off 9212 9212 0 0.00 0 7559 30 0 0
t$perf-norm:sp_supplier_invoice 5190 5190 0 0.00 0 4789 38 0 0
t$perf-norm:sp_supplier_order 5049 5049 0 0.00 0 2943 34 0 0
t$perf-norm:srv_aggregate_perf_data 102 102 0 0.00 0 826 29 0 0
t$perf-norm:srv_fill_mon_cache_memory 72 72 0 0.00 0 188 7 0 0
t$perf-norm:srv_make_invnt_saldo 424 424 0 0.00 0 1227 17 0 0
t$perf-norm:srv_make_money_saldo 898 898 0 0.00 0 434 6 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 6 200 88 0 0
v_add_invoice_to_stock 9528 9264 264 2.77 0 7126 31 0 264
v_all_customers 34292 34292 0 0.00 0 103 4 0 0
v_all_suppliers 28424 28424 0 0.00 0 150 5 0 0
v_all_wares 37476 37476 0 0.00 0 106 4 0 0
v_cancel_adding_invoice 760 760 0 0.00 0 2937 25 0 0
v_cancel_client_order 3298 3298 0 0.00 0 1617 12 0 0
v_cancel_customer_prepayment 1302 1302 0 0.00 0 297 12 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 1 3017 18 0 0
v_cancel_payment_to_supplier 768 768 0 0.00 0 535 14 0 0
v_cancel_supplier_invoice 792 772 20 2.52 0 500 27 0 20
v_cancel_supplier_order 740 740 0 0.00 0 409 12 0 0
v_cancel_write_off 1914 1914 0 0.00 0 3868 31 0 0
v_max_id_clo_ord 5046 5046 0 0.00 0 414 6 0 0
v_max_id_clo_res 902 902 0 0.00 0 7390 22 0 0
v_max_id_ord_sup 5181 5181 0 0.00 0 6698 10 0 0
v_max_non_paid_invoice 1496 1496 0 0.00 0 766 9 0 0
v_max_non_paid_realizn 3503 3503 0 0.00 0 5511 10 0 0
v_min_id_clo_ord 5046 5046 0 0.00 0 3651 10 0 0
v_min_id_clo_res 902 902 0 0.00 0 3260 15 0 0
v_min_id_ord_sup 5181 5181 0 0.00 0 5124 13 0 0
v_min_non_paid_invoice 5483 5483 0 0.00 0 5587 16 0 0
v_min_non_paid_realizn 6618 6618 0 0.00 0 8554 17 0 0
v_our_firm 9408 9408 0 0.00 0 68 5 0 0
v_reserve_write_off 18422 18422 0 0.00 0 4609 12 0 0
x_lock_depdocs_on_canc_invoice 10412 10412 0 0.00 0 19456 160 0 0
x_lock_depdocs_on_canc_sup_ord 370 370 0 0.00 0 8362 517 0 0
x_make_qstorno_1000_1200 5049 5049 0 0.00 2 29464 1265 0 0
x_make_qstorno_1200_2000 5190 5190 0 0.00 5 131233 3445 0 0
x_make_qstorno_2100_3300 425770 425770 0 0.00 0 66036 251 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 30-07-2022 04:39:50 to 30-07-2022 04:39:54.

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:24 3 8192000000 8799631040 8811175936 5414896 0.066 2 1 2 0 5516496 65824 35776
02:08:24 24405 8192000000 9715376736 10013720576 792903376 8.824 100 86 172 1 821254288 7723792 20627120
02:09:57 15920 8192000000 9756828688 10066591744 835869728 9.258 100 84 166 0 867426576 11929824 19627024
02:11:22 12546 8192000000 9793420032 10113073152 865282064 9.553 100 79 158 0 900261408 16676976 18302368
02:12:43 7346 8192000000 9862114512 10191851520 910912464 10.006 100 79 153 0 964094112 36023488 17158160
02:13:52 4586 8192000000 9908681664 10249973760 946214384 10.354 100 81 157 0 1021413424 57810336 17388704
02:15:00 2951 8192000000 9930570992 10278707200 976996032 10.655 100 86 166 1 1052609248 58317664 17295552
02:16:07 1509 8192000000 9968479008 10329346048 1012156224 10.996 100 85 150 1 1094219296 66784512 15278560
02:17:10 1173 8192000000 10012084800 10379341824 1036212912 11.228 100 93 184 0 1132589472 78486640 17889920
02:18:14 1077 8192000000 10009480080 10380292096 1047455104 11.336 100 94 181 0 1132520096 66551680 18513312
02:19:16 1002 8192000000 10025620512 10401218560 1059427040 11.451 100 92 169 1 1143615696 66962448 17226208
02:20:18 1026 8192000000 10025980368 10401935360 1067377360 11.527 100 96 187 1 1144694656 58885568 18431728
02:21:22 951 8192000000 10055637376 10441265152 1070633248 11.558 100 88 171 1 1178232144 90331872 17267024
02:22:23 985 8192000000 10045679632 10426843136 1076856128 11.618 100 94 175 0 1173032064 78004768 18171168
02:23:25 2572 8192000000 10032525568 10426458112 1078848352 11.636 100 76 150 2 1161768928 67105328 15815248
02:24:35 1213 8192000000 10068650400 10456576000 1088230304 11.726 100 95 189 2 1188697776 81945840 18521632
02:25:37 1122 8192000000 10080534656 10471047168 1092472096 11.766 100 98 188 2 1205744048 94740144 18531808
02:26:39 1136 8192000000 10069649488 10464993280 1096894128 11.808 100 95 187 0 1195481760 80071408 18516224
02:27:42 960 8192000000 10049734016 10439811072 1098418880 11.823 100 93 179 3 1171147600 55074320 17654400
02:28:43 2083 8192000000 10045294352 10438197248 1094947872 11.790 100 80 156 0 1168580464 57685760 15946832
02:29:46 2476 8192000000 10041772960 10432589824 1075154672 11.601 99 67 134 0 1155627632 67229456 13243504
02:30:50 1565 8192000000 10005234656 10390073344 1069194400 11.544 100 81 147 1 1132344688 47939952 15210336
02:31:52 1191 8192000000 9998503440 10375852032 1045216624 11.315 100 93 166 5 1121815760 59534720 17064416
02:32:54 1089 8192000000 10014272848 10392788992 1051384528 11.374 100 98 194 1 1139388144 66636128 21367488
02:33:56 3583 8192000000 9988036368 10367320064 1044524624 11.308 99 76 142 3 1113644400 54811280 14308496
02:35:00 1374 8192000000 9993983440 10367156224 1022863872 11.100 100 88 159 0 1116701248 76962400 16874976
02:36:02 5017 8192000000 9976696768 10358026240 1042163744 11.285 100 67 132 0 1092602928 37029040 13410144
02:37:08 1145 8192000000 10020223440 10390286336 1033399568 11.201 100 93 185 2 1141664320 90323696 17941056
02:38:16 4379 8192000000 10000498992 10378760192 1046710064 11.329 100 77 149 0 1119066144 56683920 15672160
02:39:53 801 8192000000 10028356128 10410024960 1058955776 11.446 100 72 135 1 1147418320 75575792 12886752
02:40:59 6122 8192000000 10055366048 10433466368 1081503376 11.662 100 84 151 4 1172454848 75888976 15062496
02:42:09 1179 8192000000 10097700400 10477658112 1094360448 11.784 100 96 185 0 1220996640 106981392 19654800
02:43:13 2012 8192000000 10147122672 10531975168 1104604752 11.881 100 87 167 0 1251327744 129707984 17015008
02:44:20 12201 8192000000 10149523232 10549628928 1119331216 12.021 100 65 130 0 1277979424 144862176 13786032
02:45:36 2684 8192000000 10169879344 10571603968 1123798640 12.063 100 69 138 0 1284813696 145907504 15107552
02:46:44 3897 8192000000 10216563728 10630033408 1133971696 12.159 100 78 148 1 1335878688 186058784 15848208
02:47:51 4250 8192000000 10210759408 10614636544 1138694096 12.203 100 63 118 1 1325184560 173563904 12926560
02:48:59 1075 8192000000 10242591264 10640728064 1160590976 12.409 100 74 137 1 1363803776 187893616 15319184
02:50:04 1088 8192000000 10308243600 10707886080 1170450128 12.501 100 92 176 2 1418113808 228017392 19646288
02:51:10 2869 8192000000 10335380864 10738532352 1172842048 12.523 100 85 159 0 1448404336 258422096 17140192
02:52:17 1525 8192000000 10343930480 10758537216 1181060288 12.600 100 94 178 2 1451970304 249893520 21016496
02:53:22 1030 8192000000 10365092880 10778259456 1185630624 12.643 100 93 183 0 1476589056 270518848 20439584
02:54:27 1614 8192000000 10385914464 10798014464 1180924224 12.599 100 83 158 1 1495204224 296144176 18135824
02:55:38 795 8192000000 10389211840 10813845504 1199536224 12.772 100 95 185 1 1493707936 272101888 22069824
02:56:42 1383 8192000000 10388728112 10807001088 1189286800 12.677 99 96 187 1 1489807328 278524928 21995600
02:57:47 728 8192000000 10419294768 10820747264 1194520432 12.725 100 97 186 1 1522091664 306465968 21105264
02:58:53 1219 8192000000 10444545088 10855292928 1192150320 12.703 100 95 184 1 1548206768 333758368 22298080
03:00:00 901 8192000000 10475831392 10892603392 1187622448 12.661 100 96 188 1 1578916672 368058336 23235888
03:01:04 1707 8192000000 10535363744 10952339456 1202906672 12.803 100 94 186 1 1640192976 415726320 21559984
03:02:10 1797 8192000000 10510309696 10962649088 1187888112 12.664 100 80 156 0 1611753120 404367120 19497888
03:03:16 923 8192000000 10509819648 10957000704 1180313632 12.593 100 98 195 1 1617323056 412870384 24139040
03:04:22 2200 8192000000 10539631056 10967101440 1191702592 12.699 100 73 146 0 1645601792 435143376 18755824
03:05:28 5089 8192000000 10521876192 10968969216 1169660416 12.494 100 60 118 2 1625127616 439714816 15752384
03:06:41 7298 8192000000 10452042128 10968682496 1169250416 12.490 100 56 112 0 1558642928 375049440 14343072
03:07:56 4415 8192000000 10463583072 10893258752 1175251072 12.546 100 48 96 1 1569246960 381552960 12442928
03:09:18 15323 8192000000 10424520224 10904772608 1171202288 12.508 100 40 77 0 1527270480 345703200 10364992
03:10:42 21961 8192000000 10368675104 10823081984 1151688720 12.325 100 29 58 0 1481669328 322366240 7614368
03:12:24 47161 8192000000 10199120016 10681946112 1143168848 12.245 99 17 28 0 1388653440 241516464 3968128
03:14:18 6143 8192000000 10197725696 10626215936 1132741536 12.147 99 44 86 0 1276640224 134571280 9327408
03:15:28 6878 8192000000 10172293024 10618634240 1144956608 12.262 100 52 104 0 1269704656 113449248 11298800
03:16:37 18881 8192000000 10197660672 10635431936 1151989328 12.328 100 34 67 1 1305150352 144881248 8279776
03:17:57 22023 8192000000 10230690464 10686656512 1129805888 12.120 99 27 52 0 1310830352 174389952 6634512
03:19:20 9484 8192000000 10240763392 10658816000 1143012128 12.244 100 66 120 1 1339440560 183683264 12745168
03:20:31 28789 8192000000 10227559232 10679918592 1123761872 12.063 99 13 26 0 1311561088 184408928 3390288
03:22:23 74190 8192000000 10207255776 10673344512 1124100656 12.066 100 30 56 0 1310392912 179436144 6856112
03:24:39 35757 8192000000 10096766928 10599088128 1127777504 12.100 99 26 51 0 1222762896 89515216 5470176
03:26:18 76215 8192000000 10112987744 10562596864 1145629120 12.268 100 20 38 0 1223673664 74182048 3862496
03:28:49 116300 8192000000 10128970496 10600484864 1141595744 12.231 99 18 38 0 1244683440 99352016 3735680
03:32:00 47265 8192000000 10100898864 10580729856 1154628640 12.353 100 18 36 0 1212706064 54242944 3834480
03:33:48 110011 8192000000 10108139408 10584981504 1148948992 12.300 99 12 24 0 1211384016 60052352 2382672
03:36:48 18891 8192000000 10128316800 10573500416 1138159120 12.198 98 40 74 1 1213190560 67122112 7909328
03:38:55 34256 8192000000 10092081328 10563764224 1142990816 12.244 100 23 37 0 1195335408 47608160 4736432
03:40:30 109373 8192000000 10100057200 10576048128 1136908176 12.186 99 24 48 0 1226813632 84875264 5030192
03:43:22 29831 8192000000 10050042624 10471448576 1084504608 11.690 95 62 124 0 1115010128 17194352 13311168
03:44:54 31828 8192000000 10104270656 10554241024 1119814608 12.025 99 48 92 0 1199097632 69819008 9464016
03:46:39 88864 8192000000 10089392496 10536595456 1109292864 11.926 98 19 34 0 1168613520 55451712 3868944
03:49:09 39848 8192000000 10128876512 10572316672 1113356768 11.964 99 36 72 1 1222451952 101706000 7389184
03:50:49 60123 8192000000 10108479408 10583048192 1142141920 12.236 100 12 24 0 1209834592 64989568 2703104
03:53:30 69097 8192000000 10038073264 10488053760 1086687056 11.711 96 4 8 0 1127018768 39571632 760080
03:55:39 12535 8192000000 10100019200 10539020288 1131285872 12.133 100 63 123 0 1188962912 45351360 12325680
03:56:52 88568 8192000000 10097490416 10565902336 1137268240 12.190 99 17 34 1 1231250064 90272688 3709136
03:59:21 51128 8192000000 10072601872 10548895744 1113522800 11.966 98 12 22 0 1156182048 40523648 2135600
04:01:13 16755 8192000000 10149992816 10588753920 1137453024 12.192 99 60 115 1 1221583520 72018256 12112240
04:02:30 49686 8192000000 10092687424 10603327488 1072707280 11.578 95 36 68 2 1154918992 74698224 7513488
04:04:23 97613 8192000000 10080975632 10544406528 1072025408 11.571 95 28 54 0 1131358880 53900080 5433392
04:07:47 37932 8192000000 10135759136 10596294656 1121122384 12.038 100 47 95 0 1214534032 83971280 9440368
04:11:09 27095 8192000000 10051836960 10487959552 1109904240 11.932 98 42 76 2 1148874768 30326832 8643696
04:12:38 14359 8192000000 10087951888 10516746240 1122800976 12.053 99 58 115 0 1176332512 41867072 11664464
04:13:56 40592 8192000000 10074354416 10533879808 1125887712 12.083 99 11 22 0 1183321280 54659984 2773584
04:15:38 54704 8192000000 10101982080 10576547840 1137272816 12.190 100 19 38 0 1202983232 62005936 3704480
04:17:43 101662 8192000000 10084201488 10585554944 1133295520 12.152 100 9 18 0 1173344064 38031984 2016560
04:20:29 42736 8192000000 10052817376 10498785280 1120070096 12.028 99 18 31 0 1148258464 25108400 3079968
04:22:13 46259 8192000000 10125598256 10575851520 1132301904 12.143 100 34 66 0 1214063216 74177600 7583712
04:24:15 22769 8192000000 10204590832 10649174016 1142033248 12.235 99 39 78 0 1285736384 134891840 8811296
04:25:39 37063 8192000000 10213158176 10671419392 1149950256 12.309 100 28 56 1 1309027152 152570208 6506688
04:27:17 66101 8192000000 10213842608 10678804480 1131334992 12.134 98 26 52 0 1283882656 146488640 6059024
04:29:25 74773 8192000000 10121033568 10613547008 1126707504 12.090 99 8 14 0 1212988320 84569696 1711120
04:31:43 55057 8192000000 10090790096 10537472000 1117200592 12.001 98 26 52 0 1153906512 31624688 5081232
04:33:41 25560 8192000000 10108589472 10550259712 1112679952 11.958 98 64 128 0 1179323904 53877552 12766400
04:35:09 83670 8192000000 10064793952 10522152960 1082425536 11.671 96 37 72 0 1159478112 69345424 7707152

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 30-07-2022 04:39:56 to 30-07-2022 04:40:12.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 132 v_add_invoice_to_stock 335544517
except 10 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 30-07-2022 04:40:13 to 30-07-2022 04:40:15.

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          210677
MON$OLDEST_ACTIVE               212983
MON$OLDEST_SNAPSHOT             212983
MON$NEXT_TRANSACTION            212983
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                       2119872
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             763
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2811 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2811 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2811 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2811 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 ---
:265: 
:266: mscs-test-161.budzdorov.ru    Sat Jul 30 04:37:36 2022
:267:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:268: 
:269: 
:270: mscs-test-161.budzdorov.ru    Sat Jul 30 04:37:44 2022
:271:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:272: 
:273: 
:274: mscs-test-161.budzdorov.ru    Sat Jul 30 04:37:44 2022
:275:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:276: 
:277: 
:278: mscs-test-161.budzdorov.ru    Sat Jul 30 04:37:44 2022
:279:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:280: 
--- end of diff output ---

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

30.07.22 04:40:16. 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
2022-07-30 04:40:18 No severe PSQL-related problems occured
30.07.22 04:40:20. End of report.