Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.06.2022 10: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 16230420 kB
MemAvailable 31221472 kB
Buffers 0 kB
Cached 15459440 kB
SwapCached 2468 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-06-20 10:37:18
DTS_END                         2022-06-20 10:37:18.3750
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/42376
TRN_ID                          202525
ATT_ID                          585
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: 8382.36 23831 1005883
customer order: creation 150.82 556 18099
customer order: refuse 13.03 498 1563
order to supplier: creation 40.73 1523 4887
order to supplier: removal 3.43 183524 412
invoice (draft): creation 40.17 3617 4821
invoice (draft): removal 49.43 1096 5932
invoice accept: apply 35.88 28777 4306
invoice accept: cancel 44.73 1289 5368
customer reserve: creation 3348.88 271 401866
customer reserve: removal 4392.93 112 527152
realization accept: apply 74.81 331 8977
realization accept: cancel 73.50 201 8820
payment from customer: creation 52.71 244 6325
payment from customer: removal 5.33 139 639
payment to supplier: creation 42.12 242 5054
payment to supplier: removal 2.87 160 344
service: total inventory turnovers 3.50 4746 420
service: total monetary turnovers 6.60 480 792
service: aggregate perf. data 0.84 17930 101
service: refresh index statistics 0.04 230887 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-06-2022 10:38:40 to 20-06-2022 10:38:42.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 12852
TEST_TIME 2 8815
TEST_TIME 3 7281
TEST_TIME 4 6902
TEST_TIME 5 7071
TEST_TIME 6 6542
TEST_TIME 7 6484
TEST_TIME 8 6418
TEST_TIME 9 6367
TEST_TIME 10 6408
TEST_TIME 11 6609
TEST_TIME 12 6480
TEST_TIME 13 6449
TEST_TIME 14 6596
TEST_TIME 15 6502
TEST_TIME 16 6484
TEST_TIME 17 6544
TEST_TIME 18 6545
TEST_TIME 19 6597
TEST_TIME 20 6722
TEST_TIME 21 6765
TEST_TIME 22 6842
TEST_TIME 23 7148
TEST_TIME 24 7141
TEST_TIME 25 7197
TEST_TIME 26 7216
TEST_TIME 27 7460
TEST_TIME 28 8210
TEST_TIME 29 8496
TEST_TIME 30 8761
TEST_TIME 31 8767
TEST_TIME 32 9096
TEST_TIME 33 9437
TEST_TIME 34 9444
TEST_TIME 35 10086
TEST_TIME 36 10193
TEST_TIME 37 10041
TEST_TIME 38 9867
TEST_TIME 39 9818
TEST_TIME 40 9667
TEST_TIME 41 9626
TEST_TIME 42 9714
TEST_TIME 43 9563
TEST_TIME 44 9450
TEST_TIME 45 9303
TEST_TIME 46 9368
TEST_TIME 47 9413
TEST_TIME 48 9387
TEST_TIME 49 9296
TEST_TIME 50 9216
TEST_TIME 51 9190
TEST_TIME 52 9122
TEST_TIME 53 9060
TEST_TIME 54 8937
TEST_TIME 55 8834
TEST_TIME 56 8733
TEST_TIME 57 8774
TEST_TIME 58 8790
TEST_TIME 59 8670
TEST_TIME 60 8551
TEST_TIME 61 8611
TEST_TIME 62 8598
TEST_TIME 63 8600
TEST_TIME 64 8645
TEST_TIME 65 8560
TEST_TIME 66 8549
TEST_TIME 67 8543
TEST_TIME 68 8533
TEST_TIME 69 8571
TEST_TIME 70 8634
TEST_TIME 71 8612
TEST_TIME 72 8699
TEST_TIME 73 8762
TEST_TIME 74 8783
TEST_TIME 75 8789
TEST_TIME 76 8771
TEST_TIME 77 8777
TEST_TIME 78 8720
TEST_TIME 79 8697
TEST_TIME 80 8650
TEST_TIME 81 8587
TEST_TIME 82 8571
TEST_TIME 83 8566
TEST_TIME 84 8513
TEST_TIME 85 8581
TEST_TIME 86 8499
TEST_TIME 87 8546
TEST_TIME 88 8526
TEST_TIME 89 8521
TEST_TIME 90 8561
TEST_TIME 91 8538
TEST_TIME 92 8585
TEST_TIME 93 8613
TEST_TIME 94 8696
TEST_TIME 95 8713
TEST_TIME 96 8733
TEST_TIME 97 8685
TEST_TIME 98 8668
TEST_TIME 99 8698
TEST_TIME 100 8803
TEST_TIME 101 8812
TEST_TIME 102 8742
TEST_TIME 103 8667
TEST_TIME 104 8606
TEST_TIME 105 8650
TEST_TIME 106 8591
TEST_TIME 107 8566
TEST_TIME 108 8565
TEST_TIME 109 8544
TEST_TIME 110 8499
TEST_TIME 111 8458
TEST_TIME 112 8429
TEST_TIME 113 8417
TEST_TIME 114 8456
TEST_TIME 115 8457
TEST_TIME 116 8470
TEST_TIME 117 8456
TEST_TIME 118 8435
TEST_TIME 119 8428
TEST_TIME 120 8408

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-06-2022 10:38:43 to 20-06-2022 10:38:47.

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 1378069 1378069 0 0.00 0 34340 14 0 0
doc_list_biud 1378052 1378052 0 0.00 0 33140 49 0 0
sp_add_invoice_to_stock 4421 4306 115 2.60 39 195801 28777 0 115
sp_cancel_adding_invoice 5368 5368 0 0.00 5 79517 1290 0 0
sp_cancel_client_order 1563 1563 0 0.00 6 23845 499 0 0
sp_cancel_customer_reserve 527152 527152 0 0.00 1 33145 112 0 0
sp_cancel_pay_from_customer 639 639 0 0.00 4 3035 139 0 0
sp_cancel_pay_to_supplier 344 344 0 0.00 3 8178 161 0 0
sp_cancel_supplier_invoice 5944 5932 12 0.20 3 22242 1096 0 12
sp_cancel_supplier_order 412 412 0 0.00 12 1426215 183525 0 0
sp_cancel_write_off 8820 8820 0 0.00 2 17922 202 0 0
sp_client_order 18099 18099 0 0.00 6 36326 556 0 0
sp_customer_reserve 401866 401866 0 0.00 1 51953 271 0 0
sp_fill_shopping_cart 27796 27796 0 0.00 1 26380 96 0 0
sp_fill_shopping_cart_clo_res 401823 401823 0 0.00 0 22722 23 0 0
sp_get_clo_for_invoice 4260 4260 0 0.00 0 29733 380 0 0
sp_kill_cost_storno 15171 15171 0 0.00 0 31867 90 0 0
sp_kill_qstorno_ret_qs2qd 1069468 1069468 0 0.00 0 33128 50 0 0
sp_kill_qty_storno 562482 562482 0 0.00 0 33137 113 0 0
sp_lock_selected_doc 18383 18383 0 0.00 0 8384 9 0 0
sp_make_cost_storno 24615 24615 0 0.00 0 34323 114 0 0
sp_multiply_rows_for_pdistr 24615 24615 0 0.00 0 9623 45 0 0
sp_multiply_rows_for_qdistr 18099 18099 0 0.00 0 18926 220 0 0
sp_pay_from_customer 6325 6325 0 0.00 5 34535 245 0 0
sp_pay_to_supplier 5054 5054 0 0.00 6 17824 243 0 0
sp_payment_common 11379 11379 0 0.00 2 34515 61 0 0
sp_qd_handle_on_cancel_clo 1563 1563 0 0.00 1 23839 427 0 0
sp_qd_handle_on_invoice_upd_sts 9626 9626 0 0.00 0 25024 572 0 0
sp_qd_handle_on_reserve_upd_sts 8977 8977 0 0.00 0 11951 75 0 0
sp_reserve_write_off 8977 8977 0 0.00 8 21586 331 0 0
sp_supplier_invoice 4821 4821 0 0.00 9 97133 3618 0 0
sp_supplier_order 4887 4887 0 0.00 8 77170 1524 0 0
srv_aggregate_perf_data 101 101 0 0.00 2394 191520 17930 0 0
srv_fill_mon_cache_memory 71 71 0 0.00 929 123956 33628 0 0
srv_find_qd_qs_mism 964110 964110 0 0.00 0 11588 10 0 0
srv_make_invnt_saldo 420 420 0 0.00 84 119994 4746 0 0
srv_make_money_saldo 792 792 0 0.00 1 43168 480 0 0
srv_recalc_idx_stat 5 5 0 0.00 59503 408638 230888 0 0
t$perf-abend:sp_add_invoice_to_stock 115 115 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4311 4311 0 0.00 1 12273 1019 0 0
t$perf-norm:sp_cancel_adding_invoice 351 351 0 0.00 1 5281 447 0 0
t$perf-norm:sp_cancel_client_order 1563 1563 0 0.00 0 885 27 0 0
t$perf-norm:sp_cancel_customer_reserve 610 610 0 0.00 0 2301 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 639 639 0 0.00 0 326 12 0 0
t$perf-norm:sp_cancel_pay_to_supplier 344 344 0 0.00 0 1175 19 0 0
t$perf-norm:sp_cancel_supplier_invoice 350 350 0 0.00 0 1160 41 0 0
t$perf-norm:sp_cancel_supplier_order 412 412 0 0.00 1 109605 8005 0 0
t$perf-norm:sp_cancel_write_off 881 881 0 0.00 0 2178 26 0 0
t$perf-norm:sp_client_order 18099 18099 0 0.00 0 5776 43 0 0
t$perf-norm:sp_customer_reserve 880 880 0 0.00 0 777 18 0 0
t$perf-norm:sp_pay_from_customer 6325 6325 0 0.00 0 6748 21 0 0
t$perf-norm:sp_pay_to_supplier 5054 5054 0 0.00 0 12788 23 0 0
t$perf-norm:sp_reserve_write_off 8977 8977 0 0.00 0 12784 27 0 0
t$perf-norm:sp_supplier_invoice 4821 4821 0 0.00 0 16843 44 0 0
t$perf-norm:sp_supplier_order 4887 4887 0 0.00 0 6425 37 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 335 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 71 71 0 0.00 0 213 8 0 0
t$perf-norm:srv_make_invnt_saldo 420 420 0 0.00 0 474 16 0 0
t$perf-norm:srv_make_money_saldo 792 792 0 0.00 0 667 7 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 3 186 85 0 0
v_add_invoice_to_stock 8742 8512 230 2.63 0 7208 23 0 230
v_all_customers 33130 33130 0 0.00 0 184 4 0 0
v_all_suppliers 26968 26968 0 0.00 0 185 4 0 0
v_all_wares 36194 36194 0 0.00 0 67 4 0 0
v_cancel_adding_invoice 702 702 0 0.00 0 6004 22 0 0
v_cancel_client_order 3126 3126 0 0.00 0 2467 10 0 0
v_cancel_customer_prepayment 1278 1278 0 0.00 0 1905 13 0 0
v_cancel_customer_reserve 1220 1220 0 0.00 0 1881 11 0 0
v_cancel_payment_to_supplier 688 688 0 0.00 0 1725 15 0 0
v_cancel_supplier_invoice 724 700 24 3.31 0 2977 36 0 24
v_cancel_supplier_order 824 824 0 0.00 0 680 10 0 0
v_cancel_write_off 1762 1762 0 0.00 0 7609 31 0 0
v_max_id_clo_ord 4886 4886 0 0.00 0 7525 9 0 0
v_max_id_clo_res 880 880 0 0.00 0 1948 17 0 0
v_max_id_ord_sup 4811 4811 0 0.00 0 7547 12 0 0
v_max_non_paid_invoice 1267 1267 0 0.00 0 83 7 0 0
v_max_non_paid_realizn 3363 3363 0 0.00 0 7374 9 0 0
v_min_id_clo_ord 4886 4886 0 0.00 0 9485 17 0 0
v_min_id_clo_res 880 880 0 0.00 0 6689 20 0 0
v_min_id_ord_sup 4811 4811 0 0.00 0 6345 17 0 0
v_min_non_paid_invoice 5054 5054 0 0.00 0 9838 23 0 0
v_min_non_paid_realizn 6325 6325 0 0.00 0 34507 39 0 0
v_our_firm 8988 8988 0 0.00 0 60 4 0 0
v_reserve_write_off 17954 17954 0 0.00 0 4778 12 0 0
x_lock_depdocs_on_canc_invoice 10950 10950 0 0.00 0 8605 135 0 0
x_lock_depdocs_on_canc_sup_ord 412 412 0 0.00 0 13958 423 0 0
x_make_qstorno_1000_1200 4887 4887 0 0.00 3 68563 1338 0 0
x_make_qstorno_1200_2000 4821 4821 0 0.00 4 91790 3323 0 0
x_make_qstorno_2100_3300 401865 401865 0 0.00 0 43865 235 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-06-2022 10:38:48 to 20-06-2022 10:38:52.

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
08:07:22 1 8192000000 8796669024 8807108608 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:23 22889 8192000000 9726862528 10030735360 808491440 8.982 100 83 166 1 837192304 8497792 20203072
08:09:53 21806 8192000000 9780504288 10092130304 852494000 9.425 100 86 172 0 885673136 13141088 20038048
08:11:22 15216 8192000000 9811867248 10131795968 874870256 9.649 100 88 176 1 917506896 21903296 20733344
08:12:42 12508 8192000000 9846283376 10175209472 899856192 9.897 100 85 170 1 953742096 34367984 19517920
08:13:57 5286 8192000000 9895883376 10233311232 931722480 10.212 100 90 177 2 1009937840 59446720 18768640
08:15:06 2958 8192000000 9960858928 10310590464 981388864 10.698 100 88 173 0 1077532624 78050384 18093376
08:16:12 2397 8192000000 9984592144 10344054784 1008759680 10.963 100 87 172 1 1098517072 71924912 17832480
08:17:17 6455 8192000000 10015899808 10387787776 1032390176 11.191 100 78 156 0 1140923088 93175424 15357488
08:18:27 1338 8192000000 10036162752 10412781568 1054110496 11.400 100 96 184 1 1166355456 94213856 18031104
08:19:30 945 8192000000 10030387120 10406932480 1061521088 11.471 100 94 183 0 1152613440 71780448 19311904
08:20:33 801 8192000000 10059871664 10438504448 1071497440 11.566 100 97 185 1 1179287616 89439984 18350192
08:21:37 1403 8192000000 10065809760 10445926400 1071140832 11.563 100 92 174 0 1180734688 92833776 16760080
08:22:39 1099 8192000000 10044919376 10427125760 1076363456 11.613 100 93 183 2 1163969440 68490160 19115824
08:23:41 3029 8192000000 10034826192 10424090624 1081564224 11.662 100 71 133 0 1153770832 58358352 13848256
08:24:45 1271 8192000000 10057436448 10443628544 1087667792 11.720 100 100 189 2 1176246816 69431456 19147568
08:25:47 1110 8192000000 10060859248 10449506304 1094354016 11.784 100 98 193 2 1173623088 60692944 18576128
08:26:51 1178 8192000000 10058218816 10447200256 1094436576 11.785 100 98 188 0 1190948192 76951184 19560432
08:27:53 1343 8192000000 10043539904 10432606208 1093853344 11.779 100 98 192 1 1166101632 52787456 19460832
08:28:55 3282 8192000000 10059639680 10459570176 1093819104 11.779 100 71 136 2 1172103248 64265360 14018784
08:30:00 1161 8192000000 10052508240 10435112960 1087701552 11.721 100 96 175 1 1166512656 60923216 17887888
08:31:03 1228 8192000000 10029218672 10410205184 1067997440 11.533 100 96 188 0 1150629984 62793024 19839520
08:32:05 1142 8192000000 10031305840 10413961216 1062903296 11.484 100 97 186 0 1152409968 69709408 19797264
08:33:07 1336 8192000000 10018101968 10397057024 1055015600 11.409 100 95 185 1 1147120400 72325616 19779184
08:34:10 1114 8192000000 10012301776 10391793664 1056097056 11.419 100 96 187 1 1136824800 61906720 18821024
08:35:12 1242 8192000000 9991117216 10371338240 1047904288 11.341 100 71 136 1 1109972784 46945296 15123200
08:36:14 7149 8192000000 9949016288 10319515648 1007571568 10.952 97 60 111 0 1079683056 61559984 10551504
08:37:24 12000 8192000000 9984968992 10362916864 1045557856 11.318 100 51 94 1 1119805456 65325920 8921680
08:38:42 1132 8192000000 10017217504 10395783168 1064229952 11.497 99 83 157 1 1134631472 54014640 16386880
08:39:55 25320 8192000000 10019064832 10417197056 1073779184 11.588 100 55 110 0 1142329072 57035520 11514368
08:41:30 3872 8192000000 10065894672 10453086208 1093186928 11.773 100 75 146 2 1187804208 78914496 15702784
08:42:37 1495 8192000000 10098228512 10489581568 1104882352 11.884 100 91 168 0 1224908400 101793328 18232720
08:43:42 5079 8192000000 10117477056 10508115968 1097744368 11.816 99 71 130 0 1227717824 116310736 13662720
08:44:54 5863 8192000000 10154523136 10551586816 1115756688 11.987 100 65 128 0 1264983424 135861072 13365664
08:46:06 2567 8192000000 10195470352 10591879168 1127568592 12.098 100 93 182 1 1314358080 164978352 21811136
08:47:13 3383 8192000000 10205971168 10608766976 1136519824 12.183 100 84 165 0 1320815312 164730944 19564544
08:48:26 14248 8192000000 10205079248 10627149824 1150530864 12.314 100 62 117 1 1320491680 156852720 13108096
08:49:49 4613 8192000000 10256592304 10663006208 1166092272 12.460 100 71 137 1 1367683024 186159920 15430832
08:51:03 1843 8192000000 10310114256 10722316288 1169277344 12.490 100 96 182 1 1420539456 230424192 20837920
08:52:10 1151 8192000000 10316896944 10721062912 1181154544 12.601 100 82 157 0 1426739808 227578368 18006896
08:53:18 1382 8192000000 10349950512 10758647808 1186328240 12.649 100 84 160 2 1462769584 258657696 17783648
08:54:28 1483 8192000000 10403122496 10813894656 1189203568 12.676 100 95 181 0 1513789024 302491584 22093872
08:55:35 1187 8192000000 10443642240 10853683200 1191492560 12.697 100 96 188 0 1552555696 337526496 23536640
08:56:42 920 8192000000 10465628640 10884440064 1193254432 12.714 100 96 183 1 1573094544 357349584 22490528
08:57:48 1375 8192000000 10471577584 10884120576 1186740192 12.653 100 98 189 1 1579717632 369936608 23040832
08:58:53 1262 8192000000 10488282560 10937106432 1195866176 12.738 100 96 188 1 1595623792 376461296 23296320
09:00:01 940 8192000000 10541734704 10990632960 1207460112 12.846 100 97 186 1 1643207888 413521056 22226720
09:01:06 2329 8192000000 10545963152 10970017792 1204204128 12.815 100 89 174 0 1647407184 422116752 21086304
09:02:12 1274 8192000000 10589755248 11016171520 1209671040 12.866 100 93 179 0 1691615616 460203488 21741088
09:03:16 1178 8192000000 10608303008 11057856512 1207520000 12.846 100 88 172 3 1715039584 485678064 21841520
09:04:22 990 8192000000 10572877792 11103469568 1198009232 12.758 100 92 184 2 1675396432 453668784 23718416
09:05:25 3145 8192000000 10534404768 10964312064 1194277584 12.723 100 64 126 0 1634503440 424291072 15934784
09:06:30 10374 8192000000 10507558704 10977779712 1182370624 12.612 100 47 92 0 1630285088 435201824 12712640
09:08:02 29485 8192000000 10439935248 10909749248 1178309168 12.574 100 34 68 0 1549748528 363253840 8185520
09:09:34 10670 8192000000 10371230768 10882752512 1169495136 12.492 100 42 84 0 1496837040 317161632 10180272
09:11:02 28162 8192000000 10277044896 10745769984 1140657168 12.222 100 14 28 0 1379338080 234968592 3712320
09:12:32 20867 8192000000 10156162080 10627547136 1149504480 12.305 99 24 48 0 1263793424 108994192 5294752
09:13:57 79307 8192000000 10132351904 10578595840 1128445488 12.107 97 41 82 5 1207460512 69760704 9254320
09:16:39 15106 8192000000 10145941056 10609618944 1150879344 12.318 100 52 102 3 1242371456 80456944 11035168
09:18:00 47553 8192000000 10181363536 10661453824 1138071248 12.197 98 25 48 2 1254837312 110877200 5888864
09:19:51 73269 8192000000 10145538768 10603896832 1141490528 12.230 99 19 38 0 1233540976 87686464 4363984
09:22:04 22107 8192000000 10165464752 10606592000 1136039808 12.178 99 44 84 3 1260423136 114876768 9506560
09:23:28 84835 8192000000 10118946848 10603896832 1119853040 12.026 98 12 24 2 1233474624 110999424 2622160
09:26:19 87254 8192000000 10072375216 10521698304 1120356688 12.030 98 36 69 3 1152973392 25469488 7147216
09:28:47 38293 8192000000 10042018688 10480594944 1104484592 11.880 97 39 77 1 1123570112 11516176 7569344
09:30:30 44705 8192000000 10044660448 10490691584 1128436432 12.107 99 18 36 0 1155433824 23494016 3503376
09:32:22 12123 8192000000 10069588384 10495848448 1114166752 11.972 98 64 128 1 1173697568 46205312 13325504
09:33:41 19458 8192000000 10156390272 10586935296 1138287088 12.199 100 68 133 2 1260173568 107035632 14850848
09:35:03 52695 8192000000 10095417632 10605871104 1126929536 12.092 99 14 28 2 1231671600 101157024 3585040
09:37:05 77101 8192000000 10121144336 10586718208 1127388176 12.097 100 9 18 0 1231229456 101748928 2092352
09:39:28 62208 8192000000 10055446720 10500562944 1095318576 11.793 97 9 18 2 1110043696 12939072 1786048
09:41:30 53458 8192000000 10043852144 10463035392 1071159232 11.563 95 44 88 3 1097759664 17807744 8792688
09:43:30 64640 8192000000 10103063424 10548785152 1105953120 11.894 98 25 49 1 1174212224 62460688 5798416
09:45:36 28634 8192000000 10141521520 10585522176 1143150608 12.245 100 29 58 0 1238794032 89019792 6623632
09:47:12 27291 8192000000 10128215296 10605658112 1141553808 12.230 100 36 72 1 1237310528 87751696 8005024
09:48:40 81137 8192000000 10119013616 10622578688 1113788720 11.968 97 18 36 0 1217438896 99679520 3970656
09:51:03 33901 8192000000 10083079696 10547474432 1122747040 12.053 99 21 42 2 1175254576 48199504 4308032
09:53:00 23082 8192000000 10110129792 10546577408 1123929904 12.064 99 40 80 0 1197326304 64986896 8409504
09:54:25 123956 8192000000 10067807056 10527211520 1093454912 11.775 97 22 44 0 1168966176 71151776 4359488
09:57:30 43326 8192000000 10068346944 10524942336 1097377072 11.813 98 14 28 0 1154969408 54761664 2830672
09:59:15 56470 8192000000 10105607936 10548928512 1107647808 11.910 98 21 42 0 1209512976 97525120 4340048
10:01:42 62788 8192000000 10094216176 10596429824 1109686272 11.929 98 14 28 1 1212549040 99585072 3277696
10:03:45 48674 8192000000 10073297376 10584801280 1129750992 12.119 100 10 20 0 1197193552 64753984 2688576
10:05:42 36267 8192000000 10105633600 10542821376 1127412912 12.097 99 22 44 0 1188629744 56178656 5038176
10:07:19 39249 8192000000 10116797888 10563649536 1131601904 12.136 98 24 48 1 1195943600 59365872 4975824
10:09:24 53498 8192000000 10166253728 10620137472 1148307088 12.294 100 38 73 0 1240219664 84597136 7315440
10:11:17 67812 8192000000 10083452672 10542948352 1133936064 12.158 99 12 24 0 1222710528 85867472 2906992
10:13:26 61564 8192000000 10105167280 10561847296 1134394368 12.163 98 17 34 1 1180900880 43174112 3332400
10:15:31 36260 8192000000 10189767712 10649034752 1166860688 12.467 100 39 78 1 1289955424 115244512 7850224
10:17:21 99057 8192000000 10100679184 10592100352 1107675376 11.910 96 7 14 0 1158393648 49613280 1104992
10:21:13 33290 8192000000 10135736288 10570153984 1132756880 12.147 99 48 96 1 1212372096 69700480 9914736
10:22:54 74138 8192000000 10086679616 10554904576 1146334256 12.275 100 20 40 1 1210327536 59312976 4680304
10:26:21 85888 8192000000 10060446288 10516570112 1078586048 11.634 94 20 40 0 1102558448 19802208 4170192
10:29:14 55063 8192000000 10090993360 10551599104 1110076112 11.933 97 21 42 1 1168375856 53856400 4443344
10:31:09 64634 8192000000 10093646384 10584281088 1099983776 11.837 97 9 18 0 1157054800 55000416 2070608
10:33:17 37037 8192000000 10087495728 10571280384 1124827520 12.073 99 27 52 1 1181602032 50717680 6056832
10:34:58 29192 8192000000 10117654208 10553098240 1104988112 11.885 97 36 70 1 1185636560 73311904 7336544
10:36:38 51385 8192000000 9598283056 10040938496 684905392 7.715 60 29 57 0 793901024 103027600 5968032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 20-06-2022 10:38:53 to 20-06-2022 10:39:09.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-06-2022 10:39:10 to 20-06-2022 10:39:12.

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          198266
MON$OLDEST_ACTIVE               203006
MON$OLDEST_SNAPSHOT             203006
MON$NEXT_TRANSACTION            203006
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                       2038432
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             747
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2780 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2780 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2780 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2780 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 ---
:243: 
:244: mscs-test-161.budzdorov.ru    Mon Jun 20 10:37:37 2022
:245:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:246: 
:247: 
:248: mscs-test-161.budzdorov.ru    Mon Jun 20 10:37:41 2022
:249:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:250: 
--- end of diff output ---

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

20.06.22 10:39:13. 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-06-20 10:39:16 No severe PSQL-related problems occured
20.06.22 10:39:17. End of report.