Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.11.2021 04:37
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-11-11 18:28
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4794.33
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16319244 kB
MemAvailable 31490072 kB
Buffers 20 kB
Cached 15270508 kB
SwapCached 0 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.1
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 2021-11-27 04:37:08
DTS_END                         2021-11-27 04:37:08.1710
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_ADD_INVOICE_TO_STOCK(190:30) ==> SP_CUSTOMER_RESERVE(47:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/43596
TRN_ID                          237698
ATT_ID                          651
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: 9184.90 23196 1102188
customer order: creation 175.10 349 21012
customer order: refuse 14.50 301 1740
order to supplier: creation 46.51 1352 5581
order to supplier: removal 3.32 100458 398
invoice (draft): creation 47.50 3240 5700
invoice (draft): removal 50.54 899 6065
invoice accept: apply 42.64 18442 5117
invoice accept: cancel 45.27 866 5432
customer reserve: creation 4016.09 180 481931
customer reserve: removal 4453.12 58 534374
realization accept: apply 83.99 175 10079
realization accept: cancel 76.49 111 9179
payment from customer: creation 60.62 110 7274
payment from customer: removal 5.97 87 716
payment to supplier: creation 48.26 122 5791
payment to supplier: removal 3.48 56 418
service: total inventory turnovers 4.27 3010 512
service: total monetary turnovers 6.23 476 748
service: aggregate perf. data 0.97 13827 116
service: refresh index statistics 0.04 319806 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 27-11-2021 04:37:59 to 27-11-2021 04:38:01.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8072
TEST_TIME 2 7335
TEST_TIME 3 6597
TEST_TIME 4 5773
TEST_TIME 5 5360
TEST_TIME 6 5454
TEST_TIME 7 5328
TEST_TIME 8 5647
TEST_TIME 9 6057
TEST_TIME 10 6627
TEST_TIME 11 6687
TEST_TIME 12 7092
TEST_TIME 13 7148
TEST_TIME 14 7368
TEST_TIME 15 8484
TEST_TIME 16 8367
TEST_TIME 17 8775
TEST_TIME 18 8904
TEST_TIME 19 8996
TEST_TIME 20 9387
TEST_TIME 21 9554
TEST_TIME 22 9827
TEST_TIME 23 10182
TEST_TIME 24 10555
TEST_TIME 25 10583
TEST_TIME 26 10335
TEST_TIME 27 10688
TEST_TIME 28 10885
TEST_TIME 29 10656
TEST_TIME 30 10491
TEST_TIME 31 10548
TEST_TIME 32 10426
TEST_TIME 33 10432
TEST_TIME 34 10270
TEST_TIME 35 10580
TEST_TIME 36 10567
TEST_TIME 37 10722
TEST_TIME 38 10629
TEST_TIME 39 10527
TEST_TIME 40 10538
TEST_TIME 41 10511
TEST_TIME 42 10395
TEST_TIME 43 10583
TEST_TIME 44 10566
TEST_TIME 45 10350
TEST_TIME 46 10222
TEST_TIME 47 10409
TEST_TIME 48 10209
TEST_TIME 49 10192
TEST_TIME 50 10096
TEST_TIME 51 10064
TEST_TIME 52 10039
TEST_TIME 53 10011
TEST_TIME 54 9918
TEST_TIME 55 9871
TEST_TIME 56 9869
TEST_TIME 57 9832
TEST_TIME 58 9750
TEST_TIME 59 9686
TEST_TIME 60 9614
TEST_TIME 61 9557
TEST_TIME 62 9573
TEST_TIME 63 9562
TEST_TIME 64 9522
TEST_TIME 65 9493
TEST_TIME 66 9494
TEST_TIME 67 9421
TEST_TIME 68 9523
TEST_TIME 69 9457
TEST_TIME 70 9502
TEST_TIME 71 9497
TEST_TIME 72 9496
TEST_TIME 73 9516
TEST_TIME 74 9567
TEST_TIME 75 9574
TEST_TIME 76 9554
TEST_TIME 77 9470
TEST_TIME 78 9454
TEST_TIME 79 9375
TEST_TIME 80 9360
TEST_TIME 81 9340
TEST_TIME 82 9273
TEST_TIME 83 9279
TEST_TIME 84 9203
TEST_TIME 85 9146
TEST_TIME 86 9218
TEST_TIME 87 9170
TEST_TIME 88 9136
TEST_TIME 89 9194
TEST_TIME 90 9223
TEST_TIME 91 9204
TEST_TIME 92 9235
TEST_TIME 93 9276
TEST_TIME 94 9295
TEST_TIME 95 9310
TEST_TIME 96 9304
TEST_TIME 97 9411
TEST_TIME 98 9470
TEST_TIME 99 9488
TEST_TIME 100 9496
TEST_TIME 101 9494
TEST_TIME 102 9417
TEST_TIME 103 9365
TEST_TIME 104 9366
TEST_TIME 105 9386
TEST_TIME 106 9355
TEST_TIME 107 9319
TEST_TIME 108 9259
TEST_TIME 109 9258
TEST_TIME 110 9217
TEST_TIME 111 9208
TEST_TIME 112 9260
TEST_TIME 113 9234
TEST_TIME 114 9234
TEST_TIME 115 9228
TEST_TIME 116 9286
TEST_TIME 117 9282
TEST_TIME 118 9277
TEST_TIME 119 9253
TEST_TIME 120 9192

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 27-11-2021 04:38:03 to 27-11-2021 04:38:06.

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 1547039 1547039 0 0.00 0 24476 7 0 0
doc_list_biud 1547038 1547038 0 0.00 0 29321 26 0 0
sp_add_invoice_to_stock 5256 5117 139 2.64 29 183936 18443 0 139
sp_cancel_adding_invoice 5432 5432 0 0.00 5 46973 867 0 0
sp_cancel_client_order 1740 1740 0 0.00 5 19088 302 0 0
sp_cancel_customer_reserve 534374 534374 0 0.00 1 26402 58 0 0
sp_cancel_pay_from_customer 716 716 0 0.00 3 7656 88 0 0
sp_cancel_pay_to_supplier 418 418 0 0.00 2 1179 56 0 0
sp_cancel_supplier_invoice 6074 6065 9 0.14 2 15308 900 0 9
sp_cancel_supplier_order 398 398 0 0.00 17 882364 100458 0 0
sp_cancel_write_off 9179 9179 0 0.00 1 24479 112 0 0
sp_client_order 21012 21012 0 0.00 4 25394 349 0 0
sp_customer_reserve 481931 481931 0 0.00 0 88667 181 0 0
sp_fill_shopping_cart 32286 32286 0 0.00 1 20641 44 0 0
sp_fill_shopping_cart_clo_res 481920 481920 0 0.00 0 22600 14 0 0
sp_get_clo_for_invoice 5095 5095 0 0.00 0 31654 309 0 0
sp_kill_cost_storno 15745 15745 0 0.00 0 24471 55 0 0
sp_kill_qstorno_ret_qs2qd 1084390 1084390 0 0.00 0 26397 32 0 0
sp_kill_qty_storno 572362 572362 0 0.00 0 29321 70 0 0
sp_lock_selected_doc 20962 20962 0 0.00 0 4915 3 0 0
sp_make_cost_storno 28239 28239 0 0.00 0 13042 57 0 0
sp_multiply_rows_for_pdistr 28239 28239 0 0.00 0 7603 32 0 0
sp_multiply_rows_for_qdistr 21012 21012 0 0.00 0 12748 170 0 0
sp_pay_from_customer 7274 7274 0 0.00 4 13626 110 0 0
sp_pay_to_supplier 5791 5791 0 0.00 4 15604 122 0 0
sp_payment_common 13065 13065 0 0.00 1 10994 27 0 0
sp_qd_handle_on_cancel_clo 1740 1740 0 0.00 0 19081 280 0 0
sp_qd_handle_on_invoice_upd_sts 10527 10527 0 0.00 0 29321 358 0 0
sp_qd_handle_on_reserve_upd_sts 10079 10079 0 0.00 0 23676 56 0 0
sp_reserve_write_off 10079 10079 0 0.00 6 23784 175 0 0
sp_supplier_invoice 5700 5700 0 0.00 7 90354 3240 0 0
sp_supplier_order 5581 5581 0 0.00 5 26254 1353 0 0
srv_aggregate_perf_data 116 116 0 0.00 2896 106339 13828 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1946 157943 45187 0 0
srv_find_qd_qs_mism 1052608 1052608 0 0.00 0 8643 6 0 0
srv_make_invnt_saldo 512 512 0 0.00 40 116794 3010 0 0
srv_make_money_saldo 748 748 0 0.00 1 25648 476 0 0
srv_recalc_idx_stat 5 5 0 0.00 132556 487937 319806 0 0
t$perf-abend:sp_add_invoice_to_stock 139 139 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5117 5117 0 0.00 0 14703 139 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 7048 133 0 0
t$perf-norm:sp_cancel_client_order 1740 1740 0 0.00 0 5761 17 0 0
t$perf-norm:sp_cancel_customer_reserve 669 669 0 0.00 0 8235 32 0 0
t$perf-norm:sp_cancel_pay_from_customer 716 716 0 0.00 0 3621 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 418 418 0 0.00 0 1958 8 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 1927 14 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 25401 1501 0 0
t$perf-norm:sp_cancel_write_off 1035 1035 0 0.00 0 570 3 0 0
t$perf-norm:sp_client_order 21012 21012 0 0.00 0 7980 16 0 0
t$perf-norm:sp_customer_reserve 1033 1033 0 0.00 0 2333 6 0 0
t$perf-norm:sp_pay_from_customer 7274 7274 0 0.00 0 9736 9 0 0
t$perf-norm:sp_pay_to_supplier 5791 5791 0 0.00 0 8654 10 0 0
t$perf-norm:sp_reserve_write_off 10079 10079 0 0.00 0 6571 10 0 0
t$perf-norm:sp_supplier_invoice 5700 5700 0 0.00 0 6853 19 0 0
t$perf-norm:sp_supplier_order 5581 5581 0 0.00 0 5015 12 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 864 19 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 174 7 0 0
t$perf-norm:srv_make_invnt_saldo 512 512 0 0.00 0 3114 14 0 0
t$perf-norm:srv_make_money_saldo 748 748 0 0.00 0 4025 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10468 10190 278 2.65 0 7682 17 0 278
v_all_customers 38296 38296 0 0.00 0 18 1 0 0
v_all_suppliers 31084 31084 0 0.00 0 19 1 0 0
v_all_wares 42024 42024 0 0.00 0 88 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 2119 8 0 0
v_cancel_client_order 3480 3480 0 0.00 0 1253 4 0 0
v_cancel_customer_prepayment 1432 1432 0 0.00 0 7547 10 0 0
v_cancel_customer_reserve 1338 1338 0 0.00 0 8709 10 0 0
v_cancel_payment_to_supplier 836 836 0 0.00 0 585 5 0 0
v_cancel_supplier_invoice 814 796 18 2.21 0 4311 25 0 18
v_cancel_supplier_order 796 796 0 0.00 0 932 5 0 0
v_cancel_write_off 2070 2070 0 0.00 0 1022 12 0 0
v_max_id_clo_ord 5581 5581 0 0.00 0 157 1 0 0
v_max_id_clo_res 1033 1033 0 0.00 0 5447 22 0 0
v_max_id_ord_sup 5693 5693 0 0.00 0 8103 6 0 0
v_max_non_paid_invoice 1523 1523 0 0.00 0 1882 3 0 0
v_max_non_paid_realizn 3877 3877 0 0.00 0 1128 3 0 0
v_min_id_clo_ord 5581 5581 0 0.00 0 5632 8 0 0
v_min_id_clo_res 1033 1033 0 0.00 0 450 2 0 0
v_min_id_ord_sup 5693 5693 0 0.00 0 8027 7 0 0
v_min_non_paid_invoice 5791 5791 0 0.00 0 10934 21 0 0
v_min_non_paid_realizn 7274 7274 0 0.00 0 10982 19 0 0
v_our_firm 10522 10522 0 0.00 0 17 1 0 0
v_reserve_write_off 20158 20158 0 0.00 0 9378 4 0 0
x_lock_depdocs_on_canc_invoice 11099 11099 0 0.00 0 11921 100 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 4865 280 0 0
x_make_qstorno_1000_1200 5581 5581 0 0.00 2 26206 1281 0 0
x_make_qstorno_1200_2000 5700 5700 0 0.00 4 90346 3073 0 0
x_make_qstorno_2100_3300 481931 481931 0 0.00 0 88665 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-11-2021 04:38:07 to 27-11-2021 04:38:11.

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:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:12 23615 8192000000 9728217040 10025906176 801172784 8.908 100 89 178 0 830779232 7329872 22276576
02:09:41 29234 8192000000 9770245808 10077818880 834993952 9.249 100 83 166 1 868264608 13118464 20152192
02:11:17 19367 8192000000 9815358976 10136559616 875576096 9.656 100 83 166 0 919782000 24626720 19579184
02:12:39 9254 8192000000 9863491760 10201178112 921692608 10.113 100 78 154 0 974103088 35124176 17286304
02:13:52 10076 8192000000 9934742304 10281959424 971260384 10.599 100 80 160 0 1047430384 59162368 17007632
02:15:03 3777 8192000000 9961429664 10321227776 1005110320 10.928 100 77 152 2 1077814800 56906096 15798384
02:16:09 4530 8192000000 10002515200 10374651904 1024381104 11.114 100 66 132 0 1119990736 82476368 13133264
02:17:16 2156 8192000000 10035445776 10404765696 1055578288 11.414 100 90 179 0 1151224752 77704432 17942032
02:18:19 1547 8192000000 10050363392 10423783424 1079765424 11.645 100 95 188 2 1158231536 59346048 19120064
02:19:22 3208 8192000000 10041076528 10426986496 1081524048 11.662 100 72 145 0 1153285120 57111280 14649792
02:20:25 5115 8192000000 10023737760 10428383232 1086253728 11.707 100 32 64 2 1129112928 36122560 6736640
02:21:34 61083 8192000000 9997715136 10400067584 1094145920 11.782 100 38 75 1 1115762432 12715472 8901040
02:23:45 3175 8192000000 10038379952 10443653120 1102836912 11.865 100 58 113 3 1152264384 37765344 11662128
02:24:49 4312 8192000000 10026805776 10433495040 1108536160 11.919 100 37 75 0 1141385024 24992288 7856576
02:25:58 52357 8192000000 10015386688 10414702592 1086167632 11.706 99 27 52 0 1112724496 20328688 6228176
02:27:51 7441 8192000000 9995238128 10399412224 1086756944 11.712 100 22 44 0 1106029216 14559776 4712496
02:29:01 5682 8192000000 9979780208 10364354560 1008413408 10.960 95 47 93 1 1039452608 20684032 10355168
02:30:09 8591 8192000000 9928516848 10313740288 998079936 10.860 98 27 53 1 1022178640 18301056 5797648
02:31:20 18891 8192000000 9915735648 10313080832 992830720 10.809 98 15 30 0 1030408816 34741904 2836192
02:32:39 6408 8192000000 9952198160 10340212736 1032259552 11.190 100 40 81 3 1056647216 14049328 10338336
02:33:46 9570 8192000000 9965689088 10360733696 1045421008 11.317 100 53 106 0 1075004720 16559168 13024544
02:34:57 61688 8192000000 9961154112 10355462144 1055969248 11.418 100 27 52 2 1086650992 24262848 6418896
02:37:01 4755 8192000000 9981606304 10376101888 1069046944 11.543 100 39 78 0 1095740016 18330576 8362496
02:38:16 14138 8192000000 10052700592 10446295040 1096889872 11.808 100 60 120 2 1165867184 57100880 11876432
02:39:38 10633 8192000000 10080036624 10483130368 1110283872 11.935 100 42 84 1 1199116704 79488816 9344016
02:40:49 87523 8192000000 10130232064 10535903232 1122813648 12.054 100 34 68 0 1214786496 84805088 7167760
02:43:19 13592 8192000000 10174698288 10586013696 1133041984 12.150 100 43 86 1 1272218000 129666432 9509584
02:44:46 11848 8192000000 10206797568 10609442816 1148757792 12.298 100 58 110 0 1315886992 154969920 12159280
02:46:02 10137 8192000000 10252805920 10656378880 1148205904 12.293 100 58 116 0 1355047312 194015872 12825536
02:47:17 10190 8192000000 10266834912 10687750144 1159806336 12.401 100 63 125 2 1361480576 187626080 14048160
02:48:32 5646 8192000000 10287684128 10697650176 1170172608 12.498 100 64 128 1 1391223360 206960192 14090560
02:49:46 2025 8192000000 10294167072 10695766016 1155219504 12.358 99 84 168 1 1388103392 214728528 18155360
02:50:53 4239 8192000000 10340597088 10756542464 1172753232 12.523 100 86 170 0 1448341888 256244048 19344608
02:52:05 5495 8192000000 10384686080 10801676288 1154305760 12.350 99 58 116 2 1483561840 315029104 14226976
02:53:13 3079 8192000000 10253521680 10695098368 1121016896 12.037 99 41 82 1 1350423888 219446144 9960848
02:54:22 1945 8192000000 10279511776 10678530048 1122962832 12.055 99 81 160 0 1367283152 224926864 19393456
02:55:26 11347 8192000000 10263689616 10692931584 1141837760 12.233 100 30 60 1 1372334880 223097024 7400096
02:56:46 5494 8192000000 10299670672 10728902656 1116053648 11.990 99 54 106 3 1385454656 256242784 13158224
02:57:56 17447 8192000000 10293568224 10734112768 1093606208 11.777 97 32 64 1 1365361520 263304128 8451184
02:59:28 7696 8192000000 10272657616 10692300800 1105375312 11.889 99 34 68 0 1356076720 242122576 8578832
03:00:38 8406 8192000000 10222481504 10656251904 1128615152 12.108 100 30 60 2 1329799952 193477344 7707456
03:01:49 38151 8192000000 10190187824 10661085184 1130510528 12.126 100 23 46 2 1326927808 190540848 5876432
03:03:55 9369 8192000000 10186480480 10606891008 1119201248 12.019 100 49 98 0 1283583824 153498624 10883952
03:05:05 40217 8192000000 10088366160 10631127040 1103454224 11.870 98 16 32 0 1178210928 71742608 3014096
03:06:52 58609 8192000000 10127806832 10593689600 1107780832 11.911 99 13 26 0 1207381744 96714928 2885984
03:08:51 52585 8192000000 10088820560 10510266368 1119152096 12.019 99 33 66 1 1169609920 43032720 7425104
03:10:54 78768 8192000000 10146518864 10596417536 1100127168 11.839 97 15 28 1 1173885360 71186656 2571536
03:13:27 82455 8192000000 10096306032 10601213952 1123773008 12.063 99 9 18 1 1181121664 55443520 1905136
03:15:50 65929 8192000000 10083334928 10501804032 1134465120 12.163 99 33 66 2 1177935408 36368320 7101968
03:17:57 87702 8192000000 10124310752 10563756032 1133708880 12.156 99 21 43 0 1205592432 67460880 4422672
03:20:36 157943 8192000000 10110627584 10556555264 1139482064 12.211 99 9 18 0 1203012160 61759008 1771088
03:24:17 67366 8192000000 10101887808 10615115776 1145762576 12.270 100 28 56 0 1208253232 56208656 6282000
03:27:11 33083 8192000000 10139647520 10567102464 1153310800 12.341 100 50 98 1 1207872032 44098656 10462576
03:28:45 24400 8192000000 10147168576 10619105280 1153256848 12.340 100 29 58 0 1244023440 84458064 6308528
03:30:34 83203 8192000000 10120897024 10605518848 1156717120 12.373 100 14 28 0 1224794704 64989520 3088064
03:32:58 76161 8192000000 10121849264 10597588992 1159829680 12.402 100 16 32 0 1210859616 47266720 3763216
03:35:18 5799 8192000000 10095039872 10576773120 1158699104 12.391 100 28 56 1 1195826976 30888944 6238928
03:36:24 95530 8192000000 10083648160 10563502080 1161837488 12.420 100 15 30 0 1194946096 30040432 3068176
03:39:01 94442 8192000000 10101086048 10564653056 1112808752 11.959 95 24 48 0 1180539152 62973840 4756560
03:41:46 53961 8192000000 10092462528 10555285504 1109662752 11.929 97 11 22 1 1166667824 54619152 2385920
03:44:11 89501 8192000000 10085546656 10525097984 1070798272 11.560 93 11 22 0 1164983792 91735088 2450432
03:46:42 28162 8192000000 10104180640 10521858048 1080114032 11.649 95 57 114 0 1139168832 48330336 10724464
03:48:17 43996 8192000000 10104476256 10545979392 1118433232 12.012 99 31 62 1 1176419152 51921904 6064016
03:50:06 39672 8192000000 10101302432 10529443840 1121358176 12.040 99 40 80 1 1176779952 47043248 8378528
03:51:49 48473 8192000000 10052485136 10481803264 1091334240 11.755 97 20 40 1 1157331072 61267760 4729072
03:53:39 114281 8192000000 10042968544 10493607936 1104759168 11.883 98 26 52 0 1160534736 50588304 5187264
03:56:37 10631 8192000000 10062623152 10482692096 1106457264 11.899 99 35 70 0 1145601104 31730288 7413552
03:57:49 69119 8192000000 10061323632 10537275392 1108994400 11.923 99 19 39 0 1195740848 82588416 4158032
03:59:58 47014 8192000000 10038372448 10505318400 1095581520 11.796 98 18 36 0 1153687424 54351632 3754272
04:01:51 77811 8192000000 10056372528 10516131840 1077870544 11.627 97 18 36 0 1139722080 58113472 3738064
04:04:16 39984 8192000000 10079508912 10504167424 1104362656 11.879 99 29 58 0 1140375776 29815824 6197296
04:05:56 49540 8192000000 10105648912 10571931648 1114012192 11.970 99 16 32 1 1174155552 56895648 3247712
04:07:47 30424 8192000000 10092812528 10519236608 1118595696 12.014 99 36 72 1 1171576384 45618880 7361808
04:09:20 33218 8192000000 10097629472 10521931776 1117025824 11.999 99 40 80 1 1183484672 58523936 7934912
04:10:55 27512 8192000000 10076304928 10519375872 1130949808 12.130 100 36 73 0 1184181168 46288112 6943248
04:12:23 80278 8192000000 10134715248 10582876160 1135998064 12.178 99 16 32 2 1188552304 49440816 3113424
04:14:46 42807 8192000000 10101718656 10583330816 1141686944 12.231 99 17 34 0 1216386064 71678800 3020320
04:16:29 79959 8192000000 10089656464 10578837504 1135011024 12.169 99 15 28 1 1168987120 31254256 2721840
04:18:51 108690 8192000000 10101884192 10540109824 1134695664 12.166 99 23 46 0 1203023936 63329792 4998480
04:21:42 22037 8192000000 10098290432 10546716672 1123884016 12.064 98 44 88 1 1176112304 43740032 8488256
04:23:11 40954 8192000000 10055162768 10504847360 1139189312 12.208 100 13 25 0 1167063776 25325600 2548864
04:24:53 74998 8192000000 10099113968 10549727232 1098598704 11.824 96 14 27 2 1161872912 60025856 3248352
04:28:12 48471 8192000000 10074059040 10521231360 1085758944 11.702 96 16 31 1 1148510000 58577824 4173232
04:30:08 45607 8192000000 10069952352 10519552000 1109777104 11.930 99 24 48 0 1147960672 33373696 4809872
04:31:55 40268 8192000000 10082193216 10537684992 1103576400 11.872 97 37 74 1 1165976848 54653296 7747152
04:33:41 93921 8192000000 10055566320 10507669504 1024741616 11.118 91 22 44 1 1065635712 36186032 4708064
04:36:15 27967 8192000000 10115582896 10544152576 1085740448 11.702 97 53 104 0 1162328384 65929408 10658528

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 27-11-2021 04:38:13 to 27-11-2021 04:38:28.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 139 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 27-11-2021 04:38:29 to 27-11-2021 04:38:30.

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          237480
MON$OLDEST_ACTIVE               240188
MON$OLDEST_SNAPSHOT             240188
MON$NEXT_TRANSACTION            240188
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                       2079648
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             812
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2669 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2669 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2669 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2669 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 ---
:37: 
:38: mscs-test-161.budzdorov.ru    Sat Nov 27 04:37:10 2021
:39:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:40: 
:41: 
:42: mscs-test-161.budzdorov.ru    Sat Nov 27 04:37:17 2021
:43:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:44: 
:45: 
:46: mscs-test-161.budzdorov.ru    Sat Nov 27 04:37:17 2021
:47:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:48: 
:49: 
:50: mscs-test-161.budzdorov.ru    Sat Nov 27 04:37:17 2021
:51:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:52: 
:53: 
:54: mscs-test-161.budzdorov.ru    Sat Nov 27 04:37:17 2021
:55:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:56: 
--- end of diff output ---

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

27.11.21 04:38:32. 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
2021-11-27 04:38:34 No severe PSQL-related problems occured
27.11.21 04:38:35. End of report.