Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.02.2021 04:29
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16414520 kB
MemAvailable 31218244 kB
Buffers 20 kB
Cached 15234320 kB
SwapCached 4876 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.0
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-02-05 04:26:57
DTS_END                         2021-02-05 04:26:57.0420
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/46402
TRN_ID                          227526
ATT_ID                          625
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: 9218.12 20655 1106174
customer order: creation 170.06 411 20407
customer order: refuse 14.32 358 1718
order to supplier: creation 44.13 1414 5296
order to supplier: removal 3.24 154226 389
invoice (draft): creation 44.38 3615 5325
invoice (draft): removal 54.23 1154 6508
invoice accept: apply 39.84 17837 4781
invoice accept: cancel 49.01 1036 5881
customer reserve: creation 3726.11 172 447133
customer reserve: removal 4776.65 82 573198
realization accept: apply 81.13 187 9736
realization accept: cancel 88.61 133 10633
payment from customer: creation 58.42 111 7010
payment from customer: removal 5.50 90 660
payment to supplier: creation 47.96 133 5755
payment to supplier: removal 3.56 76 427
service: total inventory turnovers 3.90 3487 468
service: total monetary turnovers 6.03 596 724
service: aggregate perf. data 1.01 14405 121
service: refresh index statistics 0.03 213580 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-02-2021 04:30:03 to 05-02-2021 04:30:05.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2636
TEST_TIME 2 1987
TEST_TIME 3 2660
TEST_TIME 4 3649
TEST_TIME 5 3829
TEST_TIME 6 3899
TEST_TIME 7 4414
TEST_TIME 8 4440
TEST_TIME 9 4681
TEST_TIME 10 4950
TEST_TIME 11 5368
TEST_TIME 12 5764
TEST_TIME 13 6008
TEST_TIME 14 6100
TEST_TIME 15 6157
TEST_TIME 16 6405
TEST_TIME 17 6848
TEST_TIME 18 6966
TEST_TIME 19 7605
TEST_TIME 20 7663
TEST_TIME 21 7774
TEST_TIME 22 7631
TEST_TIME 23 7667
TEST_TIME 24 7706
TEST_TIME 25 7957
TEST_TIME 26 8345
TEST_TIME 27 8654
TEST_TIME 28 8811
TEST_TIME 29 8836
TEST_TIME 30 9124
TEST_TIME 31 11226
TEST_TIME 32 11023
TEST_TIME 33 11045
TEST_TIME 34 10988
TEST_TIME 35 10805
TEST_TIME 36 10930
TEST_TIME 37 10891
TEST_TIME 38 10862
TEST_TIME 39 10715
TEST_TIME 40 10544
TEST_TIME 41 10339
TEST_TIME 42 10277
TEST_TIME 43 10235
TEST_TIME 44 10117
TEST_TIME 45 10032
TEST_TIME 46 9943
TEST_TIME 47 9925
TEST_TIME 48 9926
TEST_TIME 49 9847
TEST_TIME 50 9766
TEST_TIME 51 9623
TEST_TIME 52 9704
TEST_TIME 53 9679
TEST_TIME 54 9685
TEST_TIME 55 9667
TEST_TIME 56 9704
TEST_TIME 57 9658
TEST_TIME 58 9735
TEST_TIME 59 9667
TEST_TIME 60 9725
TEST_TIME 61 9761
TEST_TIME 62 9840
TEST_TIME 63 9862
TEST_TIME 64 9832
TEST_TIME 65 9693
TEST_TIME 66 9630
TEST_TIME 67 9555
TEST_TIME 68 9490
TEST_TIME 69 9439
TEST_TIME 70 9378
TEST_TIME 71 9387
TEST_TIME 72 9338
TEST_TIME 73 9295
TEST_TIME 74 9268
TEST_TIME 75 9234
TEST_TIME 76 9233
TEST_TIME 77 9313
TEST_TIME 78 9328
TEST_TIME 79 9411
TEST_TIME 80 9419
TEST_TIME 81 9488
TEST_TIME 82 9419
TEST_TIME 83 9329
TEST_TIME 84 9305
TEST_TIME 85 9244
TEST_TIME 86 9188
TEST_TIME 87 9146
TEST_TIME 88 9112
TEST_TIME 89 9126
TEST_TIME 90 9104
TEST_TIME 91 9101
TEST_TIME 92 9103
TEST_TIME 93 9107
TEST_TIME 94 9184
TEST_TIME 95 9249
TEST_TIME 96 9263
TEST_TIME 97 9237
TEST_TIME 98 9269
TEST_TIME 99 9443
TEST_TIME 100 9449
TEST_TIME 101 9432
TEST_TIME 102 9364
TEST_TIME 103 9321
TEST_TIME 104 9274
TEST_TIME 105 9214
TEST_TIME 106 9173
TEST_TIME 107 9163
TEST_TIME 108 9147
TEST_TIME 109 9148
TEST_TIME 110 9120
TEST_TIME 111 9125
TEST_TIME 112 9094
TEST_TIME 113 9092
TEST_TIME 114 9077
TEST_TIME 115 9179
TEST_TIME 116 9216
TEST_TIME 117 9242
TEST_TIME 118 9227
TEST_TIME 119 9208
TEST_TIME 120 9223
TEST_TIME 121 9147

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 05-02-2021 04:30:06 to 05-02-2021 04:30:10.

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 1521209 1521209 0 0.00 0 25332 7 0 0
doc_list_biud 1521206 1521206 0 0.00 0 68283 39 0 0
sp_add_invoice_to_stock 4916 4781 135 2.74 19 180736 17837 0 135
sp_cancel_adding_invoice 5881 5881 0 0.00 4 89922 1037 0 0
sp_cancel_client_order 1718 1718 0 0.00 4 10117 358 0 0
sp_cancel_customer_reserve 573198 573198 0 0.00 1 68284 82 0 0
sp_cancel_pay_from_customer 660 660 0 0.00 3 3447 90 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 2076 77 0 0
sp_cancel_supplier_invoice 6511 6508 3 0.04 2 36901 1155 0 3
sp_cancel_supplier_order 389 389 0 0.00 6 1302282 154226 0 0
sp_cancel_write_off 10633 10633 0 0.00 1 9437 133 0 0
sp_client_order 20407 20407 0 0.00 4 29702 412 0 0
sp_customer_reserve 447133 447133 0 0.00 0 59670 172 0 0
sp_fill_shopping_cart 30997 30997 0 0.00 1 24036 46 0 0
sp_fill_shopping_cart_clo_res 447129 447129 0 0.00 0 27957 21 0 0
sp_get_clo_for_invoice 4774 4774 0 0.00 0 28761 365 0 0
sp_kill_cost_storno 17601 17601 0 0.00 0 9609 66 0 0
sp_kill_qstorno_ret_qs2qd 1163926 1163926 0 0.00 0 68273 44 0 0
sp_kill_qty_storno 612836 612836 0 0.00 0 68282 95 0 0
sp_lock_selected_doc 20209 20209 0 0.00 0 9023 5 0 0
sp_make_cost_storno 27275 27275 0 0.00 0 14989 56 0 0
sp_multiply_rows_for_pdistr 27274 27274 0 0.00 0 14316 35 0 0
sp_multiply_rows_for_qdistr 20407 20407 0 0.00 0 29562 250 0 0
sp_pay_from_customer 7010 7010 0 0.00 4 16437 112 0 0
sp_pay_to_supplier 5755 5755 0 0.00 5 14413 134 0 0
sp_payment_common 12764 12764 0 0.00 1 12058 23 0 0
sp_qd_handle_on_cancel_clo 1718 1718 0 0.00 0 10111 335 0 0
sp_qd_handle_on_invoice_upd_sts 10655 10655 0 0.00 0 29645 436 0 0
sp_qd_handle_on_reserve_upd_sts 9735 9735 0 0.00 0 12397 52 0 0
sp_reserve_write_off 9736 9736 0 0.00 7 16876 188 0 0
sp_supplier_invoice 5325 5325 0 0.00 8 148049 3615 0 0
sp_supplier_order 5296 5296 0 0.00 6 42647 1415 0 0
srv_aggregate_perf_data 121 121 0 0.00 2532 139346 14406 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1215 114146 42509 0 0
srv_find_qd_qs_mism 1060111 1060111 0 0.00 0 25331 5 0 0
srv_make_invnt_saldo 468 468 0 0.00 38 97782 3488 0 0
srv_make_money_saldo 724 724 0 0.00 1 17559 597 0 0
srv_recalc_idx_stat 4 4 0 0.00 167887 294389 213580 0 0
t$perf-abend:sp_add_invoice_to_stock 135 135 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 3 3 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4781 4781 0 0.00 0 37070 194 0 0
t$perf-norm:sp_cancel_adding_invoice 422 422 0 0.00 0 11118 115 0 0
t$perf-norm:sp_cancel_client_order 1718 1718 0 0.00 0 1137 11 0 0
t$perf-norm:sp_cancel_customer_reserve 712 712 0 0.00 0 885 10 0 0
t$perf-norm:sp_cancel_pay_from_customer 660 660 0 0.00 0 656 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 446 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 423 423 0 0.00 0 714 11 0 0
t$perf-norm:sp_cancel_supplier_order 389 389 0 0.00 0 24264 2095 0 0
t$perf-norm:sp_cancel_write_off 950 950 0 0.00 0 3794 12 0 0
t$perf-norm:sp_client_order 20407 20407 0 0.00 0 11546 22 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 998 6 0 0
t$perf-norm:sp_pay_from_customer 7010 7010 0 0.00 0 3225 7 0 0
t$perf-norm:sp_pay_to_supplier 5755 5755 0 0.00 0 9493 13 0 0
t$perf-norm:sp_reserve_write_off 9736 9736 0 0.00 0 8367 11 0 0
t$perf-norm:sp_supplier_invoice 5325 5325 0 0.00 0 11473 21 0 0
t$perf-norm:sp_supplier_order 5296 5296 0 0.00 0 4855 16 0 0
t$perf-norm:srv_aggregate_perf_data 121 121 0 0.00 0 400 15 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 208 5 0 0
t$perf-norm:srv_make_invnt_saldo 468 468 0 0.00 0 1244 14 0 0
t$perf-norm:srv_make_money_saldo 724 724 0 0.00 0 1525 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 5 3 0 0
v_add_invoice_to_stock 9814 9544 270 2.75 0 5895 19 0 270
v_all_customers 37372 37372 0 0.00 0 17 1 0 0
v_all_suppliers 29896 29896 0 0.00 0 20 1 0 0
v_all_wares 40804 40804 0 0.00 0 54 1 0 0
v_cancel_adding_invoice 844 844 0 0.00 0 2016 13 0 0
v_cancel_client_order 3436 3436 0 0.00 0 719 4 0 0
v_cancel_customer_prepayment 1320 1320 0 0.00 0 3368 9 0 0
v_cancel_customer_reserve 1424 1424 0 0.00 0 1649 6 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 1780 8 0 0
v_cancel_supplier_invoice 852 846 6 0.70 0 1159 14 0 6
v_cancel_supplier_order 778 778 0 0.00 0 3429 11 0 0
v_cancel_write_off 1900 1900 0 0.00 0 2496 15 0 0
v_max_id_clo_ord 5282 5282 0 0.00 0 550 1 0 0
v_max_id_clo_res 991 991 0 0.00 0 2004 13 0 0
v_max_id_ord_sup 5308 5308 0 0.00 0 8933 9 0 0
v_max_non_paid_invoice 1396 1396 0 0.00 0 520 2 0 0
v_max_non_paid_realizn 3494 3494 0 0.00 0 849 3 0 0
v_min_id_clo_ord 5282 5282 0 0.00 0 2031 6 0 0
v_min_id_clo_res 991 991 0 0.00 0 8438 20 0 0
v_min_id_ord_sup 5308 5308 0 0.00 0 10515 9 0 0
v_min_non_paid_invoice 5754 5754 0 0.00 0 11468 15 0 0
v_min_non_paid_realizn 7010 7010 0 0.00 0 11982 16 0 0
v_our_firm 10464 10464 0 0.00 0 16 1 0 0
v_reserve_write_off 19470 19470 0 0.00 0 8409 7 0 0
x_lock_depdocs_on_canc_invoice 11966 11966 0 0.00 0 15041 119 0 0
x_lock_depdocs_on_canc_sup_ord 389 389 0 0.00 0 8908 390 0 0
x_make_qstorno_1000_1200 5296 5296 0 0.00 2 41403 1334 0 0
x_make_qstorno_1200_2000 5325 5325 0 0.00 4 142396 3438 0 0
x_make_qstorno_2100_3300 447133 447133 0 0.00 0 57967 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-02-2021 04:30:11 to 05-02-2021 04:30:15.

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
01:57:00 10 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
01:58:01 21683 8192000000 9728967456 10027528192 808083488 8.978 100 85 170 1 836513968 7652768 20777712
01:59:25 11083 8192000000 9784785552 10091769856 859703008 9.497 100 95 190 0 893202560 11220848 22278704
02:00:43 19879 8192000000 9805470272 10123591680 876995616 9.670 100 83 166 0 912913632 17373872 18544144
02:02:15 13972 8192000000 9875088192 10206646272 922057072 10.116 100 82 164 0 981285792 41495504 17733216
02:03:36 10203 8192000000 9915541872 10254479360 958584992 10.475 100 86 170 1 1028935232 52351696 17998544
02:04:53 5919 8192000000 9979109856 10331529216 998846064 10.867 100 86 171 0 1094032336 77909408 17276864
02:06:03 3452 8192000000 9999204688 10359611392 1019874864 11.071 100 86 173 1 1113261920 76131520 17255536
02:07:09 3301 8192000000 10005997488 10376200192 1038916352 11.254 100 79 158 1 1114659568 59640896 16102320
02:08:15 2892 8192000000 10034932112 10404610048 1060717136 11.463 100 89 171 3 1144513696 66186672 17609888
02:09:19 2096 8192000000 10047491712 10420862976 1076866400 11.618 100 86 173 0 1158665008 63814352 17984256
02:10:24 20994 8192000000 10042493440 10434928640 1082406832 11.670 100 52 105 0 1166137920 73147808 10583280
02:11:47 5617 8192000000 10009637072 10395971584 1087783488 11.722 100 47 90 1 1117005824 19984240 9238096
02:12:54 10191 8192000000 9999632608 10398535680 1089999872 11.743 100 30 60 2 1119290736 22503776 6787088
02:14:07 36716 8192000000 10003396064 10423021568 1093860624 11.779 100 27 52 0 1129232976 29751856 5620496
02:16:20 2672 8192000000 10027678336 10431209472 1101197008 11.849 100 47 89 3 1148250080 37012112 10040960
02:17:25 5101 8192000000 10023772768 10427305984 1111195936 11.944 100 27 54 1 1139554944 22766928 5592080
02:18:31 7407 8192000000 10024293856 10419986432 1083632512 11.682 99 28 55 0 1105893952 17082384 5179056
02:19:39 27514 8192000000 9985752960 10390769664 1024418640 11.115 94 24 47 2 1045141344 16378336 4344368
02:21:08 4107 8192000000 9945734272 10323369984 1015247744 11.026 98 49 102 0 1047158960 20955776 10955440
02:22:13 8349 8192000000 9953509504 10339409920 1011488400 10.990 97 39 76 1 1043394544 22770768 9135376
02:23:24 78521 8192000000 9940095872 10341273600 990776976 10.789 95 28 56 0 1007163584 10352080 6034528
02:25:43 9983 8192000000 9977768640 10367815680 1055323136 11.412 100 38 72 4 1086966160 22859760 8783264
02:26:54 9062 8192000000 9990048144 10379558912 1070439040 11.556 100 49 98 0 1101435888 19366528 11630320
02:28:30 72944 8192000000 10066405120 10477662208 1100620192 11.844 100 30 60 0 1170423472 63700272 6103008
02:30:47 53831 8192000000 10096830288 10512048128 1124898816 12.073 100 36 72 0 1207916880 74454384 8563680
02:32:49 14569 8192000000 10140337440 10548375552 1138403776 12.201 100 50 100 0 1244299440 95391904 10503760
02:34:08 9667 8192000000 10175974784 10585382912 1155434528 12.360 100 57 114 2 1278925904 111314304 12177072
02:35:24 3336 8192000000 10238747312 10645123072 1157285200 12.378 100 75 148 0 1337321504 164136784 15899520
02:36:34 16170 8192000000 10293789888 10718007296 1168977584 12.487 100 59 118 0 1392775744 210477664 13320496
02:38:07 4441 8192000000 10294027472 10727444480 1179303424 12.584 100 62 123 2 1402793456 209958320 13531712
02:39:19 1924 8192000000 10345382368 10749829120 1191501328 12.697 100 83 161 0 1438776640 229958544 17316768
02:40:26 2411 8192000000 10371989888 10782904320 1198422016 12.762 100 85 166 1 1470724464 253633904 18668544
02:41:36 3166 8192000000 10379336288 10791768064 1192803216 12.709 100 83 166 1 1475195120 264556176 17835728
02:42:42 3113 8192000000 10450744624 10861686784 1203808544 12.812 100 87 173 1 1552222672 327293728 21120400
02:43:53 1496 8192000000 10439289488 10848108544 1199645456 12.773 100 86 171 1 1533948752 314486368 19816928
02:45:03 2267 8192000000 10475734400 10888310784 1195537568 12.735 99 83 165 1 1565459056 349912464 20009024
02:46:08 4379 8192000000 10428523984 10841264128 1178874576 12.580 100 71 139 1 1525259344 328714912 17669856
02:47:21 1214 8192000000 10507588336 10921758720 1194664928 12.727 100 77 153 0 1607606720 394529200 18412592
02:48:30 1686 8192000000 10543924624 10959577088 1184948208 12.636 99 69 136 0 1639071136 436745856 17377072
02:49:42 1262 8192000000 10646320720 11050622976 1192903648 12.710 99 92 186 0 1732773696 517624320 22245728
02:50:48 9905 8192000000 10668516480 11092434944 1190841472 12.691 100 57 114 1 1759379360 553345168 15192720
02:52:12 6472 8192000000 10624722768 11116879872 1173154272 12.526 100 45 90 1 1765201920 579517184 12530464
02:53:27 17970 8192000000 10628161936 11086663680 1185759008 12.644 100 32 62 1 1717562048 523718368 8084672
02:54:58 15918 8192000000 10590225440 11100127232 1136957488 12.187 99 36 72 0 1699527552 552632528 9937536
02:56:36 22852 8192000000 10511325600 11010367488 1105858464 11.893 98 34 68 0 1673164832 556969376 10336992
02:58:06 61388 8192000000 10092249456 10512674816 1092414864 11.766 96 30 59 1 1145015744 46050912 6549968
03:00:11 90899 8192000000 10095268464 10528030720 1103419344 11.870 97 28 55 3 1167903856 58183072 6301440
03:02:44 42484 8192000000 10086510528 10548224000 1123978640 12.065 99 12 25 0 1186705616 60225584 2501392
03:04:41 74146 8192000000 10066796048 10493440000 1101563232 11.852 97 25 50 1 1143437632 36177776 5696624
03:06:59 114146 8192000000 10079594240 10564829184 1119014560 12.018 99 24 45 2 1205343120 81121520 5207040
03:10:55 43510 8192000000 10103328528 10537594880 1125342080 12.077 99 33 66 2 1183333168 51009744 6981344
03:12:46 41805 8192000000 10105142800 10550824960 1129640032 12.118 100 7 14 1 1195455280 64056592 1758656
03:14:40 27829 8192000000 10072405376 10544119808 1126728704 12.091 99 24 48 1 1163675024 32488928 4457392
03:16:08 54674 8192000000 10101706272 10553999360 1139020544 12.206 100 9 18 0 1188011696 47054032 1937120
03:18:03 60923 8192000000 10090607888 10607357952 1140228864 12.218 100 6 12 0 1195368256 53832768 1306624
03:20:05 28030 8192000000 10124991712 10576343040 1149237776 12.302 100 28 56 2 1222295296 67114016 5943504
03:21:40 13606 8192000000 10093705600 10531905536 1126609648 12.089 98 35 70 0 1162688064 28995888 7082528
03:22:59 70050 8192000000 10095017008 10567311360 1128204912 12.104 98 22 43 1 1197266320 64928528 4132880
03:25:12 46934 8192000000 10137292688 10581475328 1115196992 11.982 97 23 46 1 1191166080 70878320 5090768
03:27:00 95717 8192000000 10077941072 10542583808 1093960448 11.780 95 13 26 0 1193317456 96408624 2948384
03:29:56 87598 8192000000 10078045216 10538143744 1122422688 12.050 98 13 26 1 1173840560 49034576 2383296
03:32:54 42369 8192000000 10057483840 10515894272 1128551600 12.108 99 8 16 0 1159512160 29346880 1613680
03:34:38 68622 8192000000 10046610464 10513543168 1112089760 11.952 98 8 16 0 1130645872 16776128 1779984
03:36:51 44324 8192000000 10058516288 10521120768 1103165264 11.868 98 12 22 2 1151359808 45900608 2293936
03:38:49 33873 8192000000 10099718896 10533883904 1126730704 12.091 98 24 49 1 1162622016 30896352 4994960
03:40:35 38311 8192000000 10117721824 10558922752 1115876144 11.988 97 26 51 1 1197371872 75619648 5876080
03:42:22 45184 8192000000 10141967872 10593550336 1105444080 11.889 97 27 54 1 1225595024 113950736 6200208
03:44:07 54072 8192000000 10104771120 10564407296 1103685312 11.873 97 25 49 3 1186470848 77102896 5682640
03:46:04 43249 8192000000 10103237952 10589036544 1100999760 11.847 97 21 41 1 1190962592 85707760 4255072
03:47:48 41038 8192000000 10032765664 10463895552 1075651792 11.606 96 30 60 2 1099982976 18634224 5696960
03:49:30 92973 8192000000 10032400768 10506833920 1100001088 11.838 99 24 48 0 1156928096 52162192 4764816
03:52:12 52212 8192000000 10051160256 10541703168 1099030160 11.828 99 3 6 1 1141951280 42387680 533440
03:54:38 36526 8192000000 10082744448 10527535104 1111754576 11.949 98 23 46 0 1163510752 47149200 4606976
03:56:29 54512 8192000000 10093026928 10557259776 1126918816 12.092 100 31 62 2 1192091760 58998832 6174112
03:58:29 56892 8192000000 10151897888 10589605888 1133406544 12.153 99 29 57 1 1246248448 106125616 6716288
04:00:28 83717 8192000000 10081753680 10562134016 1121570592 12.042 99 14 28 2 1213284304 88652240 3061472
04:02:53 43483 8192000000 10198106064 10635132928 1144872752 12.261 99 30 60 2 1265205824 113918944 6414128
04:04:36 47106 8192000000 10146894800 10677895168 1134660976 12.165 99 15 30 0 1308634208 170469472 3503760
04:06:27 72403 8192000000 10078581312 10529865728 1130809216 12.129 99 18 36 0 1189072480 54881760 3381504
04:08:45 100677 8192000000 10053701216 10501910528 1126916832 12.092 99 22 44 0 1160206592 28698368 4591392
04:11:35 39586 8192000000 10086983248 10537250816 1146335424 12.275 100 13 26 0 1187033488 38011456 2686608
04:13:21 49730 8192000000 10107852848 10601754624 1149731696 12.307 100 6 12 0 1202693728 51821392 1140640
04:15:12 67746 8192000000 10077883952 10526810112 1124041408 12.065 98 10 20 1 1160723664 34659152 2023104
04:17:24 50012 8192000000 10117420384 10574635008 1118587728 12.014 97 26 52 0 1178296912 54045408 5663776
04:19:25 45190 8192000000 10141509760 10592342016 1138088800 12.198 99 19 38 0 1205793104 63084880 4619424
04:21:12 107106 8192000000 10110742528 10627833856 1137548544 12.192 99 15 30 1 1267186064 126365456 3272064
04:24:08 71383 8192000000 10082728688 10537451520 1112477344 11.956 97 21 42 0 1135071200 18109456 4484400
04:26:24 89406 8192000000 9154699856 9454202880 292843888 3.451 28 5 8 0 316848640 23043792 960960

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-02-2021 04:30:16 to 05-02-2021 04:30:31.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 135 v_add_invoice_to_stock 335544517
except 3 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-02-2021 04:30:32 to 05-02-2021 04:30:34.

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          228013
MON$OLDEST_ACTIVE               228014
MON$OLDEST_SNAPSHOT             228014
MON$NEXT_TRANSACTION            228014
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                       2112832
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             783
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2361 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2361 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2361 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2361 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:61: 
:62: mscs-test-161.budzdorov.ru    Fri Feb  5 04:27:59 2021
:63:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:64: 
--- end of diff output ---

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

05.02.21 04:30:35. 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-02-05 04:30:37 No severe PSQL-related problems occured
05.02.21 04:30:39. End of report.