Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 05.11.2021 13: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 15964040 kB
MemAvailable 30852752 kB
Buffers 0 kB
Cached 15777624 kB
SwapCached 2480 kB
Command: fdisk -l
WARNING: fdisk GPT support is currently new, and therefore in an experimental phase. Use at your own discretion.
Disk /dev/sda: 599.9 GB, 599932581888 bytes, 1171743324 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk label type: gpt
Disk identifier: 919DACA4-2F35-4CBB-BAA7-2A8CD8F7168C
# Start End Size Type Name
1 2048 411647 200M EFI System EFI System Partition
2 411648 2508799 1G Microsoft basic
3 2508800 1171742719 557.5G Linux LVM
Disk /dev/mapper/centos_mscs--test--161-root: 53.7 GB, 53687091200 bytes, 104857600 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/mapper/centos_mscs--test--161-swap: 16.8 GB, 16844324864 bytes, 32899072 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes
Disk /dev/sdb: 536 MB, 536870912 bytes, 1048576 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 512 bytes / 512 bytes
Disk label type: dos
Disk identifier: 0x00000046
Device Boot Start End Blocks Id System
/dev/sdb1 63 514079 257008+ c W95 FAT32 (LBA)
Disk /dev/mapper/centos_mscs--test--161-home: 528.1 GB, 528109010944 bytes, 1031462912 sectors
Units = sectors of 1 * 512 = 512 bytes
Sector size (logical/physical): 512 bytes / 512 bytes
I/O size (minimum/optimal): 262144 bytes / 524288 bytes

Server and database settinfs

SERVER_MODE                     SuperServer 4.0.1
DB_NAME                         /home/bases/oltp40-etalone.fdb
FORCED_WRITES                   ON
SWEEP_INT                       0
PAGE_BUFFERS                    1000000
PAGE_SIZE                       8192


Test configuration settings

