Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 03.08.2021 10: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 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 16274192 kB
MemAvailable 31039636 kB
Buffers 0 kB
Cached 15378280 kB
SwapCached 2572 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-08-03 10:37:05
DTS_END                         2021-08-03 10:37:05.0620
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/41120
TRN_ID                          234269
ATT_ID                          642
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: 9185.56 20738 1102267
customer order: creation 169.36 440 20323
customer order: refuse 15.00 302 1800
order to supplier: creation 43.94 1487 5273
order to supplier: removal 3.37 107112 404
invoice (draft): creation 46.01 3565 5521
invoice (draft): removal 51.87 908 6224
invoice accept: apply 41.69 19161 5003
invoice accept: cancel 47.34 835 5681
customer reserve: creation 3849.85 189 461982
customer reserve: removal 4627.69 61 555323
realization accept: apply 81.68 168 9802
realization accept: cancel 82.50 127 9900
payment from customer: creation 58.18 129 6982
payment from customer: removal 5.63 125 675
payment to supplier: creation 47.08 124 5650
payment to supplier: removal 3.72 96 446
service: total inventory turnovers 3.58 3219 430
service: total monetary turnovers 6.01 484 721
service: aggregate perf. data 1.02 14328 122
service: refresh index statistics 0.04 261898 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 03-08-2021 10:38:22 to 03-08-2021 10:38:24.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6608
TEST_TIME 2 5239
TEST_TIME 3 5306
TEST_TIME 4 4775
TEST_TIME 5 4801
TEST_TIME 6 5851
TEST_TIME 7 6073
TEST_TIME 8 6359
TEST_TIME 9 6491
TEST_TIME 10 6882
TEST_TIME 11 6895
TEST_TIME 12 7224
TEST_TIME 13 7368
TEST_TIME 14 7883
TEST_TIME 15 8166
TEST_TIME 16 8616
TEST_TIME 17 8691
TEST_TIME 18 9527
TEST_TIME 19 9427
TEST_TIME 20 9549
TEST_TIME 21 9410
TEST_TIME 22 9432
TEST_TIME 23 10148
TEST_TIME 24 11307
TEST_TIME 25 11247
TEST_TIME 26 11242
TEST_TIME 27 11019
TEST_TIME 28 10764
TEST_TIME 29 10624
TEST_TIME 30 10636
TEST_TIME 31 10545
TEST_TIME 32 10511
TEST_TIME 33 10365
TEST_TIME 34 10378
TEST_TIME 35 10309
TEST_TIME 36 10256
TEST_TIME 37 10276
TEST_TIME 38 10130
TEST_TIME 39 10033
TEST_TIME 40 9816
TEST_TIME 41 9825
TEST_TIME 42 9916
TEST_TIME 43 9923
TEST_TIME 44 9985
TEST_TIME 45 9967
TEST_TIME 46 9903
TEST_TIME 47 9959
TEST_TIME 48 10060
TEST_TIME 49 9972
TEST_TIME 50 10139
TEST_TIME 51 10152
TEST_TIME 52 10211
TEST_TIME 53 10191
TEST_TIME 54 10063
TEST_TIME 55 9938
TEST_TIME 56 9898
TEST_TIME 57 9899
TEST_TIME 58 9811
TEST_TIME 59 9719
TEST_TIME 60 9644
TEST_TIME 61 9549
TEST_TIME 62 9684
TEST_TIME 63 9625
TEST_TIME 64 9590
TEST_TIME 65 9564
TEST_TIME 66 9596
TEST_TIME 67 9671
TEST_TIME 68 9736
TEST_TIME 69 9657
TEST_TIME 70 9807
TEST_TIME 71 9806
TEST_TIME 72 9896
TEST_TIME 73 9909
TEST_TIME 74 9830
TEST_TIME 75 9775
TEST_TIME 76 9711
TEST_TIME 77 9668
TEST_TIME 78 9574
TEST_TIME 79 9527
TEST_TIME 80 9566
TEST_TIME 81 9529
TEST_TIME 82 9527
TEST_TIME 83 9495
TEST_TIME 84 9504
TEST_TIME 85 9564
TEST_TIME 86 9552
TEST_TIME 87 9547
TEST_TIME 88 9549
TEST_TIME 89 9583
TEST_TIME 90 9596
TEST_TIME 91 9630
TEST_TIME 92 9549
TEST_TIME 93 9531
TEST_TIME 94 9477
TEST_TIME 95 9449
TEST_TIME 96 9405
TEST_TIME 97 9376
TEST_TIME 98 9432
TEST_TIME 99 9443
TEST_TIME 100 9400
TEST_TIME 101 9372
TEST_TIME 102 9399
TEST_TIME 103 9493
TEST_TIME 104 9501
TEST_TIME 105 9500
TEST_TIME 106 9494
TEST_TIME 107 9519
TEST_TIME 108 9567
TEST_TIME 109 9560
TEST_TIME 110 9481
TEST_TIME 111 9472
TEST_TIME 112 9425
TEST_TIME 113 9406
TEST_TIME 114 9364
TEST_TIME 115 9319
TEST_TIME 116 9272
TEST_TIME 117 9247
TEST_TIME 118 9255
TEST_TIME 119 9262
TEST_TIME 120 9190

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 03-08-2021 10:38:26 to 03-08-2021 10:38:29.

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 1529213 1529213 0 0.00 0 23071 7 0 0
doc_list_biud 1529211 1529211 0 0.00 0 46493 28 0 0
sp_add_invoice_to_stock 5116 5003 113 2.20 32 193754 19161 0 113
sp_cancel_adding_invoice 5681 5681 0 0.00 6 77689 835 0 0
sp_cancel_client_order 1800 1800 0 0.00 4 13704 303 0 0
sp_cancel_customer_reserve 555323 555323 0 0.00 1 37704 62 0 0
sp_cancel_pay_from_customer 675 675 0 0.00 2 9084 125 0 0
sp_cancel_pay_to_supplier 446 446 0 0.00 3 6674 96 0 0
sp_cancel_supplier_invoice 6235 6224 11 0.17 2 47171 909 0 11
sp_cancel_supplier_order 404 404 0 0.00 7 1023113 107113 0 0
sp_cancel_write_off 9900 9900 0 0.00 1 9236 128 0 0
sp_client_order 20323 20323 0 0.00 4 25163 440 0 0
sp_customer_reserve 461982 461982 0 0.00 0 49423 190 0 0
sp_fill_shopping_cart 31101 31101 0 0.00 1 17502 40 0 0
sp_fill_shopping_cart_clo_res 461975 461975 0 0.00 0 15782 17 0 0
sp_get_clo_for_invoice 4994 4994 0 0.00 0 29421 375 0 0
sp_kill_cost_storno 16702 16702 0 0.00 0 7027 56 0 0
sp_kill_qstorno_ret_qs2qd 1127174 1127174 0 0.00 0 46491 34 0 0
sp_kill_qty_storno 594128 594128 0 0.00 0 46492 72 0 0
sp_lock_selected_doc 20583 20583 0 0.00 0 9019 4 0 0
sp_make_cost_storno 27428 27428 0 0.00 0 14527 55 0 0
sp_multiply_rows_for_pdistr 27428 27428 0 0.00 0 14421 33 0 0
sp_multiply_rows_for_qdistr 20323 20323 0 0.00 0 17863 246 0 0
sp_pay_from_customer 6982 6982 0 0.00 4 14653 129 0 0
sp_pay_to_supplier 5650 5650 0 0.00 5 12001 125 0 0
sp_payment_common 12631 12631 0 0.00 1 14452 28 0 0
sp_qd_handle_on_cancel_clo 1800 1800 0 0.00 0 13697 282 0 0
sp_qd_handle_on_invoice_upd_sts 10675 10675 0 0.00 0 38456 331 0 0
sp_qd_handle_on_reserve_upd_sts 9802 9802 0 0.00 0 22880 52 0 0
sp_reserve_write_off 9802 9802 0 0.00 7 22926 169 0 0
sp_supplier_invoice 5521 5521 0 0.00 6 97682 3566 0 0
sp_supplier_order 5273 5273 0 0.00 6 36604 1487 0 0
srv_aggregate_perf_data 122 122 0 0.00 2352 363812 14329 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1176 137130 43749 0 0
srv_find_qd_qs_mism 1054804 1054804 0 0.00 0 23067 6 0 0
srv_make_invnt_saldo 430 430 0 0.00 44 82140 3220 0 0
srv_make_money_saldo 721 721 0 0.00 1 13312 484 0 0
srv_recalc_idx_stat 5 5 0 0.00 153063 358060 261898 0 0
t$perf-abend:sp_add_invoice_to_stock 113 113 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5003 5003 0 0.00 0 15544 153 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 1 2961 81 0 0
t$perf-norm:sp_cancel_client_order 1800 1800 0 0.00 0 6741 14 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 373 7 0 0
t$perf-norm:sp_cancel_pay_from_customer 675 675 0 0.00 0 3148 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 446 446 0 0.00 0 1259 7 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 2757 35 0 0
t$perf-norm:sp_cancel_supplier_order 404 404 0 0.00 0 14636 1528 0 0
t$perf-norm:sp_cancel_write_off 964 964 0 0.00 0 5036 12 0 0
t$perf-norm:sp_client_order 20323 20323 0 0.00 0 17262 16 0 0
t$perf-norm:sp_customer_reserve 986 986 0 0.00 0 1483 5 0 0
t$perf-norm:sp_pay_from_customer 6982 6982 0 0.00 0 4836 7 0 0
t$perf-norm:sp_pay_to_supplier 5650 5650 0 0.00 0 4604 8 0 0
t$perf-norm:sp_reserve_write_off 9802 9802 0 0.00 0 10637 11 0 0
t$perf-norm:sp_supplier_invoice 5521 5521 0 0.00 0 5824 18 0 0
t$perf-norm:sp_supplier_order 5273 5273 0 0.00 0 13989 18 0 0
t$perf-norm:srv_aggregate_perf_data 122 122 0 0.00 0 1126 16 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1063 20 0 0
t$perf-norm:srv_make_invnt_saldo 430 430 0 0.00 0 690 7 0 0
t$perf-norm:srv_make_money_saldo 721 721 0 0.00 0 2419 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10214 9988 226 2.21 0 5025 15 0 226
v_all_customers 36702 36702 0 0.00 0 106 1 0 0
v_all_suppliers 29732 29732 0 0.00 0 17 1 0 0
v_all_wares 40646 40646 0 0.00 0 20 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 2064 9 0 0
v_cancel_client_order 3600 3600 0 0.00 0 1124 4 0 0
v_cancel_customer_prepayment 1350 1350 0 0.00 0 6942 11 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 4646 7 0 0
v_cancel_payment_to_supplier 892 892 0 0.00 0 5190 15 0 0
v_cancel_supplier_invoice 778 756 22 2.82 0 1478 16 0 22
v_cancel_supplier_order 808 808 0 0.00 0 1190 5 0 0
v_cancel_write_off 1928 1928 0 0.00 0 5233 16 0 0
v_max_id_clo_ord 5265 5265 0 0.00 0 379 1 0 0
v_max_id_clo_res 986 986 0 0.00 0 10404 22 0 0
v_max_id_ord_sup 5513 5513 0 0.00 0 3675 3 0 0
v_max_non_paid_invoice 1562 1562 0 0.00 0 78 2 0 0
v_max_non_paid_realizn 3848 3848 0 0.00 0 2802 4 0 0
v_min_id_clo_ord 5265 5265 0 0.00 0 1528 4 0 0
v_min_id_clo_res 986 986 0 0.00 0 5881 22 0 0
v_min_id_ord_sup 5513 5513 0 0.00 0 7110 8 0 0
v_min_non_paid_invoice 5650 5650 0 0.00 0 8472 15 0 0
v_min_non_paid_realizn 6981 6981 0 0.00 0 14443 27 0 0
v_our_firm 10210 10210 0 0.00 0 21 1 0 0
v_reserve_write_off 19604 19604 0 0.00 0 4645 5 0 0
x_lock_depdocs_on_canc_invoice 11527 11527 0 0.00 0 23918 106 0 0
x_lock_depdocs_on_canc_sup_ord 404 404 0 0.00 0 18753 352 0 0
x_make_qstorno_1000_1200 5273 5273 0 0.00 2 36596 1416 0 0
x_make_qstorno_1200_2000 5521 5521 0 0.00 3 97673 3414 0 0
x_make_qstorno_2100_3300 461982 461982 0 0.00 0 49421 170 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 03-08-2021 10:38:30 to 03-08-2021 10:38:34.

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:08 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:09 31682 8192000000 9730780064 10029711360 809617760 8.994 100 86 172 0 837533344 7445472 20470112
08:09:43 16214 8192000000 9771943504 10081095680 845791568 9.358 100 87 174 0 878441552 12178704 20471280
08:11:06 17790 8192000000 9803866336 10120929280 872809936 9.628 100 90 180 0 913952384 20718512 20423936
08:12:41 16080 8192000000 9881385696 10217037824 918026336 10.077 100 83 166 1 993795280 58058176 17710768
08:14:00 7505 8192000000 9925962272 10272075776 960410800 10.493 100 81 162 1 1041267808 63298784 17558224
08:15:12 4987 8192000000 9970103968 10324049920 998991072 10.869 100 77 152 1 1093025760 78595168 15439520
08:16:19 2471 8192000000 9987686144 10346262528 1026213120 11.132 100 83 165 0 1098811504 55828816 16769568
08:17:23 2708 8192000000 10036818944 10402086912 1050256448 11.363 100 96 192 3 1137146160 67080176 19809536
08:18:28 21955 8192000000 10024418096 10399727616 1058330784 11.441 100 75 150 0 1124453904 50984320 15138800
08:19:51 2339 8192000000 10011164320 10401656832 1067572864 11.529 100 45 90 0 1128872464 52069120 9230480
08:20:56 7891 8192000000 9993892400 10393088000 1077248560 11.621 100 38 75 2 1113626000 28745840 7631600
08:22:04 30791 8192000000 10010306672 10393354240 1080502704 11.652 100 67 137 2 1112013120 16648848 14861568
08:23:35 6153 8192000000 10022046160 10418888704 1089733280 11.740 100 42 84 1 1153163600 54660832 8769488
08:24:44 4829 8192000000 10008174336 10408620032 1093781376 11.779 100 51 102 2 1125125520 20213328 11130816
08:25:50 10970 8192000000 10006874144 10403590144 1088360752 11.727 99 24 48 1 1128416976 34561840 5494384
08:27:05 18602 8192000000 9986035168 10396602368 1053831360 11.397 96 20 40 0 1082309904 24793952 3684592
08:28:26 4354 8192000000 9994562240 10379476992 1071410880 11.566 100 40 78 0 1103215152 22641264 9163008
08:29:33 4693 8192000000 9984164224 10369830912 1045399664 11.317 99 55 111 1 1090912960 33565696 11947600
08:30:40 61370 8192000000 9953982944 10333937664 995056928 10.831 96 32 64 1 1022297040 19825872 7414240
08:32:43 5869 8192000000 9965029088 10350125056 1020419280 11.076 98 48 98 1 1040021520 8721536 10880704
08:33:52 6449 8192000000 9968368144 10361331712 1056719056 11.425 100 36 72 1 1088529776 23497600 8313120
08:35:01 6914 8192000000 9987913840 10398380032 1068824912 11.541 100 28 56 0 1101974192 27586048 5563232
08:36:10 30743 8192000000 9982103088 10393104384 1071048704 11.562 100 29 56 1 1093060544 16257904 5753936
08:37:45 10486 8192000000 10032483792 10422030336 1099827264 11.836 100 58 118 1 1132165408 20534544 11803600
08:39:02 17822 8192000000 10075642112 10475298816 1114556048 11.976 100 59 118 0 1177944672 51285904 12102720
08:40:23 18987 8192000000 10116319488 10517520384 1125888960 12.083 100 49 99 2 1225737792 89297136 10551696
08:41:45 57799 8192000000 10166399440 10594123776 1137764288 12.194 100 30 60 0 1267376432 123083088 6529056
08:43:58 11589 8192000000 10188390032 10596487168 1149913200 12.309 100 59 116 2 1288711360 126837808 11960352
08:45:19 5404 8192000000 10218790816 10634706944 1159906848 12.402 100 68 136 1 1328545072 153620208 15018016
08:46:30 7288 8192000000 10227343232 10640179200 1157991488 12.384 100 56 113 0 1337454496 166985504 12477504
08:47:40 1175 8192000000 10247481024 10651480064 1167025248 12.469 100 83 165 1 1356989696 171732768 18231680
08:48:44 3278 8192000000 10321053856 10725740544 1166522880 12.464 100 81 159 1 1422842544 238663776 17655888
08:49:50 5299 8192000000 10316459680 10723299328 1153309152 12.341 100 75 150 1 1418249216 247787760 17152304
08:51:01 3415 8192000000 10368857648 10772094976 1159434880 12.398 99 72 144 0 1464183104 287347520 17400704
08:52:08 13398 8192000000 10320927376 10776047616 1162531888 12.427 100 50 97 0 1426830416 251280688 13017840
08:53:24 2965 8192000000 10312992768 10734395392 1121588752 12.042 99 77 153 1 1425059520 285810448 17660320
08:54:32 4538 8192000000 10351338208 10762797056 1119431392 12.022 99 60 120 2 1432932704 298720000 14781312
08:55:44 11875 8192000000 10278442176 10716151808 1128701424 12.109 99 42 84 0 1382801360 244033360 10066576
08:56:59 20446 8192000000 10307085552 10753544192 1145880624 12.271 100 38 76 2 1420474896 265342880 9251392
08:58:33 22073 8192000000 10362460304 10805133312 1139823232 12.214 99 30 58 2 1457448528 310301856 7323440
08:59:57 31851 8192000000 10401244096 10868805632 1139352304 12.209 100 30 59 0 1489588576 342101712 8134560
09:01:33 50173 8192000000 10108688128 10564849664 1133842656 12.158 100 12 24 0 1277491856 140722944 2926256
09:03:34 30302 8192000000 10095528176 10513002496 1121249680 12.039 98 47 94 2 1158814128 27553856 10010592
09:05:10 111595 8192000000 10096811024 10541137920 1105247808 11.887 98 22 44 0 1172225472 62252672 4724992
09:08:07 19676 8192000000 10106940224 10534957056 1100590992 11.843 98 58 116 0 1172522256 60656320 11274944
09:09:35 16704 8192000000 10049105136 10486362112 1109689344 11.929 99 37 74 1 1145921408 28656528 7575536
09:10:54 22035 8192000000 10099970256 10548621312 1128141104 12.104 100 49 98 0 1192017328 53500272 10375952
09:12:17 31209 8192000000 10091123008 10568105984 1128651968 12.109 100 11 22 2 1190057872 58810464 2595440
09:14:09 57461 8192000000 10068309088 10541637632 1135370048 12.172 100 18 36 0 1195947904 56567408 4010448
09:16:16 96983 8192000000 10115328960 10587193344 1143034240 12.244 100 24 46 1 1251262832 103065392 5163200
09:18:53 49650 8192000000 10075324976 10567778304 1115619472 11.986 98 21 42 0 1173717920 53043616 5054832
09:20:45 40723 8192000000 10095670800 10537521152 1133796192 12.157 99 20 40 1 1202077760 64031696 4249872
09:22:26 36992 8192000000 10104752528 10572292096 1135833440 12.176 99 35 71 1 1173973184 30312000 7827744
09:24:08 34371 8192000000 10120403376 10579570688 1149336496 12.303 100 39 73 3 1200436512 43077056 8022960
09:25:42 64246 8192000000 10130597456 10578845696 1117321216 12.002 97 28 56 2 1188307424 65095648 5890560
09:27:59 98450 8192000000 10087040304 10605101056 1135808208 12.176 99 16 32 1 1212237824 73123200 3306416
09:30:44 83451 8192000000 10087110736 10554150912 1131499680 12.135 99 28 56 1 1177411984 40594336 5317968
09:33:22 12708 8192000000 10106982624 10538659840 1124831936 12.073 99 46 89 1 1203625280 70223280 8570064
09:34:36 31271 8192000000 10041457584 10499645440 1112777376 11.959 98 17 34 0 1157169312 40674000 3717936
09:36:19 42133 8192000000 10068201312 10520547328 1123246448 12.058 99 22 44 1 1161341152 33261376 4833328
09:38:03 74595 8192000000 10119353440 10601230336 1132065712 12.141 100 22 44 1 1225882496 88754608 5062176
09:41:28 42188 8192000000 10068378080 10500161536 1074024656 11.590 95 24 48 0 1146526528 67373552 5128320
09:43:14 65117 8192000000 10145284848 10598506496 1126170672 12.085 99 23 46 2 1233408352 101931536 5306144
09:45:22 54550 8192000000 10095198448 10554671104 1105313840 11.888 98 16 32 1 1175268480 66498544 3456096
09:47:17 74275 8192000000 10070645424 10529116160 1103276416 11.869 98 22 44 0 1178146928 70290000 4580512
09:50:50 14053 8192000000 10060857728 10491338752 1116388432 11.993 99 45 88 4 1142101696 16288480 9424784
09:52:05 80220 8192000000 10073091872 10521403392 1112723872 11.958 98 43 86 0 1180343520 58480720 9138928
09:54:25 39735 8192000000 10051349104 10524553216 1118268496 12.011 100 6 12 0 1164494544 44836768 1389280
09:56:07 54492 8192000000 10085561312 10554744832 1102033728 11.857 98 9 18 0 1179525856 75424128 2068000
09:58:10 28826 8192000000 10008296848 10448777216 1076890656 11.618 96 12 24 0 1103852480 24254752 2707072
09:59:50 41541 8192000000 10074491664 10513518592 1110686752 11.939 99 35 70 0 1165557872 47510992 7360128
10:01:42 74032 8192000000 10083046368 10580709376 1133651152 12.156 100 11 22 0 1194528208 58352688 2524368
10:03:58 45826 8192000000 10125474784 10557386752 1121195616 12.038 98 30 60 1 1187900928 60771376 5933936
10:05:48 19600 8192000000 10111024512 10547757056 1121676240 12.043 99 34 67 0 1179995248 51409792 6909216
10:07:17 39398 8192000000 10099601856 10537197568 1145426864 12.267 100 29 58 1 1213926656 62810448 5689344
10:08:56 113434 8192000000 10079864496 10533642240 1141789472 12.232 100 35 71 0 1196494928 47791936 6913520
10:11:50 76197 8192000000 10060597344 10542854144 1138388224 12.200 99 11 23 1 1171382720 30581632 2412864
10:14:29 88236 8192000000 10123413040 10637426688 1146412384 12.276 99 14 28 1 1239054720 89713936 2928400
10:17:03 79627 8192000000 10145022736 10604552192 1116835168 11.997 96 30 58 2 1177525984 54554432 6136384
10:19:23 82599 8192000000 10187437776 10662072320 1163888656 12.440 100 18 36 0 1274914528 107342000 3683872
10:21:46 51169 8192000000 10093041296 10556108800 1149685056 12.307 99 20 40 0 1209466928 55647920 4133952
10:23:42 43887 8192000000 10156592384 10613362688 1151377376 12.322 99 25 50 1 1226181792 69779296 5025120
10:25:26 137130 8192000000 10092136912 10554187776 1099934736 11.837 95 13 26 0 1119628480 17252208 2441536
10:29:29 44755 8192000000 10053238272 10517065728 1105728176 11.892 97 21 41 1 1138671008 28774384 4168448
10:31:18 68838 8192000000 10035965296 10491375616 1092131616 11.763 97 10 20 0 1114561968 20271088 2159264
10:33:27 62244 8192000000 10107699008 10564009984 1130563088 12.127 100 17 34 1 1178803008 44546784 3693136
10:35:43 23447 8192000000 10058761152 10541682688 1055889264 11.417 94 22 44 0 1111124896 50081600 5154032

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 03-08-2021 10:38:36 to 03-08-2021 10:38:51.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 113 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 03-08-2021 10:38:52 to 03-08-2021 10:38:54.

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          233118
MON$OLDEST_ACTIVE               234539
MON$OLDEST_SNAPSHOT             234539
MON$NEXT_TRANSACTION            234539
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                       2038560
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.2556 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2556 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2556 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2556 Firebird 4.0/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 ---
:11: 
:12: mscs-test-161.budzdorov.ru    Tue Aug  3 10:37:06 2021
:13:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:14: 
:15: 
:16: mscs-test-161.budzdorov.ru    Tue Aug  3 10:37:07 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Tue Aug  3 10:37:07 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Tue Aug  3 10:37:11 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
--- end of diff output ---

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

03.08.21 10:38:55. 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-08-03 10:38:58 No severe PSQL-related problems occured
03.08.21 10:38:59. End of report.