Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 31.10.2021 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 58e1ddde69c44dad93d327777e5becec
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-05-09 08:45
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 4793.92
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 16071296 kB
MemAvailable 30879972 kB
Buffers 0 kB
Cached 15667604 kB
SwapCached 4364 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-10-31 10:37:09
DTS_END                         2021-10-31 10:37:09.0640
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/53902
TRN_ID                          223878
ATT_ID                          657
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: 8442.56 25391 1013107
customer order: creation 162.28 420 19474
customer order: refuse 13.33 395 1599
order to supplier: creation 42.77 1492 5132
order to supplier: removal 3.10 136595 372
invoice (draft): creation 42.64 3888 5117
invoice (draft): removal 49.14 1151 5897
invoice accept: apply 38.33 23448 4599
invoice accept: cancel 43.96 1204 5275
customer reserve: creation 3506.03 234 420724
customer reserve: removal 4261.17 76 511340
realization accept: apply 78.51 203 9421
realization accept: cancel 80.52 127 9662
payment from customer: creation 56.35 135 6762
payment from customer: removal 5.14 136 617
payment to supplier: creation 45.75 157 5490
payment to supplier: removal 3.16 87 379
service: total inventory turnovers 3.40 4458 408
service: total monetary turnovers 5.98 637 718
service: aggregate perf. data 0.97 13527 116
service: refresh index statistics 0.04 319442 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 31-10-2021 10:39:01 to 31-10-2021 10:39:03.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6903
TEST_TIME 2 6184
TEST_TIME 3 5890
TEST_TIME 4 5545
TEST_TIME 5 5483
TEST_TIME 6 5662
TEST_TIME 7 5402
TEST_TIME 8 5561
TEST_TIME 9 5549
TEST_TIME 10 5578
TEST_TIME 11 5975
TEST_TIME 12 6075
TEST_TIME 13 6450
TEST_TIME 14 7118
TEST_TIME 15 7480
TEST_TIME 16 7744
TEST_TIME 17 7934
TEST_TIME 18 8078
TEST_TIME 19 8252
TEST_TIME 20 8474
TEST_TIME 21 8483
TEST_TIME 22 8604
TEST_TIME 23 8794
TEST_TIME 24 8818
TEST_TIME 25 8932
TEST_TIME 26 8752
TEST_TIME 27 8860
TEST_TIME 28 9720
TEST_TIME 29 10488
TEST_TIME 30 10325
TEST_TIME 31 10691
TEST_TIME 32 10389
TEST_TIME 33 10176
TEST_TIME 34 10157
TEST_TIME 35 10090
TEST_TIME 36 9941
TEST_TIME 37 9751
TEST_TIME 38 9652
TEST_TIME 39 9538
TEST_TIME 40 9373
TEST_TIME 41 9204
TEST_TIME 42 9171
TEST_TIME 43 9367
TEST_TIME 44 9363
TEST_TIME 45 9273
TEST_TIME 46 9329
TEST_TIME 47 9228
TEST_TIME 48 9173
TEST_TIME 49 9116
TEST_TIME 50 9067
TEST_TIME 51 8944
TEST_TIME 52 9014
TEST_TIME 53 9201
TEST_TIME 54 9186
TEST_TIME 55 9103
TEST_TIME 56 9108
TEST_TIME 57 9058
TEST_TIME 58 9081
TEST_TIME 59 9097
TEST_TIME 60 9078
TEST_TIME 61 9106
TEST_TIME 62 9061
TEST_TIME 63 9138
TEST_TIME 64 9204
TEST_TIME 65 9133
TEST_TIME 66 9029
TEST_TIME 67 8944
TEST_TIME 68 8909
TEST_TIME 69 8928
TEST_TIME 70 8904
TEST_TIME 71 8869
TEST_TIME 72 8840
TEST_TIME 73 8888
TEST_TIME 74 8840
TEST_TIME 75 8809
TEST_TIME 76 8739
TEST_TIME 77 8712
TEST_TIME 78 8713
TEST_TIME 79 8690
TEST_TIME 80 8619
TEST_TIME 81 8548
TEST_TIME 82 8554
TEST_TIME 83 8525
TEST_TIME 84 8520
TEST_TIME 85 8670
TEST_TIME 86 8666
TEST_TIME 87 8734
TEST_TIME 88 8718
TEST_TIME 89 8713
TEST_TIME 90 8734
TEST_TIME 91 8784
TEST_TIME 92 8814
TEST_TIME 93 8831
TEST_TIME 94 8759
TEST_TIME 95 8707
TEST_TIME 96 8715
TEST_TIME 97 8667
TEST_TIME 98 8601
TEST_TIME 99 8602
TEST_TIME 100 8591
TEST_TIME 101 8560
TEST_TIME 102 8546
TEST_TIME 103 8542
TEST_TIME 104 8567
TEST_TIME 105 8625
TEST_TIME 106 8650
TEST_TIME 107 8735
TEST_TIME 108 8705
TEST_TIME 109 8718
TEST_TIME 110 8715
TEST_TIME 111 8738
TEST_TIME 112 8751
TEST_TIME 113 8679
TEST_TIME 114 8668
TEST_TIME 115 8640
TEST_TIME 116 8619
TEST_TIME 117 8588
TEST_TIME 118 8555
TEST_TIME 119 8510
TEST_TIME 120 8445

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 31-10-2021 10:39:04 to 31-10-2021 10:39:07.

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 1402685 1402685 0 0.00 0 22732 9 0 0
doc_list_biud 1402684 1402684 0 0.00 0 98994 36 0 0
sp_add_invoice_to_stock 4680 4599 81 1.73 61 211524 23448 0 81
sp_cancel_adding_invoice 5275 5275 0 0.00 6 67910 1205 0 0
sp_cancel_client_order 1599 1599 0 0.00 4 8255 395 0 0
sp_cancel_customer_reserve 511340 511340 0 0.00 1 41257 76 0 0
sp_cancel_pay_from_customer 617 617 0 0.00 3 7795 136 0 0
sp_cancel_pay_to_supplier 379 379 0 0.00 3 3004 88 0 0
sp_cancel_supplier_invoice 5900 5897 3 0.05 2 99002 1152 0 3
sp_cancel_supplier_order 372 372 0 0.00 11 1261556 136596 0 0
sp_cancel_write_off 9662 9662 0 0.00 1 17150 128 0 0
sp_client_order 19474 19474 0 0.00 4 28860 420 0 0
sp_customer_reserve 420724 420724 0 0.00 0 52220 235 0 0
sp_fill_shopping_cart 29711 29711 0 0.00 1 27641 46 0 0
sp_fill_shopping_cart_clo_res 420718 420718 0 0.00 0 23209 17 0 0
sp_get_clo_for_invoice 4591 4591 0 0.00 0 28461 379 0 0
sp_kill_cost_storno 15933 15933 0 0.00 0 22707 75 0 0
sp_kill_qstorno_ret_qs2qd 1038611 1038611 0 0.00 0 98993 42 0 0
sp_kill_qty_storno 548157 548157 0 0.00 0 98994 91 0 0
sp_lock_selected_doc 19349 19349 0 0.00 0 8106 5 0 0
sp_make_cost_storno 26264 26264 0 0.00 0 14304 65 0 0
sp_multiply_rows_for_pdistr 26264 26264 0 0.00 0 7294 39 0 0
sp_multiply_rows_for_qdistr 19474 19474 0 0.00 0 22980 201 0 0
sp_pay_from_customer 6762 6762 0 0.00 4 11742 135 0 0
sp_pay_to_supplier 5490 5490 0 0.00 5 10645 158 0 0
sp_payment_common 12252 12252 0 0.00 1 7320 29 0 0
sp_qd_handle_on_cancel_clo 1599 1599 0 0.00 0 8248 355 0 0
sp_qd_handle_on_invoice_upd_sts 9866 9866 0 0.00 0 39779 496 0 0
sp_qd_handle_on_reserve_upd_sts 9421 9421 0 0.00 0 16731 63 0 0
sp_reserve_write_off 9421 9421 0 0.00 7 16754 203 0 0
sp_supplier_invoice 5117 5117 0 0.00 7 119320 3888 0 0
sp_supplier_order 5132 5132 0 0.00 7 37649 1493 0 0
srv_aggregate_perf_data 116 116 0 0.00 2797 111361 13527 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 969 152359 42100 0 0
srv_find_qd_qs_mism 968803 968803 0 0.00 0 12560 7 0 0
srv_make_invnt_saldo 408 408 0 0.00 64 89099 4458 0 0
srv_make_money_saldo 718 718 0 0.00 1 10932 637 0 0
srv_recalc_idx_stat 5 5 0 0.00 171810 439560 319443 0 0
t$perf-abend:sp_add_invoice_to_stock 81 81 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 4599 4599 0 0.00 0 15930 215 0 0
t$perf-norm:sp_cancel_adding_invoice 376 376 0 0.00 1 11449 175 0 0
t$perf-norm:sp_cancel_client_order 1599 1599 0 0.00 0 3314 23 0 0
t$perf-norm:sp_cancel_customer_reserve 655 655 0 0.00 0 1331 13 0 0
t$perf-norm:sp_cancel_pay_from_customer 617 617 0 0.00 0 5143 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 379 379 0 0.00 0 9490 35 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 597 20 0 0
t$perf-norm:sp_cancel_supplier_order 372 372 0 0.00 0 28808 2072 0 0
t$perf-norm:sp_cancel_write_off 968 968 0 0.00 0 3521 12 0 0
t$perf-norm:sp_client_order 19474 19474 0 0.00 0 24578 26 0 0
t$perf-norm:sp_customer_reserve 954 954 0 0.00 0 3944 10 0 0
t$perf-norm:sp_pay_from_customer 6762 6762 0 0.00 0 4751 12 0 0
t$perf-norm:sp_pay_to_supplier 5490 5490 0 0.00 0 6444 14 0 0
t$perf-norm:sp_reserve_write_off 9421 9421 0 0.00 0 6420 15 0 0
t$perf-norm:sp_supplier_invoice 5117 5117 0 0.00 0 11516 28 0 0
t$perf-norm:sp_supplier_order 5132 5132 0 0.00 0 5797 22 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 639 12 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 399 9 0 0
t$perf-norm:srv_make_invnt_saldo 408 408 0 0.00 0 1080 8 0 0
t$perf-norm:srv_make_money_saldo 718 718 0 0.00 0 3187 15 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 11 5 0 0
v_add_invoice_to_stock 9344 9182 162 1.73 0 8504 19 0 162
v_all_customers 35688 35688 0 0.00 0 26 1 0 0
v_all_suppliers 28334 28334 0 0.00 0 95 1 0 0
v_all_wares 38948 38948 0 0.00 0 19 1 0 0
v_cancel_adding_invoice 752 752 0 0.00 0 482 6 0 0
v_cancel_client_order 3198 3198 0 0.00 0 3269 7 0 0
v_cancel_customer_prepayment 1234 1234 0 0.00 0 3521 12 0 0
v_cancel_customer_reserve 1310 1310 0 0.00 0 1642 5 0 0
v_cancel_payment_to_supplier 758 758 0 0.00 0 2996 8 0 0
v_cancel_supplier_invoice 748 742 6 0.80 0 1651 18 0 6
v_cancel_supplier_order 744 744 0 0.00 0 547 4 0 0
v_cancel_write_off 1936 1936 0 0.00 0 2919 18 0 0
v_max_id_clo_ord 5129 5129 0 0.00 0 1655 2 0 0
v_max_id_clo_res 954 954 0 0.00 0 4843 21 0 0
v_max_id_ord_sup 5108 5108 0 0.00 0 10292 5 0 0
v_max_non_paid_invoice 1560 1560 0 0.00 0 5327 6 0 0
v_max_non_paid_realizn 3575 3575 0 0.00 0 1708 3 0 0
v_min_id_clo_ord 5129 5129 0 0.00 0 2685 7 0 0
v_min_id_clo_res 954 954 0 0.00 0 2762 5 0 0
v_min_id_ord_sup 5108 5108 0 0.00 0 6613 7 0 0
v_min_non_paid_invoice 5490 5490 0 0.00 0 5327 20 0 0
v_min_non_paid_realizn 6762 6762 0 0.00 0 5109 21 0 0
v_our_firm 9634 9634 0 0.00 0 15 1 0 0
v_reserve_write_off 18842 18842 0 0.00 0 5568 7 0 0
x_lock_depdocs_on_canc_invoice 10801 10801 0 0.00 0 18287 153 0 0
x_lock_depdocs_on_canc_sup_ord 372 372 0 0.00 0 23772 580 0 0
x_make_qstorno_1000_1200 5132 5132 0 0.00 2 37636 1400 0 0
x_make_qstorno_1200_2000 5117 5117 0 0.00 3 114999 3718 0 0
x_make_qstorno_2100_3300 420724 420724 0 0.00 0 52218 215 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 31-10-2021 10:39:08 to 31-10-2021 10:39:12.

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:12 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:14 16376 8192000000 9716494496 10013523968 804294256 8.940 100 86 172 0 832443296 7753120 20395920
08:09:39 24207 8192000000 9758130352 10066722816 836477952 9.264 100 88 176 1 869772592 11985552 21309088
08:11:11 23049 8192000000 9800897824 10116857856 863266416 9.533 100 85 170 0 903388976 20333936 19788624
08:12:37 13559 8192000000 9864298736 10196439040 909306592 9.990 100 85 170 1 970159088 42250608 18601888
08:13:55 8669 8192000000 9920911728 10265014272 947724992 10.369 100 83 166 0 1040797408 75291600 17780816
08:15:06 6204 8192000000 9967383712 10322280448 988409392 10.766 100 79 158 1 1080071296 74924064 16737840
08:16:15 3409 8192000000 9987188976 10347212800 1022019616 11.092 100 85 169 0 1109439136 70101792 17317728
08:17:22 2402 8192000000 10008472464 10376495104 1045989664 11.322 100 83 166 1 1120731168 57982928 16758576
08:18:25 38798 8192000000 10008091360 10389446656 1060711808 11.463 100 63 126 0 1150685888 77491408 12482672
08:20:05 7149 8192000000 9974805648 10368520192 1072904336 11.580 100 58 116 0 1108891936 22571792 13415808
08:21:14 4850 8192000000 9994032832 10397106176 1076670144 11.616 100 37 75 1 1119334464 34835792 7828528
08:22:21 64240 8192000000 9998936800 10392158208 1081290384 11.660 100 49 96 1 1100102480 7884128 10927968
08:24:25 3794 8192000000 10024309088 10421526528 1088091680 11.725 100 49 97 0 1130464800 32482048 9891072
08:25:32 7889 8192000000 9988270864 10389233664 1081830224 11.665 99 49 96 3 1111916848 18385440 11701184
08:26:44 28444 8192000000 10012566800 10399649792 1083140096 11.677 99 48 94 0 1104788432 11881456 9766880
08:28:14 6954 8192000000 9983591408 10376536064 1038988688 11.255 96 48 96 1 1072882480 22998688 10895104
08:29:23 8021 8192000000 9949378608 10336546816 972869072 10.615 92 25 50 0 1000080960 22340528 4871360
08:30:31 9163 8192000000 9936343152 10326233088 981423344 10.698 94 22 44 0 1004988896 18660896 4904656
08:31:42 18490 8192000000 9946604800 10346328064 994302416 10.823 96 46 93 0 1023361264 18598240 10460608
08:33:01 7141 8192000000 9946389008 10330345472 1023362176 11.104 100 45 85 3 1062350192 28508400 10479616
08:34:08 8593 8192000000 9972922304 10364325888 1052212928 11.382 100 49 96 0 1080833312 17779136 10841248
08:35:19 81298 8192000000 9983719680 10386280448 1064943552 11.504 100 33 66 0 1093921440 21627504 7350384
08:37:46 18002 8192000000 10026852256 10422185984 1093843456 11.779 100 42 84 0 1130378448 27461360 9073632
08:39:25 18763 8192000000 10063998496 10465087488 1109673328 11.929 100 44 90 1 1172288672 52588048 10027296
08:40:56 13833 8192000000 10085697920 10489737216 1131182576 12.133 100 51 102 1 1193955584 52158656 10614352
08:42:14 14187 8192000000 10125761744 10539704320 1136211856 12.180 100 42 82 0 1237214944 92070880 8932208
08:43:33 6306 8192000000 10153414320 10557747200 1153706576 12.344 100 56 112 1 1266010000 100722240 11581184
08:44:51 19657 8192000000 10235488496 10639495168 1169756704 12.495 100 66 132 0 1335421600 151868096 13796800
08:46:18 6410 8192000000 10253580880 10654838784 1180652416 12.596 100 66 130 1 1349529056 154295680 14580960
08:47:28 14198 8192000000 10304155104 10717290496 1181835680 12.607 100 56 112 1 1401558208 206503152 13219376
08:48:51 4601 8192000000 10290583328 10701438976 1196253248 12.742 100 66 130 0 1395197280 184907920 14036112
08:50:01 2425 8192000000 10361511392 10765930496 1199848624 12.775 100 88 175 1 1459171504 239342224 19980656
08:51:07 968 8192000000 10363255680 10765889536 1173844240 12.533 100 92 183 0 1460355584 265583808 20927536
08:52:14 2120 8192000000 10327164032 10747461632 1176539904 12.558 100 75 148 1 1435025888 241479488 17006496
08:53:20 6011 8192000000 10341510128 10764374016 1174955328 12.543 100 66 130 1 1450631824 260588384 15088112
08:54:35 2396 8192000000 10328702272 10748207104 1159131584 12.395 100 86 170 0 1421774944 243697568 18945792
08:55:47 1953 8192000000 10328856576 10721599488 1145962448 12.272 99 92 185 3 1419416080 251727200 21726432
08:56:54 2749 8192000000 10358470304 10753724416 1141081328 12.226 99 92 184 1 1450613056 288310768 21220960
08:58:02 5076 8192000000 10381009424 10785718272 1161388640 12.416 100 71 143 1 1476097216 298058768 16649808
08:59:12 5828 8192000000 10377570288 10828591104 1162699072 12.429 100 53 106 2 1475455104 299556688 13199344
09:00:22 12624 8192000000 10409492544 10864574464 1165428480 12.454 100 36 72 1 1538500608 363163072 9909056
09:01:51 6075 8192000000 10401363392 10835496960 1164536864 12.446 100 41 82 0 1498983920 324090784 10356272
09:03:14 14711 8192000000 10399504752 10837610496 1141064480 12.226 100 30 60 1 1510050656 360354896 8631280
09:04:46 35324 8192000000 10380106512 10856247296 1113991200 11.970 99 26 52 0 1486364944 364670864 7702880
09:06:24 9980 8192000000 10126322528 10638647296 1140003328 12.216 100 45 88 1 1291656640 141679616 9973696
09:07:48 18110 8192000000 10137840048 10573975552 1120722928 12.034 98 36 70 3 1224821728 94900000 9198800
09:09:18 21616 8192000000 10071615504 10488791040 1121272928 12.039 98 36 72 1 1150608480 22469488 6866064
09:10:50 70502 8192000000 10076158736 10530639872 1106118160 11.896 98 29 57 0 1172258416 60558928 5581328
09:14:02 90207 8192000000 10090371168 10541903872 1091347088 11.755 96 20 40 0 1159232816 63621504 4264224
09:16:43 144192 8192000000 10168188096 10648285184 1122487472 12.050 100 27 54 0 1282604032 153914096 6202464
09:20:26 56926 8192000000 10121967168 10562781184 1134567632 12.164 100 24 48 0 1227728592 87755424 5405536
09:22:26 17385 8192000000 10102872704 10546597888 1132154560 12.142 100 33 66 0 1208444976 68588592 7701824
09:23:44 48938 8192000000 10111874368 10553356288 1124220800 12.067 99 22 44 1 1228662096 99718448 4722848
09:25:43 108575 8192000000 10152756240 10630471680 1137275088 12.190 100 19 38 0 1229301488 87702000 4324400
09:29:30 62635 8192000000 10129393344 10640842752 1136844592 12.186 100 22 44 2 1275920016 134441952 4633472
09:32:12 40447 8192000000 10080105088 10513408000 1141756192 12.232 100 30 58 1 1183462816 35240128 6466496
09:33:53 33382 8192000000 10075756160 10502987776 1125674992 12.081 99 34 68 1 1165068128 32930304 6462832
09:35:28 53032 8192000000 10091485056 10547994624 1106706432 11.901 97 32 64 1 1177260880 64535072 6019376
09:37:34 108283 8192000000 10061510240 10512982016 1066457536 11.518 94 18 36 0 1113325392 42965552 3902304
09:40:22 152359 8192000000 10069442000 10526150656 1070769392 11.559 94 13 26 0 1117798656 45144832 1884432
09:43:55 67689 8192000000 10107843520 10532319232 1109150064 11.924 98 35 69 0 1171281168 55473984 6657120
09:46:03 9938 8192000000 10107670688 10551660544 1115551072 11.985 99 37 75 0 1198950064 75792896 7606096
09:47:15 75003 8192000000 10089547056 10555396096 1116183072 11.991 98 13 26 0 1192468176 73461440 2823664
09:50:14 19269 8192000000 10045998368 10494730240 1106747392 11.902 98 45 88 2 1150969344 34316320 9905632
09:51:34 68175 8192000000 10069657008 10523570176 1126747776 12.091 100 14 28 1 1153653728 24292176 2613776
09:54:15 81495 8192000000 10069606160 10526900224 1089581504 11.739 96 29 58 1 1135696000 40200832 5913664
09:56:54 66008 8192000000 10087814288 10539700224 1128109184 12.104 99 18 36 1 1180731760 48859456 3763120
09:59:00 69276 8192000000 10121414144 10577367040 1082843872 11.675 95 13 27 0 1159352640 73731968 2776800
10:01:11 64744 8192000000 10119114096 10612707328 1098446800 11.823 97 25 50 1 1204201744 100538256 5216688
10:03:42 48610 8192000000 10074920848 10569166848 1118957600 12.017 99 29 58 0 1176975312 51972928 6044784
10:05:31 38371 8192000000 10109355392 10537758720 1134855648 12.167 100 44 89 0 1204819824 60106992 9857184
10:07:09 69203 8192000000 10116937568 10595454976 1119487392 12.022 99 34 68 2 1213994784 87947024 6560368
10:09:19 36191 8192000000 10094508288 10541113344 1108366656 11.917 98 24 48 1 1193202912 79942336 4893920
10:10:55 130683 8192000000 10097396016 10559352832 1122327616 12.049 99 47 94 1 1174742912 43079232 9336064
10:14:22 35016 8192000000 10035748416 10491219968 1116058768 11.990 98 5 10 0 1139829648 23253744 517136
10:15:58 31728 8192000000 10039176272 10502328320 1096169520 11.801 97 14 28 0 1144431232 45007216 3254496
10:17:29 71835 8192000000 10109191040 10560753664 1131618656 12.137 99 18 36 1 1200468592 64903568 3946368
10:19:56 47523 8192000000 10124668336 10565074944 1118572192 12.014 98 16 32 0 1207112048 84988400 3551456
10:21:53 67732 8192000000 10179094304 10710417408 1135200560 12.170 99 14 28 0 1277053648 138412512 3440576
10:24:02 105939 8192000000 10072756656 10504794112 1086894656 11.713 95 13 26 0 1161122032 71735120 2492256
10:26:56 21751 8192000000 10076179920 10498195456 1112181072 11.953 98 34 68 0 1159767888 40698176 6888640
10:28:21 21808 8192000000 10132695008 10563493888 1136485488 12.182 100 35 70 0 1211139296 67549616 7104192
10:29:44 92117 8192000000 10054873024 10484412416 1108830080 11.921 98 37 74 0 1139255776 22362480 8063216
10:32:23 4462 8192000000 10076696304 10522836992 1122606752 12.052 99 40 80 1 1164960352 34651104 7702496
10:33:30 36708 8192000000 10021497296 10481250304 1102953232 11.866 98 3 6 0 1130570336 27047872 569232
10:35:07 62284 8192000000 10040570992 10506960896 1122412656 12.050 100 6 13 0 1157238864 33535344 1290864

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 31-10-2021 10:39:14 to 31-10-2021 10:39:29.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 81 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 31-10-2021 10:39:30 to 31-10-2021 10:39:32.

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          223877
MON$OLDEST_ACTIVE               224311
MON$OLDEST_SNAPSHOT             224311
MON$NEXT_TRANSACTION            224311
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                       2042656
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             784
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2649 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2649 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2649 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2649 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 ---
:27: 
:28: mscs-test-161.budzdorov.ru    Sun Oct 31 10:37:13 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Sun Oct 31 10:37:14 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
:35: 
:36: mscs-test-161.budzdorov.ru    Sun Oct 31 10:37:15 2021
:37:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:38: 
--- end of diff output ---

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

31.10.21 10:39:33. 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-10-31 10:39:35 No severe PSQL-related problems occured
31.10.21 10:39:36. End of report.