Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 10.06.2022 13:39
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 16338168 kB
MemAvailable 31338740 kB
Buffers 0 kB
Cached 15410216 kB
SwapCached 2436 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.2
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 2022-06-10 13:37:19
DTS_END                         2022-06-10 13:37:19.1850
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/60078
TRN_ID                          209143
ATT_ID                          648
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: 8390.63 22926 1006876
customer order: creation 160.10 510 19212
customer order: refuse 13.57 419 1628
order to supplier: creation 42.43 1443 5092
order to supplier: removal 3.08 173957 369
invoice (draft): creation 43.55 3581 5226
invoice (draft): removal 47.10 1035 5652
invoice accept: apply 38.83 25662 4660
invoice accept: cancel 41.98 1172 5037
customer reserve: creation 3615.25 243 433830
customer reserve: removal 4111.63 102 493396
realization accept: apply 76.47 285 9177
realization accept: cancel 76.85 159 9222
payment from customer: creation 54.60 187 6552
payment from customer: removal 5.18 122 621
payment to supplier: creation 44.93 195 5392
payment to supplier: removal 3.32 127 398
service: total inventory turnovers 3.97 3744 476
service: total monetary turnovers 6.85 361 822
service: aggregate perf. data 0.92 16986 110
service: refresh index statistics 0.03 228229 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 10-06-2022 13:39:53 to 10-06-2022 13:39:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6976
TEST_TIME 2 6371
TEST_TIME 3 5909
TEST_TIME 4 6009
TEST_TIME 5 6025
TEST_TIME 6 5944
TEST_TIME 7 5982
TEST_TIME 8 6130
TEST_TIME 9 6044
TEST_TIME 10 6045
TEST_TIME 11 6005
TEST_TIME 12 6346
TEST_TIME 13 6358
TEST_TIME 14 6498
TEST_TIME 15 6743
TEST_TIME 16 7026
TEST_TIME 17 7101
TEST_TIME 18 7286
TEST_TIME 19 7426
TEST_TIME 20 7805
TEST_TIME 21 8149
TEST_TIME 22 8504
TEST_TIME 23 8426
TEST_TIME 24 8521
TEST_TIME 25 8350
TEST_TIME 26 8608
TEST_TIME 27 8590
TEST_TIME 28 8974
TEST_TIME 29 9619
TEST_TIME 30 9605
TEST_TIME 31 10074
TEST_TIME 32 10294
TEST_TIME 33 10326
TEST_TIME 34 10469
TEST_TIME 35 10568
TEST_TIME 36 10560
TEST_TIME 37 10366
TEST_TIME 38 10154
TEST_TIME 39 10011
TEST_TIME 40 9884
TEST_TIME 41 9853
TEST_TIME 42 9718
TEST_TIME 43 9690
TEST_TIME 44 9562
TEST_TIME 45 9479
TEST_TIME 46 9347
TEST_TIME 47 9353
TEST_TIME 48 9270
TEST_TIME 49 9150
TEST_TIME 50 9123
TEST_TIME 51 8953
TEST_TIME 52 8871
TEST_TIME 53 8811
TEST_TIME 54 8819
TEST_TIME 55 8768
TEST_TIME 56 8680
TEST_TIME 57 8646
TEST_TIME 58 8608
TEST_TIME 59 8612
TEST_TIME 60 8543
TEST_TIME 61 8502
TEST_TIME 62 8592
TEST_TIME 63 8531
TEST_TIME 64 8605
TEST_TIME 65 8603
TEST_TIME 66 8617
TEST_TIME 67 8687
TEST_TIME 68 8768
TEST_TIME 69 8906
TEST_TIME 70 8932
TEST_TIME 71 8939
TEST_TIME 72 8872
TEST_TIME 73 8856
TEST_TIME 74 8778
TEST_TIME 75 8715
TEST_TIME 76 8712
TEST_TIME 77 8646
TEST_TIME 78 8599
TEST_TIME 79 8547
TEST_TIME 80 8466
TEST_TIME 81 8448
TEST_TIME 82 8419
TEST_TIME 83 8523
TEST_TIME 84 8532
TEST_TIME 85 8587
TEST_TIME 86 8570
TEST_TIME 87 8586
TEST_TIME 88 8682
TEST_TIME 89 8656
TEST_TIME 90 8649
TEST_TIME 91 8666
TEST_TIME 92 8720
TEST_TIME 93 8771
TEST_TIME 94 8747
TEST_TIME 95 8696
TEST_TIME 96 8649
TEST_TIME 97 8609
TEST_TIME 98 8556
TEST_TIME 99 8507
TEST_TIME 100 8538
TEST_TIME 101 8481
TEST_TIME 102 8500
TEST_TIME 103 8484
TEST_TIME 104 8495
TEST_TIME 105 8552
TEST_TIME 106 8554
TEST_TIME 107 8556
TEST_TIME 108 8643
TEST_TIME 109 8615
TEST_TIME 110 8637
TEST_TIME 111 8676
TEST_TIME 112 8677
TEST_TIME 113 8617
TEST_TIME 114 8579
TEST_TIME 115 8552
TEST_TIME 116 8552
TEST_TIME 117 8505
TEST_TIME 118 8481
TEST_TIME 119 8478
TEST_TIME 120 8412

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-06-2022 13:39:57 to 10-06-2022 13:40:00.

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 1405301 1405301 0 0.00 0 29603 13 0 0
doc_list_biud 1405291 1405291 0 0.00 0 31970 42 0 0
sp_add_invoice_to_stock 4780 4660 120 2.51 63 210391 25663 0 120
sp_cancel_adding_invoice 5037 5037 0 0.00 7 68962 1172 0 0
sp_cancel_client_order 1628 1628 0 0.00 6 11714 419 0 0
sp_cancel_customer_reserve 493396 493396 0 0.00 1 29256 102 0 0
sp_cancel_pay_from_customer 621 621 0 0.00 3 5756 123 0 0
sp_cancel_pay_to_supplier 398 398 0 0.00 3 2722 127 0 0
sp_cancel_supplier_invoice 5659 5652 7 0.12 2 32005 1035 0 7
sp_cancel_supplier_order 369 369 0 0.00 7 1157855 173958 0 0
sp_cancel_write_off 9222 9222 0 0.00 2 7643 160 0 0
sp_client_order 19212 19212 0 0.00 5 27422 511 0 0
sp_customer_reserve 433830 433830 0 0.00 0 48093 243 0 0
sp_fill_shopping_cart 29522 29522 0 0.00 1 18311 89 0 0
sp_fill_shopping_cart_clo_res 433790 433790 0 0.00 0 17551 23 0 0
sp_get_clo_for_invoice 4616 4616 0 0.00 0 25449 384 0 0
sp_kill_cost_storno 15278 15278 0 0.00 0 29583 80 0 0
sp_kill_qstorno_ret_qs2qd 1002035 1002035 0 0.00 0 31965 46 0 0
sp_kill_qty_storno 529095 529095 0 0.00 0 31968 104 0 0
sp_lock_selected_doc 19095 19095 0 0.00 0 10380 7 0 0
sp_make_cost_storno 25737 25737 0 0.00 0 17160 80 0 0
sp_multiply_rows_for_pdistr 25734 25734 0 0.00 0 7866 36 0 0
sp_multiply_rows_for_qdistr 19211 19211 0 0.00 0 14324 199 0 0
sp_pay_from_customer 6552 6552 0 0.00 6 15365 188 0 0
sp_pay_to_supplier 5392 5392 0 0.00 6 17140 196 0 0
sp_payment_common 11943 11943 0 0.00 2 8283 48 0 0
sp_qd_handle_on_cancel_clo 1628 1628 0 0.00 1 11679 347 0 0
sp_qd_handle_on_invoice_upd_sts 9651 9651 0 0.00 0 21246 504 0 0
sp_qd_handle_on_reserve_upd_sts 9177 9177 0 0.00 0 27304 83 0 0
sp_reserve_write_off 9177 9177 0 0.00 8 27548 285 0 0
sp_supplier_invoice 5226 5226 0 0.00 9 84957 3582 0 0
sp_supplier_order 5092 5092 0 0.00 8 35449 1444 0 0
srv_aggregate_perf_data 110 110 0 0.00 2287 238176 16986 0 0
srv_fill_mon_cache_memory 67 67 0 0.00 787 147308 41371 0 0
srv_find_qd_qs_mism 960727 960727 0 0.00 0 19303 9 0 0
srv_make_invnt_saldo 476 476 0 0.00 67 63138 3745 0 0
srv_make_money_saldo 822 822 0 0.00 1 9652 361 0 0
srv_recalc_idx_stat 4 4 0 0.00 55500 418439 228230 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4662 4662 0 0.00 1 13341 880 0 0
t$perf-norm:sp_cancel_adding_invoice 350 350 0 0.00 1 5173 439 0 0
t$perf-norm:sp_cancel_client_order 1628 1628 0 0.00 0 3610 35 0 0
t$perf-norm:sp_cancel_customer_reserve 613 613 0 0.00 0 5402 34 0 0
t$perf-norm:sp_cancel_pay_from_customer 621 621 0 0.00 0 884 15 0 0
t$perf-norm:sp_cancel_pay_to_supplier 398 398 0 0.00 0 1983 16 0 0
t$perf-norm:sp_cancel_supplier_invoice 377 377 0 0.00 0 1902 42 0 0
t$perf-norm:sp_cancel_supplier_order 369 369 0 0.00 0 102070 6859 0 0
t$perf-norm:sp_cancel_write_off 949 949 0 0.00 0 3577 22 0 0
t$perf-norm:sp_client_order 19212 19212 0 0.00 0 9357 35 0 0
t$perf-norm:sp_customer_reserve 921 921 0 0.00 0 3654 17 0 0
t$perf-norm:sp_pay_from_customer 6552 6552 0 0.00 0 6548 24 0 0
t$perf-norm:sp_pay_to_supplier 5392 5392 0 0.00 0 10472 20 0 0
t$perf-norm:sp_reserve_write_off 9177 9177 0 0.00 0 12472 22 0 0
t$perf-norm:sp_supplier_invoice 5227 5227 0 0.00 0 5600 33 0 0
t$perf-norm:sp_supplier_order 5092 5092 0 0.00 0 5851 33 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 591 23 0 0
t$perf-norm:srv_fill_mon_cache_memory 67 67 0 0.00 0 397 8 0 0
t$perf-norm:srv_make_invnt_saldo 476 476 0 0.00 0 770 14 0 0
t$perf-norm:srv_make_money_saldo 822 822 0 0.00 0 3450 8 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 5 199 60 0 0
v_add_invoice_to_stock 9464 9224 240 2.53 0 5781 23 0 240
v_all_customers 35228 35228 0 0.00 0 112 3 0 0
v_all_suppliers 28546 28546 0 0.00 0 76 4 0 0
v_all_wares 38412 38412 0 0.00 0 62 3 0 0
v_cancel_adding_invoice 700 700 0 0.00 0 403 13 0 0
v_cancel_client_order 3256 3256 0 0.00 0 5008 12 0 0
v_cancel_customer_prepayment 1242 1242 0 0.00 0 2512 14 0 0
v_cancel_customer_reserve 1226 1226 0 0.00 0 844 9 0 0
v_cancel_payment_to_supplier 796 796 0 0.00 0 990 11 0 0
v_cancel_supplier_invoice 768 754 14 1.82 0 2278 23 0 14
v_cancel_supplier_order 738 738 0 0.00 0 4046 15 0 0
v_cancel_write_off 1898 1898 0 0.00 0 4346 27 0 0
v_max_id_clo_ord 5089 5089 0 0.00 0 418 4 0 0
v_max_id_clo_res 921 921 0 0.00 0 3376 12 0 0
v_max_id_ord_sup 5222 5222 0 0.00 0 6462 7 0 0
v_max_non_paid_invoice 1428 1428 0 0.00 0 97 7 0 0
v_max_non_paid_realizn 3415 3415 0 0.00 0 7733 9 0 0
v_min_id_clo_ord 5088 5088 0 0.00 0 2701 8 0 0
v_min_id_clo_res 921 921 0 0.00 0 601 8 0 0
v_min_id_ord_sup 5222 5222 0 0.00 0 4924 11 0 0
v_min_non_paid_invoice 5391 5391 0 0.00 0 7935 22 0 0
v_min_non_paid_realizn 6551 6551 0 0.00 0 6647 21 0 0
v_our_firm 9452 9452 0 0.00 0 110 4 0 0
v_reserve_write_off 18354 18354 0 0.00 0 4672 11 0 0
x_lock_depdocs_on_canc_invoice 10312 10312 0 0.00 0 21262 138 0 0
x_lock_depdocs_on_canc_sup_ord 369 369 0 0.00 0 4593 356 0 0
x_make_qstorno_1000_1200 5092 5092 0 0.00 3 35202 1281 0 0
x_make_qstorno_1200_2000 5226 5226 0 0.00 4 83281 3320 0 0
x_make_qstorno_2100_3300 433830 433830 0 0.00 0 43799 208 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-06-2022 13:40:01 to 10-06-2022 13:40:05.

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:23 11 8192000000 8807696576 8822579200 10421024 0.127 4 2 3 0 10640576 149088 70464
11:08:23 18529 8192000000 9700163632 9996263424 784332800 8.737 100 83 166 0 812367216 7397120 20637296
11:09:48 13919 8192000000 9751248432 10055434240 826568192 9.165 100 90 180 0 859230752 10991488 21671072
11:11:07 14361 8192000000 9787213168 10101743616 856920848 9.469 100 88 176 0 894994112 17652784 20420480
11:12:32 8795 8192000000 9857237344 10188460032 910833184 10.006 100 78 154 1 969000800 41661952 16505664
11:13:44 5981 8192000000 9900528192 10243563520 951761872 10.408 100 76 151 0 1015273056 47176992 16334192
11:14:52 3131 8192000000 9947559216 10300887040 996561856 10.845 100 81 152 0 1062254656 50980304 14712496
11:15:58 2171 8192000000 10010789840 10372358144 1019281952 11.065 100 96 190 0 1125708928 87554656 18872320
11:17:02 1630 8192000000 9991094992 10366443520 1043203136 11.295 100 88 174 1 1118623456 57892416 17527904
11:18:06 1506 8192000000 10027699760 10402856960 1051562464 11.376 100 93 179 0 1156799536 86739520 18497552
11:19:10 1369 8192000000 10040080032 10413793280 1063930256 11.494 100 95 183 0 1160303040 77395088 18977696
11:20:13 1212 8192000000 10056921456 10436767744 1071317680 11.565 100 94 183 0 1181559200 91332272 18909248
11:21:17 1221 8192000000 10060189616 10439188480 1075341568 11.603 100 98 191 0 1183197408 87570000 20285840
11:22:20 1254 8192000000 10046821520 10431422464 1077786032 11.626 100 97 190 1 1170028592 72866640 19375920
11:23:22 1125 8192000000 10040507792 10423918592 1082638864 11.673 100 91 178 1 1163622176 62069056 18914256
11:24:25 1214 8192000000 10073787872 10460430336 1084751632 11.693 100 96 192 1 1191796656 87475344 19569680
11:25:28 1295 8192000000 10087541504 10471272448 1093274032 11.774 100 96 187 0 1207806048 95811280 18720736
11:26:31 1281 8192000000 10074275344 10465779712 1094417680 11.785 100 89 172 1 1193004192 81014336 17572176
11:27:34 1287 8192000000 10062106592 10451935232 1095667200 11.797 100 96 186 1 1186694784 72100368 18927216
11:28:37 1230 8192000000 10064690816 10450231296 1080483248 11.652 100 97 188 1 1184272720 84287840 19501632
11:29:40 1427 8192000000 10035005744 10418331648 1075393056 11.604 100 97 183 1 1157574912 63604400 18577456
11:30:42 1398 8192000000 10051376928 10435325952 1082778512 11.674 100 96 192 0 1174320992 71820784 19721696
11:31:47 1251 8192000000 10027178800 10403508224 1052929072 11.389 100 95 185 0 1149855664 77114560 19812032
11:32:49 1030 8192000000 10017580256 10399268864 1057222048 11.430 100 93 177 2 1141885568 65349664 19313856
11:33:51 1187 8192000000 10014692960 10390949888 1046784464 11.330 100 96 178 0 1140395600 75986416 17624720
11:34:53 1313 8192000000 10004297216 10374225920 1041302352 11.277 100 98 190 2 1128518512 68244496 18971664
11:35:56 6124 8192000000 10011087632 10389159936 1047584704 11.338 100 64 124 0 1133289488 73624992 12079792
11:37:03 1153 8192000000 10003751696 10385342464 1047268896 11.334 100 80 148 0 1128998016 66764224 14964896
11:38:11 33687 8192000000 10023076880 10408882176 1069705680 11.549 100 68 134 0 1155220528 72367312 13147536
11:39:53 1003 8192000000 10050973440 10428481536 1069753856 11.550 99 95 185 0 1174251056 84467296 20029904
11:40:59 7196 8192000000 10057320496 10450366464 1087030656 11.714 100 55 107 0 1176096256 77900464 11165136
11:42:09 5050 8192000000 10101159824 10491994112 1103778464 11.873 100 72 141 1 1216921904 98510096 14633344
11:43:17 3940 8192000000 10101878560 10502086656 1118024128 12.008 100 62 117 1 1221397680 90536304 12837248
11:44:29 2025 8192000000 10169827568 10568101888 1132491408 12.145 100 88 166 1 1289325984 138832288 18002288
11:45:35 6229 8192000000 10197689488 10601811968 1141262464 12.227 100 75 150 1 1319689920 162120992 16306464
11:46:48 4465 8192000000 10231413296 10633936896 1147379648 12.285 100 71 142 0 1343672288 180385392 15907248
11:48:00 1780 8192000000 10260218112 10661863424 1157867536 12.383 100 85 165 0 1370547408 193498208 19181664
11:49:05 3924 8192000000 10262661168 10678808576 1171177120 12.508 100 77 147 1 1374793328 186104096 17512112
11:50:14 1837 8192000000 10291004528 10699128832 1182842272 12.617 100 80 157 1 1404855712 204185504 17827936
11:51:17 1022 8192000000 10326588800 10735722496 1187127152 12.657 100 98 191 0 1434957344 226137568 21692624
11:52:21 4971 8192000000 10373789248 10779734016 1194059040 12.721 100 84 163 1 1480326896 267175232 19092624
11:53:29 894 8192000000 10387588512 10813116416 1198520048 12.763 100 100 193 1 1499180128 278582240 22077840
11:54:32 1065 8192000000 10397746576 10807250944 1188166768 12.666 100 90 171 1 1508931440 299434256 21330416
11:55:36 849 8192000000 10415664816 10853392384 1191326000 12.696 100 90 177 0 1525741520 311320000 23095520
11:56:39 948 8192000000 10444899984 10884096000 1192544688 12.707 100 96 189 0 1552593008 337744752 22303568
11:57:42 937 8192000000 10433042944 10877599744 1207552160 12.846 100 96 173 2 1537727296 307963504 22211632
11:58:46 782 8192000000 10433069120 10883244032 1187420480 12.659 100 96 189 0 1535917136 325990112 22506544
11:59:48 3849 8192000000 10425627904 10883444736 1193501088 12.716 100 49 98 0 1539159920 333361664 12297168
12:01:07 4320 8192000000 10422148128 10889445376 1181871824 12.608 99 43 86 1 1522938672 329966160 11100688
12:02:29 9433 8192000000 10499017840 10946125824 1187317552 12.658 100 41 80 0 1596828832 398325216 11186064
12:03:52 20790 8192000000 10496053984 10936832000 1171315440 12.509 99 37 74 0 1594618720 413550512 9752768
12:05:18 20890 8192000000 10440495456 10910785536 1165505600 12.455 99 35 70 0 1571807296 397212832 9088864
12:06:42 25066 8192000000 10337077424 10829164544 1150884208 12.318 99 25 47 2 1421872560 265142288 5846064
12:08:13 18455 8192000000 10287955024 10799886336 1146554704 12.277 98 41 81 0 1390840816 234713824 9572288
12:09:45 62810 8192000000 10227857040 10748018688 1172072064 12.516 100 19 39 0 1347390192 170451168 4866960
12:11:52 50578 8192000000 10159926048 10657255424 1141678336 12.231 99 26 50 0 1270659184 122796704 6184144
12:13:57 76446 8192000000 10076123344 10519662592 1112046704 11.952 97 19 35 1 1149944832 33433136 4464992
12:16:17 2471 8192000000 10084508416 10512838656 1130352128 12.125 99 60 113 3 1177927728 35708096 11867504
12:17:20 84202 8192000000 10058167472 10521034752 1106609824 11.900 97 8 16 0 1136984224 28735216 1639184
12:19:51 35383 8192000000 10074370368 10544218112 1130526288 12.126 100 25 50 1 1193293808 56662336 6105184
12:21:32 37195 8192000000 10080317024 10516099072 1121299936 12.039 99 47 83 6 1162814304 32861824 8652544
12:23:10 66935 8192000000 10082399088 10550620160 1102904368 11.865 97 29 57 1 1160411360 51334272 6172720
12:25:21 69060 8192000000 10112279760 10568970240 1138214096 12.199 100 16 32 0 1214193744 72639248 3340400
12:27:30 147307 8192000000 10085408672 10572767232 1135217920 12.171 100 23 46 0 1216860816 76536848 5106048
12:31:29 20928 8192000000 10064306752 10507534336 1118020160 12.008 98 50 95 0 1149510304 21155232 10334912
12:32:59 60136 8192000000 10064661936 10531803136 1131836272 12.139 100 9 18 0 1175304784 41912848 1555664
12:34:59 81902 8192000000 10089479232 10536976384 1115316928 11.983 98 23 45 1 1161206992 41165168 4724896
12:37:35 54611 8192000000 10130849456 10579951616 1119352768 12.021 98 24 48 2 1217216128 92584208 5279152
12:39:30 45305 8192000000 10139377952 10578640896 1122170928 12.047 98 23 46 1 1211528928 83668464 5689536
12:41:16 64722 8192000000 10172760448 10610171904 1109002256 11.923 97 32 64 1 1242971616 126641088 7328272
12:43:24 75981 8192000000 10177562640 10651602944 1103153856 11.868 97 17 33 2 1263860048 156525600 4180592
12:46:02 56545 8192000000 10088480080 10550149120 1131403152 12.135 100 23 46 1 1229987008 93260544 5323312
12:47:59 87187 8192000000 10092859008 10508042240 1117434064 12.003 99 49 95 3 1168464672 41904816 9125792
12:50:31 106591 8192000000 10055752624 10496081920 1107762416 11.911 98 40 76 2 1161810032 46563472 7484144
12:53:27 42482 8192000000 10033165888 10483863552 1121701824 12.043 100 3 6 0 1144944304 22565632 676848
12:55:10 84354 8192000000 10098425136 10541740032 1124067344 12.065 99 16 32 0 1167130048 39423904 3638800
12:58:05 66747 8192000000 10104492512 10574053376 1104157312 11.877 97 14 28 0 1210530464 102923568 3449584
13:00:16 76419 8192000000 10118036560 10615746560 1128468208 12.107 99 17 34 1 1209380240 77302144 3609888
13:02:35 48894 8192000000 10112566240 10552020992 1111068064 11.943 98 27 54 0 1199765680 83095136 5602480
13:04:34 81222 8192000000 10071252688 10574331904 1093361984 11.775 96 30 60 1 1203111072 103301168 6447920
13:06:58 27689 8192000000 10118363504 10566103040 1123287744 12.058 99 39 78 0 1190904048 59884368 7731936
13:08:26 47137 8192000000 10104691808 10536480768 1105868480 11.893 97 33 66 0 1192104096 79696784 6538832
13:10:16 86421 8192000000 10042682816 10489057280 1074655616 11.597 95 32 62 1 1127737744 45737200 7344928
13:13:13 16652 8192000000 10055794448 10500526080 1125190704 12.076 100 23 44 2 1160388496 30659328 4538464
13:14:30 78579 8192000000 10095303728 10566991872 1145117984 12.264 100 7 14 0 1194714832 48323152 1273696
13:16:48 69432 8192000000 10102036272 10591055872 1107213808 11.906 97 29 58 0 1212953840 99057568 6682464
13:18:58 54170 8192000000 10162990912 10610814976 1148129200 12.292 100 29 59 0 1274512048 120243728 6139120
13:21:02 75507 8192000000 10095782160 10602692608 1134996560 12.168 99 20 40 1 1249242048 109119920 5125568
13:23:19 36619 8192000000 10117151200 10560495616 1156102080 12.367 100 30 60 2 1228434992 65397984 6934928
13:25:01 93343 8192000000 10084186560 10523230208 1106267232 11.897 96 29 58 1 1153950800 40532064 7151504
13:27:36 20164 8192000000 10137264400 10595999744 1130252784 12.124 99 49 98 0 1216247072 75757680 10236608
13:29:08 126854 8192000000 10074686960 10514706432 1087578832 11.720 95 27 54 0 1124834656 32257776 4998048
13:32:26 12697 8192000000 10067663296 10520260608 1136165952 12.179 100 33 61 3 1177817376 35037424 6614000
13:33:39 53329 8192000000 10081638288 10530770944 1128130688 12.104 99 10 20 1 1173204496 43053792 2020016
13:36:01 101186 8192000000 9415187664 9823600640 528477280 6.060 47 5 9 0 571536560 41901840 1157440

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:16, from 10-06-2022 13:40:07 to 10-06-2022 13:40:23.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 10-06-2022 13:40:24 to 10-06-2022 13:40:26.

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          209547
MON$OLDEST_ACTIVE               209548
MON$OLDEST_SNAPSHOT             209548
MON$NEXT_TRANSACTION            209548
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                       2041184
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             758
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.2.2776 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.2.2776 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.2.2776 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.2.2776 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 ---
:24: 
:25: mscs-test-161.budzdorov.ru    Fri Jun 10 13:38:25 2022
:26:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:27: 
--- end of diff output ---

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

10.06.22 13:40:27. 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
2022-06-10 13:40:30 No severe PSQL-related problems occured
10.06.22 13:40:31. End of report.