param=actions_todo_before_reconnect, val=300
param=create_with_compound_columns_order, val=most_selective_first
param=create_with_debug_objects, val=1
param=create_with_fw, val=sync
param=create_with_separate_qdistr_idx, val=0
param=create_with_split_heavy_tabs, val=1
param=create_with_sweep, val=0
param=dbnm, val=/home/bases/oltp40-etalone.fdb
param=detailed_info, val=0
param=etalon_dbnm, val=/home/bases/oltp40-etalone.fd0
param=expected_workers, val=100
param=fbc, val=/opt/fb40/bin
param=file_name_this_host_info, val=bz_cpu24_ram32
param=file_name_with_test_params, val=regular
param=gather_hardware_info, val=1
param=halt_test_on_errors, val=/CK/
param=host, val=localhost
param=init_buff, val=10000
param=init_docs, val=0
param=is_embed, val=0
param=make_html, val=1
param=max_cps, val=25
param=mon_query_interval, val=60
param=mon_query_role, val=tmp$oemul$worker
param=mon_unit_list, val=//
param=mon_unit_perf, val=2
param=mon_usr_prefix, val=tmp$oemul$user_
param=no_auto_undo, val=1
param=port, val=3400
param=pwd, val=masterkey
param=qmism_verify_bitset, val=1
param=recalc_idx_min_interval, val=30
param=remove_isql_logs, val=if_no_severe_errors
param=replace_instance, val=1
param=report_compress_cmd, val=/usr/bin/7za
param=results_storage_fbk, val=/home/bases/oltp40-results-storage.fbk
param=run_db_statistics, val=0
param=run_db_validation, val=0
param=separate_workers, val=1
param=sleep_ddl, val=./oltp_sleepUDF_nix.sql
param=sleep_max, val=0
param=test_intervals, val=60
param=test_time, val=120
param=tmpdir, val=/var/tmp/logs-oltp40
param=unit_selection_method, val=random
param=update_conflict_percent, val=0
param=used_in_replication, val=0
param=use_mtee, val=0
param=usr, val=SYSDBA
param=wait_after_create, val=0
param=wait_for_copy, val=1
param=wait_if_not_exists, val=0
param=warm_time, val=30
param=working_mode, val=small_03
Custom name of interactive SQL utility: parameter: 'clu', value: |isql|

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-05 13:37:07
DTS_END                         2021-11-05 13:37:07.7900
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/49534
TRN_ID                          238589
ATT_ID                          695
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: 9052.64 24090 1086317
customer order: creation 174.71 391 20965
customer order: refuse 14.67 316 1760
order to supplier: creation 47.57 1311 5708
order to supplier: removal 3.34 128886 401
invoice (draft): creation 47.11 3236 5653
invoice (draft): removal 52.49 1089 6299
invoice accept: apply 41.58 20842 4989
invoice accept: cancel 46.72 925 5606
customer reserve: creation 3779.10 208 453492
customer reserve: removal 4543.12 72 545174
realization accept: apply 85.58 187 10270
realization accept: cancel 87.10 127 10452
payment from customer: creation 59.13 97 7095
payment from customer: removal 5.77 109 692
payment to supplier: creation 49.93 118 5991
payment to supplier: removal 3.42 94 410
service: total inventory turnovers 3.93 3675 471
service: total monetary turnovers 6.46 616 775
service: aggregate perf. data 0.92 17615 110
service: refresh index statistics 0.03 301884 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 05-11-2021 13:38:45 to 05-11-2021 13:38:47.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7629
TEST_TIME 2 7293
TEST_TIME 3 6910
TEST_TIME 4 6605
TEST_TIME 5 6117
TEST_TIME 6 5913
TEST_TIME 7 6030
TEST_TIME 8 6875
TEST_TIME 9 6871
TEST_TIME 10 6784
TEST_TIME 11 6896
TEST_TIME 12 7082
TEST_TIME 13 7225
TEST_TIME 14 7595
TEST_TIME 15 7622
TEST_TIME 16 7554
TEST_TIME 17 7629
TEST_TIME 18 7887
TEST_TIME 19 8213
TEST_TIME 20 8126
TEST_TIME 21 8899
TEST_TIME 22 9091
TEST_TIME 23 9396
TEST_TIME 24 10398
TEST_TIME 25 11948
TEST_TIME 26 12091
TEST_TIME 27 11939
TEST_TIME 28 11789
TEST_TIME 29 11519
TEST_TIME 30 11414
TEST_TIME 31 11158
TEST_TIME 32 10938
TEST_TIME 33 10710
TEST_TIME 34 11048
TEST_TIME 35 10876
TEST_TIME 36 10672
TEST_TIME 37 10725
TEST_TIME 38 10721
TEST_TIME 39 10531
TEST_TIME 40 10453
TEST_TIME 41 10319
TEST_TIME 42 10232
TEST_TIME 43 10151
TEST_TIME 44 10079
TEST_TIME 45 10009
TEST_TIME 46 10022
TEST_TIME 47 10000
TEST_TIME 48 9889
TEST_TIME 49 9905
TEST_TIME 50 9945
TEST_TIME 51 9915
TEST_TIME 52 9958
TEST_TIME 53 10062
TEST_TIME 54 10088
TEST_TIME 55 10000
TEST_TIME 56 9965
TEST_TIME 57 9908
TEST_TIME 58 9765
TEST_TIME 59 9655
TEST_TIME 60 9717
TEST_TIME 61 9651
TEST_TIME 62 9617
TEST_TIME 63 9586
TEST_TIME 64 9643
TEST_TIME 65 9626
TEST_TIME 66 9567
TEST_TIME 67 9578
TEST_TIME 68 9588
TEST_TIME 69 9688
TEST_TIME 70 9658
TEST_TIME 71 9644
TEST_TIME 72 9625
TEST_TIME 73 9698
TEST_TIME 74 9647
TEST_TIME 75 9736
TEST_TIME 76 9690
TEST_TIME 77 9569
TEST_TIME 78 9581
TEST_TIME 79 9546
TEST_TIME 80 9509
TEST_TIME 81 9430
TEST_TIME 82 9386
TEST_TIME 83 9351
TEST_TIME 84 9324
TEST_TIME 85 9388
TEST_TIME 86 9355
TEST_TIME 87 9359
TEST_TIME 88 9325
TEST_TIME 89 9363
TEST_TIME 90 9373
TEST_TIME 91 9410
TEST_TIME 92 9444
TEST_TIME 93 9414
TEST_TIME 94 9428
TEST_TIME 95 9499
TEST_TIME 96 9488
TEST_TIME 97 9429
TEST_TIME 98 9351
TEST_TIME 99 9339
TEST_TIME 100 9319
TEST_TIME 101 9267
TEST_TIME 102 9239
TEST_TIME 103 9262
TEST_TIME 104 9276
TEST_TIME 105 9241
TEST_TIME 106 9250
TEST_TIME 107 9255
TEST_TIME 108 9218
TEST_TIME 109 9272
TEST_TIME 110 9350
TEST_TIME 111 9329
TEST_TIME 112 9309
TEST_TIME 113 9306
TEST_TIME 114 9293
TEST_TIME 115 9238
TEST_TIME 116 9210
TEST_TIME 117 9162
TEST_TIME 118 9154
TEST_TIME 119 9135
TEST_TIME 120 9062

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 05-11-2021 13:38:49 to 05-11-2021 13:38:52.

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 1505975 1505975 0 0.00 0 28835 8 0 0
doc_list_biud 1505975 1505975 0 0.00 0 61766 33 0 0
sp_add_invoice_to_stock 5097 4989 108 2.11 40 281041 20843 0 108
sp_cancel_adding_invoice 5606 5606 0 0.00 4 55230 925 0 0
sp_cancel_client_order 1760 1760 0 0.00 5 7714 317 0 0
sp_cancel_customer_reserve 545174 545174 0 0.00 1 61767 73 0 0
sp_cancel_pay_from_customer 692 692 0 0.00 3 7797 109 0 0
sp_cancel_pay_to_supplier 410 410 0 0.00 3 4760 94 0 0
sp_cancel_supplier_invoice 6305 6299 6 0.09 1 53498 1089 0 6
sp_cancel_supplier_order 401 401 0 0.00 9 1419509 128886 0 0
sp_cancel_write_off 10452 10452 0 0.00 1 8654 127 0 0
sp_client_order 20965 20965 0 0.00 4 26063 392 0 0
sp_customer_reserve 453492 453492 0 0.00 0 75306 209 0 0
sp_fill_shopping_cart 32326 32326 0 0.00 1 16625 44 0 0
sp_fill_shopping_cart_clo_res 453486 453486 0 0.00 0 14177 16 0 0
sp_get_clo_for_invoice 4974 4974 0 0.00 0 28985 330 0 0
sp_kill_cost_storno 17160 17160 0 0.00 0 19310 66 0 0
sp_kill_qstorno_ret_qs2qd 1107500 1107500 0 0.00 0 61762 40 0 0
sp_kill_qty_storno 584932 584932 0 0.00 0 61766 85 0 0
sp_lock_selected_doc 21087 21087 0 0.00 0 13095 7 0 0
sp_make_cost_storno 28326 28326 0 0.00 0 26250 55 0 0
sp_multiply_rows_for_pdistr 28326 28326 0 0.00 0 9022 32 0 0
sp_multiply_rows_for_qdistr 20965 20965 0 0.00 0 15060 212 0 0
sp_pay_from_customer 7095 7095 0 0.00 4 12477 98 0 0
sp_pay_to_supplier 5991 5991 0 0.00 5 12936 119 0 0
sp_payment_common 13086 13086 0 0.00 1 7711 19 0 0
sp_qd_handle_on_cancel_clo 1760 1760 0 0.00 0 7683 286 0 0
sp_qd_handle_on_invoice_upd_sts 10576 10576 0 0.00 0 34133 401 0 0
sp_qd_handle_on_reserve_upd_sts 10270 10270 0 0.00 0 9132 55 0 0
sp_reserve_write_off 10270 10270 0 0.00 7 18340 187 0 0
sp_supplier_invoice 5653 5653 0 0.00 7 83483 3237 0 0
sp_supplier_order 5708 5708 0 0.00 5 20727 1312 0 0
srv_aggregate_perf_data 110 110 0 0.00 2675 214286 17615 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 1453 146896 43465 0 0
srv_find_qd_qs_mism 1038362 1038362 0 0.00 0 18498 7 0 0
srv_make_invnt_saldo 471 471 0 0.00 35 108685 3675 0 0
srv_make_money_saldo 775 775 0 0.00 1 12390 617 0 0
srv_recalc_idx_stat 4 4 0 0.00 153307 495339 301884 0 0
t$perf-abend:sp_add_invoice_to_stock 108 108 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4989 4989 0 0.00 0 24380 203 0 0
t$perf-norm:sp_cancel_adding_invoice 414 414 0 0.00 0 8684 129 0 0
t$perf-norm:sp_cancel_client_order 1760 1760 0 0.00 0 3424 12 0 0
t$perf-norm:sp_cancel_customer_reserve 728 728 0 0.00 0 2386 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 692 692 0 0.00 0 1612 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 410 410 0 0.00 0 6107 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 442 442 0 0.00 0 1352 19 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 26807 1664 0 0
t$perf-norm:sp_cancel_write_off 1000 1000 0 0.00 0 1849 10 0 0
t$perf-norm:sp_client_order 20965 20965 0 0.00 0 12475 18 0 0
t$perf-norm:sp_customer_reserve 1010 1010 0 0.00 0 2294 8 0 0
t$perf-norm:sp_pay_from_customer 7095 7095 0 0.00 0 11971 13 0 0
t$perf-norm:sp_pay_to_supplier 5991 5991 0 0.00 0 11627 12 0 0
t$perf-norm:sp_reserve_write_off 10270 10270 0 0.00 0 12708 11 0 0
t$perf-norm:sp_supplier_invoice 5653 5653 0 0.00 0 5651 19 0 0
t$perf-norm:sp_supplier_order 5708 5708 0 0.00 0 14990 18 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 612 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 64 3 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 804 12 0 0
t$perf-norm:srv_make_money_saldo 775 775 0 0.00 0 1494 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 17 7 0 0
v_add_invoice_to_stock 10156 9940 216 2.12 0 6220 17 0 216
v_all_customers 38126 38126 0 0.00 0 22 1 0 0
v_all_suppliers 31612 31612 0 0.00 0 17 1 0 0
v_all_wares 41930 41930 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 828 828 0 0.00 0 1295 9 0 0
v_cancel_client_order 3520 3520 0 0.00 0 3099 8 0 0
v_cancel_customer_prepayment 1384 1384 0 0.00 0 3774 8 0 0
v_cancel_customer_reserve 1456 1456 0 0.00 0 1099 4 0 0
v_cancel_payment_to_supplier 820 820 0 0.00 0 1929 9 0 0
v_cancel_supplier_invoice 896 884 12 1.33 0 1985 15 0 12
v_cancel_supplier_order 802 802 0 0.00 0 515 5 0 0
v_cancel_write_off 2000 2000 0 0.00 0 2482 16 0 0
v_max_id_clo_ord 5708 5708 0 0.00 0 244 1 0 0
v_max_id_clo_res 1010 1010 0 0.00 0 2077 14 0 0
v_max_id_ord_sup 5653 5653 0 0.00 0 7413 8 0 0
v_max_non_paid_invoice 1546 1546 0 0.00 0 975 3 0 0
v_max_non_paid_realizn 3665 3665 0 0.00 0 627 2 0 0
v_min_id_clo_ord 5708 5708 0 0.00 0 1646 5 0 0
v_min_id_clo_res 1010 1010 0 0.00 0 3762 13 0 0
v_min_id_ord_sup 5653 5653 0 0.00 0 6603 10 0 0
v_min_non_paid_invoice 5991 5991 0 0.00 0 7705 12 0 0
v_min_non_paid_realizn 7095 7095 0 0.00 0 5605 12 0 0
v_our_firm 10664 10664 0 0.00 0 19 1 0 0
v_reserve_write_off 20540 20540 0 0.00 0 3215 6 0 0
x_lock_depdocs_on_canc_invoice 11463 11463 0 0.00 0 20564 117 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 11330 371 0 0
x_make_qstorno_1000_1200 5708 5708 0 0.00 2 20664 1229 0 0
x_make_qstorno_1200_2000 5653 5653 0 0.00 4 78030 3074 0 0
x_make_qstorno_2100_3300 453492 453492 0 0.00 0 75305 189 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 05-11-2021 13:38:53 to 05-11-2021 13:38:57.

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
11:07:11 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:11 31823 8192000000 9730458736 10030690304 804254384 8.939 100 91 181 0 834611472 8547792 21809296
11:09:45 20986 8192000000 9773038144 10082414592 843633520 9.336 100 93 186 0 884240032 18340304 22266208
11:11:10 14254 8192000000 9817067024 10136657920 872465456 9.625 100 82 165 0 918907344 27340928 19100960
11:12:32 13906 8192000000 9870835424 10204078080 920713088 10.103 100 73 146 0 976020880 39343168 15964624
11:13:52 4986 8192000000 9924632912 10272497664 967753808 10.565 100 81 161 1 1038655728 54113504 16788416
11:15:00 5528 8192000000 9988373312 10346758144 1001406736 10.892 100 83 163 1 1107908080 89318912 17182432
11:16:07 3940 8192000000 9999017936 10359562240 1025540992 11.125 100 91 176 0 1102693088 59229936 17922160
11:17:13 2337 8192000000 10008207600 10374930432 1046978656 11.332 100 86 174 0 1116161616 51363824 17819136
11:18:16 2005 8192000000 10019202992 10389983232 1058567984 11.443 100 94 186 1 1134528192 56699520 19260688
11:19:20 5493 8192000000 9974951408 10366750720 1061196736 11.468 100 36 72 2 1102532192 34458704 6876752
11:20:28 6757 8192000000 9996726432 10382118912 1071375296 11.565 100 38 75 0 1111168976 32475792 7317888
11:21:37 5970 8192000000 9991643712 10385145856 1081486016 11.662 100 34 68 0 1113127072 24474512 7166544
11:23:16 676 8192000000 9989334032 10388893696 1083634944 11.682 100 32 63 0 1099947216 8084256 8228016
11:24:18 7100 8192000000 10014444928 10414235648 1094877472 11.789 100 42 83 1 1119527504 15944176 8705856
11:25:28 6207 8192000000 10028993728 10426310656 1096809648 11.807 100 52 103 5 1125758080 18380768 10567664
11:26:36 8548 8192000000 10003590176 10407727104 1089582112 11.739 100 41 74 1 1122027360 24342384 8102864
11:27:46 22178 8192000000 9996948352 10407141376 1058631696 11.443 97 21 42 0 1102205760 38584880 4989184
11:29:11 6011 8192000000 9958937024 10345824256 1012580720 11.000 96 51 98 4 1049800016 25909744 11309552
11:30:21 7591 8192000000 9942397760 10324553728 1003810320 10.915 98 31 62 1 1051289824 40359904 7119600
11:31:29 58054 8192000000 9934670816 10332106752 1008823360 10.964 98 28 56 0 1036871744 21966256 6082128
11:33:29 4790 8192000000 9955586784 10342563840 1030922704 11.177 99 38 76 1 1050904112 10816256 9165152
11:34:38 8454 8192000000 9994481952 10395344896 1067565744 11.529 100 31 62 0 1100429472 25523888 7339840
11:35:48 9049 8192000000 9982954384 10384891904 1070564864 11.557 100 29 52 4 1095327456 18729328 6033264
11:36:57 46084 8192000000 10018147568 10409717760 1090528160 11.748 100 40 79 0 1118466256 19771472 8166624
11:38:55 3682 8192000000 10071009328 10470195200 1109906736 11.932 100 51 101 1 1183937552 63323344 10707472
11:40:04 17435 8192000000 10100151408 10503909376 1125465712 12.079 100 55 110 0 1205448000 68312880 11669408
11:41:26 1496 8192000000 10147818896 10549075968 1139770272 12.213 100 67 135 3 1254656064 100333920 14551872
11:42:32 9937 8192000000 10178851568 10582642688 1150839600 12.317 100 54 105 0 1284447280 122537904 11069776
11:43:48 7104 8192000000 10221900160 10627022848 1165542816 12.455 100 51 101 0 1326490976 149674032 11274128
11:45:01 7241 8192000000 10280289472 10688831488 1176565792 12.558 100 68 136 0 1374815904 183555632 14694480
11:46:10 4216 8192000000 10296131040 10707456000 1177768992 12.569 99 60 120 1 1395247152 204287216 13190944
11:47:22 4462 8192000000 10329170176 10734333952 1182825648 12.617 99 74 150 1 1424691696 224938384 16927664
11:48:29 3014 8192000000 10408297872 10818064384 1195443840 12.734 100 78 154 4 1511053584 298229280 17380464
11:49:38 1442 8192000000 10403073168 10825699328 1179064496 12.581 100 73 145 0 1502742208 306498000 17179712
11:50:49 2500 8192000000 10453647312 10870153216 1186010496 12.646 99 82 164 0 1551523376 346979008 18533872
11:51:58 3175 8192000000 10479967216 10885337088 1194905904 12.729 100 84 167 1 1574148688 359191056 20051728
11:53:08 3744 8192000000 10518854720 10924515328 1197863840 12.756 100 88 176 0 1619706256 400447888 21394528
11:54:18 2221 8192000000 10558588576 10963193856 1171660224 12.512 99 84 168 1 1647659744 455766592 20232928
11:55:25 2107 8192000000 10620184512 11024715776 1178150320 12.573 99 93 184 1 1715699728 514995024 22554384
11:56:32 8368 8192000000 10597405600 11028824064 1179618976 12.587 100 45 86 5 1699352768 507982672 11751120
11:57:44 1456 8192000000 10628977968 11035537408 1159245040 12.396 100 83 164 0 1720721168 541629632 19846496
11:58:48 11169 8192000000 10543807984 10997882880 1147633408 12.287 100 42 84 0 1657895072 498767632 11494032
12:00:02 24637 8192000000 10532654768 11021058048 1139234720 12.208 100 32 64 0 1664531056 515768880 9527456
12:01:28 33683 8192000000 10412812160 10978897920 1143921216 12.252 100 25 50 1 1583647280 432288816 7437248
12:03:05 146896 8192000000 10043150528 10495959040 987601168 10.758 88 6 12 0 1023049648 34220080 1228400
12:06:46 25188 8192000000 10140315072 10563121152 1120220304 12.029 100 32 59 1 1222195520 94808240 7166976
12:08:16 132622 8192000000 10201905136 10652049408 1139023280 12.206 100 34 68 1 1277012368 130464384 7524704
12:11:30 34051 8192000000 10073649696 10512322560 1098227984 11.821 96 27 52 1 1176845840 72540448 6077408
12:13:07 55309 8192000000 10138799056 10583752704 1136449456 12.182 100 40 79 0 1232993248 88198592 8345200
12:15:09 42126 8192000000 10092102128 10560753664 1108305488 11.916 97 27 54 0 1182529680 68569872 5654320
12:16:57 42484 8192000000 10093755088 10546028544 1103737168 11.873 97 24 47 1 1183928832 75185888 5005776
12:18:41 34236 8192000000 10076265248 10514268160 1105539920 11.890 97 28 57 0 1183010736 71010320 6460496
12:20:20 50928 8192000000 10090427552 10559078400 1127261008 12.096 99 9 18 0 1179373504 50089312 2023184
12:22:13 39974 8192000000 10067749328 10514341888 1135604752 12.174 100 25 52 0 1203570432 62391936 5573744
12:23:53 83005 8192000000 10091480256 10569510912 1129301856 12.115 99 8 16 0 1171068672 39472352 2294464
12:26:18 47128 8192000000 10128889472 10582028288 1142554800 12.240 99 26 52 0 1211073312 62897472 5621040
12:28:11 53186 8192000000 10132044048 10569986048 1117370880 12.002 97 27 54 0 1204810960 81858432 5581648
12:30:05 76443 8192000000 10109844192 10595618816 1132101952 12.141 99 13 26 0 1222129568 87351248 2676368
12:32:26 19245 8192000000 10076672304 10499788800 1127354912 12.096 100 42 83 2 1156955216 21374368 8225936
12:33:50 29181 8192000000 10104564416 10528727040 1121255584 12.039 99 37 73 0 1181210080 52948208 7006288
12:35:21 118893 8192000000 10116154064 10574462976 1112380096 11.955 98 40 80 0 1226763344 105808352 8574896
12:38:24 61175 8192000000 10077666272 10540195840 1117806016 12.006 98 8 16 0 1167970544 48622832 1541696
12:40:26 31717 8192000000 10107805984 10582712320 1130389440 12.125 99 14 28 1 1191661040 58795584 2476016
12:41:57 63147 8192000000 10069880288 10514354176 1089607136 11.739 95 13 26 0 1132183200 40371136 2204928
12:44:01 72124 8192000000 10075240752 10553970688 1093003504 11.771 97 26 52 0 1196965680 97565984 6396192
12:46:14 51782 8192000000 10069812976 10567376896 1108458736 11.918 98 34 67 0 1194785360 78431360 7895264
12:48:08 26432 8192000000 10089568992 10516897792 1111633408 11.948 99 44 88 1 1160835248 40382448 8819392
12:49:36 18422 8192000000 10084075056 10513334272 1082958368 11.676 96 43 86 0 1160810144 69329536 8522240
12:50:58 54095 8192000000 10086763808 10513784832 1073182032 11.582 95 22 44 0 1103102944 25703360 4217552
12:52:53 79028 8192000000 10106161760 10569564160 1123231920 12.058 100 18 36 1 1187747936 60864848 3651168
12:55:46 24720 8192000000 10059353792 10493181952 1120486320 12.032 99 26 52 1 1163477104 37975008 5015776
12:57:13 51575 8192000000 10047278304 10500665344 1104108128 11.877 98 12 24 0 1138575536 32021232 2446176
12:59:05 52674 8192000000 10084201552 10556182528 1126220928 12.086 100 19 38 1 1193712032 63341536 4149568
13:01:16 70034 8192000000 10082161360 10589646848 1122473280 12.050 99 19 37 1 1211625168 84941232 4210656
13:03:27 34125 8192000000 10088216736 10530455552 1120316688 12.030 99 32 64 0 1175352800 48483872 6552240
13:05:13 94776 8192000000 10098209632 10555039744 1101662112 11.853 97 21 42 0 1147662528 41444256 4556160
13:07:56 81459 8192000000 10064935376 10541137920 1107747168 11.911 97 15 30 0 1184068816 73338240 2983408
13:10:18 50451 8192000000 10124497568 10600534016 1110932464 11.941 97 38 76 0 1189138768 70515168 7691136
13:12:09 80231 8192000000 10107713632 10605617152 1132640224 12.146 98 23 46 0 1214786608 77367824 4778560
13:14:36 92394 8192000000 10120268368 10575429632 1157263280 12.378 100 28 56 1 1207484528 43968624 6252624
13:17:09 50237 8192000000 10083860880 10545729536 1126302064 12.086 98 11 22 0 1187268192 58584112 2382016
13:19:00 104997 8192000000 10106096960 10637021184 1131406880 12.135 99 9 19 1 1226979824 93423632 2149312
13:21:53 48033 8192000000 10095625648 10534408192 1134274096 12.162 99 17 34 0 1187965136 49766192 3924848
13:23:55 42145 8192000000 10152284144 10619752448 1125318992 12.077 98 34 68 0 1208666112 76013712 7333408
13:25:44 44502 8192000000 10156784080 10613411840 1131177792 12.132 99 20 40 2 1227148368 91773152 4197424
13:27:31 71293 8192000000 10067471568 10503856128 1127424304 12.097 99 15 30 0 1162309440 32075232 2809904
13:29:46 16123 8192000000 10092856384 10519601152 1136244016 12.180 100 65 127 1 1172993728 23602832 13146880
13:31:05 112459 8192000000 10065994048 10542817280 1106693472 11.901 98 28 56 0 1165207840 52918432 5595936
13:33:59 42187 8192000000 10080987072 10547122176 1130901056 12.130 100 6 12 0 1186111888 54033520 1177312
13:35:42 59649 8192000000 10111113088 10619195392 1135121264 12.170 100 15 30 0 1215360080 76634944 3603872

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 05-11-2021 13:38:59 to 05-11-2021 13:39:14.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 108 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 05-11-2021 13:39:15 to 05-11-2021 13:39:17.

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          236839
MON$OLDEST_ACTIVE               238719
MON$OLDEST_SNAPSHOT             238719
MON$NEXT_TRANSACTION            238719
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                       2047392
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.2653 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2653 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2653 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2653 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 ---
:16: 
:17: mscs-test-161.budzdorov.ru    Fri Nov  5 13:37:12 2021
:18:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:19: 
:20: 
:21: mscs-test-161.budzdorov.ru    Fri Nov  5 13:37:12 2021
:22:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:23: 
:24: 
:25: mscs-test-161.budzdorov.ru    Fri Nov  5 13:37:12 2021
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
:28: 
:29: mscs-test-161.budzdorov.ru    Fri Nov  5 13:37:13 2021
:30:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:31: 
:32: 
:33: mscs-test-161.budzdorov.ru    Fri Nov  5 13:37:14 2021
:34:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:35: 
:36: 
:37: mscs-test-161.budzdorov.ru    Fri Nov  5 13:37:15 2021
:38:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:39: 
:40: 
:41: mscs-test-161.budzdorov.ru    Fri Nov  5 13:37:15 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
--- end of diff output ---

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

05.11.21 13:39:18. There are 3 logs in /var/tmp/logs-oltp40 that can be removed if no severe errors occured, see config setting 'remove_isql_logs' Following values of gdscode are considered as SEVERE: 0 Unidentified error in PSQL code: gdscode=0 within WHEN block when exception raised. 335544321 'string truncation'. Attempt to assign too long text into string variable. 335544347 'not_valid'. Validation error for column. 335544349 'no_dup'. Attempt to store duplicate value visible to active transactions. 335544558 'check_constraint'. Operation violates CHECK constraint on view or table. 335544665 'unique_key_violation'. Violation of PRIMARY or UNIQUE KEY constraint. 335544838 'foreign_key_target_doesnt_exist'. Foreign key reference target does not exist. 335544839 'foreign_key_references_present'. Foreign key references are present for the record.
FINISHED_AT ERRORS_RESULT
2021-11-05 13:39:20 No severe PSQL-related problems occured
05.11.21 13:39:21. End of report.