Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.12.2021 07: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 16296952 kB
MemAvailable 31392444 kB
Buffers 0 kB
Cached 15350704 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-12-20 07:37:08
DTS_END                         2021-12-20 07:37:08.6460
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_ORDER(38:5) ==> X_MAKE_QSTORNO_1000_1200(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38666
TRN_ID                          235736
ATT_ID                          694
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: 9441.52 25567 1132982
customer order: creation 168.64 405 20237
customer order: refuse 15.08 326 1809
order to supplier: creation 46.13 1403 5536
order to supplier: removal 3.63 118300 435
invoice (draft): creation 45.46 3848 5455
invoice (draft): removal 54.60 971 6552
invoice accept: apply 41.81 19094 5017
invoice accept: cancel 49.64 837 5957
customer reserve: creation 3847.46 189 461695
customer reserve: removal 4873.34 69 584801
realization accept: apply 82.63 196 9915
realization accept: cancel 84.80 134 10176
payment from customer: creation 58.98 106 7078
payment from customer: removal 5.28 97 633
payment to supplier: creation 49.86 121 5983
payment to supplier: removal 3.08 78 369
service: total inventory turnovers 4.13 3537 496
service: total monetary turnovers 6.03 556 724
service: aggregate perf. data 0.92 16116 110
service: refresh index statistics 0.03 344950 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-12-2021 07:37:50 to 20-12-2021 07:37:52.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6290
TEST_TIME 2 4324
TEST_TIME 3 3947
TEST_TIME 4 4223
TEST_TIME 5 5635
TEST_TIME 6 5680
TEST_TIME 7 5400
TEST_TIME 8 5717
TEST_TIME 9 5633
TEST_TIME 10 5907
TEST_TIME 11 6574
TEST_TIME 12 6917
TEST_TIME 13 7081
TEST_TIME 14 6926
TEST_TIME 15 6985
TEST_TIME 16 7522
TEST_TIME 17 7683
TEST_TIME 18 7914
TEST_TIME 19 7760
TEST_TIME 20 8281
TEST_TIME 21 8298
TEST_TIME 22 8147
TEST_TIME 23 8347
TEST_TIME 24 9046
TEST_TIME 25 8991
TEST_TIME 26 9064
TEST_TIME 27 9225
TEST_TIME 28 9407
TEST_TIME 29 11258
TEST_TIME 30 11099
TEST_TIME 31 10963
TEST_TIME 32 10864
TEST_TIME 33 10665
TEST_TIME 34 10654
TEST_TIME 35 10639
TEST_TIME 36 10449
TEST_TIME 37 10390
TEST_TIME 38 10384
TEST_TIME 39 10247
TEST_TIME 40 10380
TEST_TIME 41 10225
TEST_TIME 42 10264
TEST_TIME 43 10282
TEST_TIME 44 10161
TEST_TIME 45 10011
TEST_TIME 46 10033
TEST_TIME 47 10048
TEST_TIME 48 10369
TEST_TIME 49 10332
TEST_TIME 50 10225
TEST_TIME 51 10225
TEST_TIME 52 10250
TEST_TIME 53 10263
TEST_TIME 54 10193
TEST_TIME 55 10149
TEST_TIME 56 10323
TEST_TIME 57 10358
TEST_TIME 58 10389
TEST_TIME 59 10252
TEST_TIME 60 10174
TEST_TIME 61 10042
TEST_TIME 62 9996
TEST_TIME 63 9941
TEST_TIME 64 10001
TEST_TIME 65 9968
TEST_TIME 66 9851
TEST_TIME 67 9872
TEST_TIME 68 9861
TEST_TIME 69 9820
TEST_TIME 70 9797
TEST_TIME 71 9806
TEST_TIME 72 9810
TEST_TIME 73 9894
TEST_TIME 74 9836
TEST_TIME 75 9821
TEST_TIME 76 9837
TEST_TIME 77 9918
TEST_TIME 78 9950
TEST_TIME 79 9998
TEST_TIME 80 9989
TEST_TIME 81 9957
TEST_TIME 82 9859
TEST_TIME 83 9833
TEST_TIME 84 9747
TEST_TIME 85 9751
TEST_TIME 86 9707
TEST_TIME 87 9688
TEST_TIME 88 9643
TEST_TIME 89 9556
TEST_TIME 90 9502
TEST_TIME 91 9486
TEST_TIME 92 9524
TEST_TIME 93 9553
TEST_TIME 94 9618
TEST_TIME 95 9607
TEST_TIME 96 9588
TEST_TIME 97 9571
TEST_TIME 98 9648
TEST_TIME 99 9637
TEST_TIME 100 9734
TEST_TIME 101 9757
TEST_TIME 102 9681
TEST_TIME 103 9596
TEST_TIME 104 9586
TEST_TIME 105 9540
TEST_TIME 106 9475
TEST_TIME 107 9449
TEST_TIME 108 9423
TEST_TIME 109 9448
TEST_TIME 110 9419
TEST_TIME 111 9393
TEST_TIME 112 9391
TEST_TIME 113 9364
TEST_TIME 114 9373
TEST_TIME 115 9427
TEST_TIME 116 9410
TEST_TIME 117 9485
TEST_TIME 118 9456
TEST_TIME 119 9492
TEST_TIME 120 9442

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-12-2021 07:37:53 to 20-12-2021 07:37:57.

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 1559784 1559784 0 0.00 0 19992 7 0 0
doc_list_biud 1559782 1559782 0 0.00 0 23356 33 0 0
sp_add_invoice_to_stock 5106 5017 89 1.74 17 172822 19095 0 89
sp_cancel_adding_invoice 5957 5957 0 0.00 3 121508 837 0 0
sp_cancel_client_order 1809 1809 0 0.00 5 12093 326 0 0
sp_cancel_customer_reserve 584801 584801 0 0.00 1 23342 70 0 0
sp_cancel_pay_from_customer 633 633 0 0.00 3 5039 98 0 0
sp_cancel_pay_to_supplier 369 369 0 0.00 3 3506 79 0 0
sp_cancel_supplier_invoice 6562 6552 10 0.15 1 15983 972 0 10
sp_cancel_supplier_order 435 435 0 0.00 11 1089856 118300 0 0
sp_cancel_write_off 10176 10176 0 0.00 1 16633 134 0 0
sp_client_order 20237 20237 0 0.00 4 28389 406 0 0
sp_customer_reserve 461695 461695 0 0.00 0 84486 190 0 0
sp_fill_shopping_cart 31223 31223 0 0.00 1 20164 42 0 0
sp_fill_shopping_cart_clo_res 461692 461692 0 0.00 0 22382 16 0 0
sp_get_clo_for_invoice 5014 5014 0 0.00 0 31667 339 0 0
sp_kill_cost_storno 17135 17135 0 0.00 0 15452 60 0 0
sp_kill_qstorno_ret_qs2qd 1186765 1186765 0 0.00 0 22898 38 0 0
sp_kill_qty_storno 624656 624656 0 0.00 0 23356 81 0 0
sp_lock_selected_doc 20667 20667 0 0.00 0 6193 4 0 0
sp_make_cost_storno 27989 27989 0 0.00 0 16482 57 0 0
sp_multiply_rows_for_pdistr 27988 27988 0 0.00 0 15423 33 0 0
sp_multiply_rows_for_qdistr 20237 20237 0 0.00 0 16813 202 0 0
sp_pay_from_customer 7078 7078 0 0.00 4 15481 107 0 0
sp_pay_to_supplier 5983 5983 0 0.00 5 15474 122 0 0
sp_payment_common 13061 13061 0 0.00 1 15443 30 0 0
sp_qd_handle_on_cancel_clo 1809 1809 0 0.00 0 12083 303 0 0
sp_qd_handle_on_invoice_upd_sts 10969 10969 0 0.00 0 23052 369 0 0
sp_qd_handle_on_reserve_upd_sts 9914 9914 0 0.00 0 23355 63 0 0
sp_reserve_write_off 9915 9915 0 0.00 7 23396 197 0 0
sp_supplier_invoice 5455 5455 0 0.00 7 115209 3848 0 0
sp_supplier_order 5536 5536 0 0.00 4 44434 1404 0 0
srv_aggregate_perf_data 110 110 0 0.00 2681 145752 16117 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1846 116930 44152 0 0
srv_find_qd_qs_mism 1085192 1085192 0 0.00 0 19991 6 0 0
srv_make_invnt_saldo 496 496 0 0.00 58 99980 3537 0 0
srv_make_money_saldo 724 724 0 0.00 1 14763 556 0 0
srv_recalc_idx_stat 4 4 0 0.00 244604 526007 344951 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 10 10 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5017 5017 0 0.00 0 13286 177 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 4728 133 0 0
t$perf-norm:sp_cancel_client_order 1809 1809 0 0.00 0 4100 14 0 0
t$perf-norm:sp_cancel_customer_reserve 678 678 0 0.00 0 11199 30 0 0
t$perf-norm:sp_cancel_pay_from_customer 633 633 0 0.00 0 1510 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 369 369 0 0.00 0 1208 9 0 0
t$perf-norm:sp_cancel_supplier_invoice 389 389 0 0.00 0 873 15 0 0
t$perf-norm:sp_cancel_supplier_order 435 435 0 0.00 0 26240 1328 0 0
t$perf-norm:sp_cancel_write_off 999 999 0 0.00 0 678 6 0 0
t$perf-norm:sp_client_order 20237 20237 0 0.00 0 12356 17 0 0
t$perf-norm:sp_customer_reserve 1006 1006 0 0.00 0 3132 17 0 0
t$perf-norm:sp_pay_from_customer 7078 7078 0 0.00 0 20937 13 0 0
t$perf-norm:sp_pay_to_supplier 5983 5983 0 0.00 0 7496 10 0 0
t$perf-norm:sp_reserve_write_off 9915 9915 0 0.00 0 5351 9 0 0
t$perf-norm:sp_supplier_invoice 5455 5455 0 0.00 0 20931 18 0 0
t$perf-norm:sp_supplier_order 5536 5536 0 0.00 0 7273 17 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 343 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 796 14 0 0
t$perf-norm:srv_make_invnt_saldo 496 496 0 0.00 0 1428 11 0 0
t$perf-norm:srv_make_money_saldo 724 724 0 0.00 0 2602 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 4 13 7 0 0
v_add_invoice_to_stock 10202 10024 178 1.74 0 4451 13 0 178
v_all_customers 37300 37300 0 0.00 0 214 1 0 0
v_all_suppliers 30512 30512 0 0.00 0 104 1 0 0
v_all_wares 40472 40472 0 0.00 0 64 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 1195 7 0 0
v_cancel_client_order 3618 3618 0 0.00 0 2570 5 0 0
v_cancel_customer_prepayment 1266 1266 0 0.00 0 2147 8 0 0
v_cancel_customer_reserve 1356 1356 0 0.00 0 654 4 0 0
v_cancel_payment_to_supplier 738 738 0 0.00 0 333 4 0 0
v_cancel_supplier_invoice 798 778 20 2.50 0 642 15 0 20
v_cancel_supplier_order 870 870 0 0.00 0 642 5 0 0
v_cancel_write_off 1998 1998 0 0.00 0 1391 12 0 0
v_max_id_clo_ord 5536 5536 0 0.00 0 598 1 0 0
v_max_id_clo_res 1006 1006 0 0.00 0 5800 21 0 0
v_max_id_ord_sup 5450 5450 0 0.00 0 20149 7 0 0
v_max_non_paid_invoice 1713 1713 0 0.00 0 746 2 0 0
v_max_non_paid_realizn 3643 3643 0 0.00 0 3882 6 0 0
v_min_id_clo_ord 5536 5536 0 0.00 0 1922 8 0 0
v_min_id_clo_res 1006 1006 0 0.00 0 2880 13 0 0
v_min_id_ord_sup 5450 5450 0 0.00 0 7645 8 0 0
v_min_non_paid_invoice 5982 5982 0 0.00 0 15408 23 0 0
v_min_non_paid_realizn 7078 7078 0 0.00 0 15437 22 0 0
v_our_firm 10042 10042 0 0.00 0 473 1 0 0
v_reserve_write_off 19827 19827 0 0.00 0 3669 4 0 0
x_lock_depdocs_on_canc_invoice 12120 12120 0 0.00 0 16168 122 0 0
x_lock_depdocs_on_canc_sup_ord 435 435 0 0.00 0 3495 326 0 0
x_make_qstorno_1000_1200 5536 5536 0 0.00 2 44421 1310 0 0
x_make_qstorno_1200_2000 5455 5455 0 0.00 3 115119 3706 0 0
x_make_qstorno_2100_3300 461695 461695 0 0.00 0 84484 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-12-2021 07:37:58 to 20-12-2021 07:38:02.

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
05:07:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:13 36781 8192000000 9728237984 10025398272 797605456 8.872 100 83 166 0 825977536 8270832 20101248
05:09:56 19755 8192000000 9761033760 10067648512 835788576 9.257 100 88 176 0 869039392 12754240 20496576
05:11:29 17569 8192000000 9813401776 10135420928 877671168 9.676 100 79 158 0 920216544 24889984 17655392
05:12:54 13730 8192000000 9881435104 10215043072 931754928 10.212 100 81 161 1 984413936 35361712 17297296
05:14:09 7038 8192000000 9931799120 10276167680 970782096 10.594 100 86 170 0 1041384944 53033040 17569808
05:15:19 4346 8192000000 9984476128 10340409344 1005485568 10.932 100 85 171 0 1092110752 68680816 17944368
05:16:25 3755 8192000000 9995072144 10359865344 1028416576 11.153 100 82 164 1 1115176064 70108704 16650784
05:17:33 2307 8192000000 10022917808 10392104960 1041632160 11.280 100 89 177 1 1136809664 77136656 18040848
05:18:36 1510 8192000000 10045017216 10419392512 1063937008 11.494 100 95 191 1 1160204752 75493344 20774400
05:19:39 6723 8192000000 10017050304 10400075776 1072869936 11.579 100 76 150 2 1126789552 38353472 15566144
05:20:46 3048 8192000000 9993061120 10394873856 1078194096 11.630 100 30 56 3 1115635856 31434736 6007024
05:21:51 5611 8192000000 10003953216 10405765120 1086707968 11.711 100 46 92 3 1126275568 29753072 9814528
05:23:03 3644 8192000000 10003685440 10403192832 1086613072 11.710 100 49 92 1 1124700800 26879312 11208416
05:24:08 38612 8192000000 10021636576 10409693184 1092057536 11.762 100 70 139 2 1131674128 24421104 15195488
05:25:47 7581 8192000000 10017153120 10418524160 1095105744 11.791 100 28 53 2 1127033600 26281472 5646384
05:26:57 5250 8192000000 10003601984 10407374848 1085963568 11.704 99 41 82 2 1111390880 16448000 8979312
05:28:05 11993 8192000000 9977903824 10378932224 1054808624 11.407 98 35 70 1 1091981376 29081872 8090880
05:29:19 20719 8192000000 9940537808 10339262464 945329872 10.345 88 19 35 0 968274208 19459136 3485200
05:30:41 10249 8192000000 9946145360 10343858176 1016315376 11.036 98 39 78 1 1055879696 31196928 8367392
05:31:54 2829 8192000000 9942034048 10320740352 1017592224 11.049 98 61 125 2 1043271840 11781280 13898336
05:32:58 68987 8192000000 9942739520 10343006208 997851680 10.858 97 22 44 0 1017091184 14559648 4679856
05:35:09 6176 8192000000 9979238832 10368151552 1047510064 11.337 100 36 72 0 1088893312 34033424 7349824
05:36:19 10518 8192000000 9980409296 10378514432 1065108784 11.505 100 39 78 1 1095842160 21716912 9016464
05:37:33 16268 8192000000 10000156720 10390282240 1078890464 11.637 100 56 113 0 1103275440 11397648 12987328
05:38:55 106324 8192000000 10044046672 10448416768 1094510832 11.786 100 33 67 0 1141604592 40214032 6879728
05:41:46 22604 8192000000 10115313360 10516271104 1112185120 11.953 100 52 104 1 1220982464 97653680 11143664
05:43:14 34756 8192000000 10116340176 10541047808 1119809568 12.025 100 40 80 1 1219823904 90826896 9187440
05:44:57 14808 8192000000 10149369440 10568605696 1135974928 12.178 100 49 97 1 1254414352 107140720 11298704
05:46:17 4212 8192000000 10194766752 10594074624 1139128800 12.207 100 71 142 1 1299702224 145682672 14890752
05:47:31 3894 8192000000 10214424912 10610561024 1144510960 12.258 100 80 157 0 1322298624 161293008 16494656
05:48:40 7317 8192000000 10239046368 10641440768 1153260560 12.340 100 66 127 0 1334741904 167356240 14125104
05:49:51 3554 8192000000 10285484720 10686218240 1162732368 12.429 100 82 164 1 1381015232 200317632 17965232
05:51:01 11222 8192000000 10274836000 10693251072 1167432544 12.473 100 77 151 2 1382937904 198266224 17239136
05:52:24 2762 8192000000 10325186384 10734325760 1175562048 12.549 100 83 164 2 1430973792 236820000 18591744
05:53:33 1843 8192000000 10353997904 10797187072 1185210128 12.639 100 90 178 0 1458444768 253881728 19352912
05:54:39 2257 8192000000 10365001568 10772623360 1187432912 12.659 100 84 164 1 1472558576 265642128 19483536
05:55:47 5594 8192000000 10389099984 10813489152 1170781264 12.504 99 53 103 1 1493603824 310262016 12560544
05:57:15 10080 8192000000 10373398144 10795487232 1131393312 12.134 96 49 97 1 1459566416 315780656 12392448
05:58:27 6372 8192000000 10387128992 10799247360 1152796048 12.336 99 57 114 1 1483555808 315976752 14783008
05:59:43 8114 8192000000 10455833408 10868469760 1165660336 12.456 100 56 112 1 1555104448 375204304 14239808
06:00:56 10729 8192000000 10492381536 10924281856 1162512048 12.427 99 43 86 1 1589935872 415784912 11638912
06:02:35 15438 8192000000 10423562288 10858950656 1151912272 12.327 100 31 60 1 1507441600 347592016 7937312
06:04:00 17491 8192000000 10437244432 10869198848 1126230032 12.086 100 28 56 0 1532041744 397503216 8308496
06:05:34 29732 8192000000 10331467536 10835337216 1108023744 11.914 100 25 50 0 1487854736 372859632 6971360
06:07:05 47890 8192000000 10050777520 10466336768 1098115648 11.820 98 33 66 2 1124906416 20416096 6374672
06:08:54 63013 8192000000 10083622032 10520489984 1085289248 11.698 97 40 79 2 1114410224 20459152 8661824
06:11:42 63288 8192000000 10102825120 10530439168 1109004384 11.923 98 53 105 1 1161988752 41977712 11006656
06:13:55 22864 8192000000 10075238272 10504970240 1109988288 11.932 99 15 30 0 1168139584 55146800 3004496
06:15:18 21990 8192000000 10086650176 10512187392 1123795728 12.063 100 32 64 1 1183210096 53058912 6355456
06:16:41 14314 8192000000 10135010512 10554679296 1122712576 12.053 99 72 142 0 1224517168 85177712 16626880
06:18:04 24010 8192000000 10095983088 10540679168 1130167664 12.123 99 12 24 0 1193863744 61191712 2504368
06:19:37 7854 8192000000 10085797360 10565640192 1137217504 12.189 100 10 20 0 1196376352 57258224 1900624
06:20:48 43190 8192000000 10081537792 10530103296 1138352848 12.200 99 9 18 0 1194676480 54125232 2198400
06:22:34 70751 8192000000 10194723904 10643492864 1145181504 12.264 99 19 38 0 1258039120 108648336 4209280
06:24:57 61772 8192000000 10092942480 10637688832 1139926704 12.215 100 9 18 0 1238239680 96413568 1899408
06:27:00 39498 8192000000 10099004480 10523332608 1140326928 12.219 99 23 46 0 1181154640 36395632 4432080
06:28:40 39932 8192000000 10108415584 10547363840 1109630960 11.929 96 36 72 1 1175108576 58243152 7234464
06:30:27 27817 8192000000 10084548176 10525184000 1147933040 12.290 100 24 48 1 1194670224 42035360 4701824
06:31:55 17836 8192000000 10115403856 10541215744 1145654720 12.269 100 41 82 1 1207797952 53968896 8174336
06:33:13 78399 8192000000 10091285200 10574041088 1101156592 11.849 96 8 16 0 1197726688 94577952 1992144
06:35:42 94448 8192000000 10053568704 10488963072 1126820080 12.091 99 14 29 0 1164474768 34739968 2914720
06:38:21 43843 8192000000 10097981328 10526478336 1130007280 12.121 99 46 92 0 1181145536 42031248 9107008
06:40:06 60355 8192000000 10131101936 10578579456 1144134240 12.254 100 18 36 0 1251749312 103307056 4308016
06:42:08 47476 8192000000 10080006240 10549071872 1117828000 12.006 98 16 33 0 1168254272 46821216 3605056
06:44:02 58885 8192000000 10065714528 10556846080 1126370176 12.087 99 9 18 0 1189060896 60799840 1890880
06:46:07 44056 8192000000 10051326016 10493882368 1096009792 11.800 96 14 28 0 1128172928 29001952 3161184
06:47:58 71925 8192000000 10107875632 10560999424 1080093104 11.648 95 16 32 0 1142393008 59186272 3113632
06:50:13 75079 8192000000 10050610080 10520403968 1090439904 11.747 96 12 24 0 1145204832 52269280 2495648
06:52:31 49790 8192000000 10121944928 10561085440 1138886208 12.205 100 36 68 2 1204183936 57748160 7549568
06:54:21 73823 8192000000 10103980512 10565038080 1124246912 12.067 98 15 30 0 1206109152 78837104 3025136
06:56:39 78473 8192000000 10073720608 10521686016 1085958896 11.704 95 6 12 0 1122616656 35481680 1176080
06:58:59 108243 8192000000 10078094624 10507587584 1116313232 11.992 98 37 74 0 1159382080 35264864 7803984
07:01:48 34381 8192000000 10108060960 10556899328 1143038272 12.244 100 37 73 0 1204160240 53891968 7230000
07:03:26 78921 8192000000 10105204496 10561466368 1106573248 11.900 96 17 34 1 1160316368 49544560 4198560
07:05:51 89945 8192000000 10137527696 10593468416 1138610112 12.202 100 15 30 1 1205829216 63458144 3760960
07:08:25 46771 8192000000 10177110800 10621644800 1143284576 12.246 99 21 42 0 1249501216 101510368 4706272
07:10:12 99742 8192000000 10099907232 10595581952 1150612928 12.315 100 21 42 1 1258902384 103171984 5117472
07:13:10 61976 8192000000 10109895888 10558963712 1111666032 11.948 97 23 46 0 1201008816 84637792 4704992
07:16:02 15736 8192000000 10097214768 10521935872 1125234880 12.076 98 52 103 2 1159843616 24862432 9746304
07:17:24 53418 8192000000 10080002704 10546630656 1095176672 11.792 96 25 50 0 1176921696 77152448 4592576
07:20:47 83602 8192000000 10063667312 10538323968 1117883888 12.007 98 23 46 1 1173549488 50985792 4679808
07:23:48 75360 8192000000 10079700656 10561175552 1101602144 11.853 96 11 22 0 1167399408 63239616 2557648
07:26:10 78825 8192000000 10059060896 10552426496 1062771104 11.483 94 11 22 0 1113547872 48879568 1897200
07:28:38 109127 8192000000 10103562560 10557419520 1111012976 11.942 98 10 20 0 1193524896 80284208 2227712
07:31:43 116930 8192000000 10110837920 10572505088 1084958048 11.695 95 14 28 0 1166393312 78248864 3186400
07:34:44 66799 8192000000 10083779760 10543759360 1071796928 11.569 96 9 18 0 1115312528 41812160 1703440
07:36:51 27958 8192000000 9043076784 9298173952 195412752 2.329 18 1 2 0 208041280 12574688 53840

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-12-2021 07:38:04 to 20-12-2021 07:38:19.

Exceptions occured during test was in run

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

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 20-12-2021 07:38:20 to 20-12-2021 07:38:22.

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          236037
MON$OLDEST_ACTIVE               236038
MON$OLDEST_SNAPSHOT             236038
MON$NEXT_TRANSACTION            236038
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                       2064608
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             804
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2692 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2692 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2692 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2692 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 ---
:102: 
:103: mscs-test-161.budzdorov.ru    Mon Dec 20 07:37:22 2021
:104:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:105: 
--- end of diff output ---

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

20.12.21 07:38:23. 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-12-20 07:38:25 No severe PSQL-related problems occured
20.12.21 07:38:27. End of report.