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

Server and database settinfs

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


Test configuration settings

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

Test finish info

FINISH_STATE                    NORMAL: TEST_TIME EXPIRED AT 2021-11-26 13:37:06
DTS_END                         2021-11-26 13:37:06.0560
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/33908
TRN_ID                          236025
ATT_ID                          679
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: 9017.83 23016 1082139
customer order: creation 166.65 417 19998
customer order: refuse 14.13 341 1695
order to supplier: creation 44.33 1524 5320
order to supplier: removal 3.38 108580 405
invoice (draft): creation 43.70 3649 5244
invoice (draft): removal 52.54 913 6305
invoice accept: apply 40.70 18855 4884
invoice accept: cancel 47.31 846 5677
customer reserve: creation 3728.91 188 447469
customer reserve: removal 4585.68 62 550281
realization accept: apply 81.49 185 9779
realization accept: cancel 85.98 115 10318
payment from customer: creation 57.09 110 6851
payment from customer: removal 5.58 86 670
payment to supplier: creation 46.71 129 5605
payment to supplier: removal 3.29 107 395
service: total inventory turnovers 3.53 3778 423
service: total monetary turnovers 5.96 454 715
service: aggregate perf. data 0.84 14841 101
service: refresh index statistics 0.03 305146 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 26-11-2021 13:38:28 to 26-11-2021 13:38:30.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6789
TEST_TIME 2 4429
TEST_TIME 3 4416
TEST_TIME 4 4216
TEST_TIME 5 4629
TEST_TIME 6 4605
TEST_TIME 7 4735
TEST_TIME 8 4674
TEST_TIME 9 4912
TEST_TIME 10 5189
TEST_TIME 11 5387
TEST_TIME 12 5525
TEST_TIME 13 6054
TEST_TIME 14 6372
TEST_TIME 15 6505
TEST_TIME 16 6744
TEST_TIME 17 7193
TEST_TIME 18 8282
TEST_TIME 19 8337
TEST_TIME 20 8670
TEST_TIME 21 9759
TEST_TIME 22 10017
TEST_TIME 23 10308
TEST_TIME 24 10744
TEST_TIME 25 10755
TEST_TIME 26 10507
TEST_TIME 27 11080
TEST_TIME 28 10825
TEST_TIME 29 10707
TEST_TIME 30 10358
TEST_TIME 31 10206
TEST_TIME 32 10145
TEST_TIME 33 10064
TEST_TIME 34 10017
TEST_TIME 35 10024
TEST_TIME 36 9901
TEST_TIME 37 9825
TEST_TIME 38 9645
TEST_TIME 39 9741
TEST_TIME 40 9806
TEST_TIME 41 9915
TEST_TIME 42 9792
TEST_TIME 43 9815
TEST_TIME 44 10112
TEST_TIME 45 10028
TEST_TIME 46 9954
TEST_TIME 47 9943
TEST_TIME 48 10177
TEST_TIME 49 10163
TEST_TIME 50 10140
TEST_TIME 51 10195
TEST_TIME 52 10052
TEST_TIME 53 9907
TEST_TIME 54 9862
TEST_TIME 55 9790
TEST_TIME 56 9748
TEST_TIME 57 9677
TEST_TIME 58 9579
TEST_TIME 59 9482
TEST_TIME 60 9439
TEST_TIME 61 9597
TEST_TIME 62 9553
TEST_TIME 63 9599
TEST_TIME 64 9607
TEST_TIME 65 9608
TEST_TIME 66 9634
TEST_TIME 67 9624
TEST_TIME 68 9596
TEST_TIME 69 9671
TEST_TIME 70 9700
TEST_TIME 71 9705
TEST_TIME 72 9660
TEST_TIME 73 9747
TEST_TIME 74 9673
TEST_TIME 75 9606
TEST_TIME 76 9570
TEST_TIME 77 9504
TEST_TIME 78 9406
TEST_TIME 79 9388
TEST_TIME 80 9410
TEST_TIME 81 9453
TEST_TIME 82 9409
TEST_TIME 83 9407
TEST_TIME 84 9389
TEST_TIME 85 9346
TEST_TIME 86 9356
TEST_TIME 87 9383
TEST_TIME 88 9518
TEST_TIME 89 9469
TEST_TIME 90 9499
TEST_TIME 91 9512
TEST_TIME 92 9443
TEST_TIME 93 9381
TEST_TIME 94 9334
TEST_TIME 95 9289
TEST_TIME 96 9276
TEST_TIME 97 9221
TEST_TIME 98 9242
TEST_TIME 99 9201
TEST_TIME 100 9200
TEST_TIME 101 9235
TEST_TIME 102 9244
TEST_TIME 103 9228
TEST_TIME 104 9294
TEST_TIME 105 9264
TEST_TIME 106 9309
TEST_TIME 107 9381
TEST_TIME 108 9339
TEST_TIME 109 9316
TEST_TIME 110 9270
TEST_TIME 111 9244
TEST_TIME 112 9225
TEST_TIME 113 9201
TEST_TIME 114 9166
TEST_TIME 115 9125
TEST_TIME 116 9124
TEST_TIME 117 9070
TEST_TIME 118 9096
TEST_TIME 119 9088
TEST_TIME 120 9019

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 26-11-2021 13:38:32 to 26-11-2021 13:38:35.

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 1496396 1496396 0 0.00 0 23844 7 0 0
doc_list_biud 1496396 1496396 0 0.00 0 46418 29 0 0
sp_add_invoice_to_stock 4990 4884 106 2.12 29 203790 18856 0 106
sp_cancel_adding_invoice 5677 5677 0 0.00 6 68305 846 0 0
sp_cancel_client_order 1695 1695 0 0.00 5 13936 342 0 0
sp_cancel_customer_reserve 550281 550281 0 0.00 1 46430 63 0 0
sp_cancel_pay_from_customer 670 670 0 0.00 3 7567 86 0 0
sp_cancel_pay_to_supplier 395 395 0 0.00 3 6101 107 0 0
sp_cancel_supplier_invoice 6317 6305 12 0.18 2 17153 914 0 12
sp_cancel_supplier_order 405 405 0 0.00 8 1046539 108580 0 0
sp_cancel_write_off 10318 10318 0 0.00 1 9759 116 0 0
sp_client_order 19998 19998 0 0.00 4 23692 417 0 0
sp_customer_reserve 447469 447469 0 0.00 0 87424 188 0 0
sp_fill_shopping_cart 30541 30541 0 0.00 1 18523 46 0 0
sp_fill_shopping_cart_clo_res 447467 447467 0 0.00 0 26535 19 0 0
sp_get_clo_for_invoice 4882 4882 0 0.00 0 24684 370 0 0
sp_kill_cost_storno 17060 17060 0 0.00 0 11675 58 0 0
sp_kill_qstorno_ret_qs2qd 1117590 1117590 0 0.00 0 46375 34 0 0
sp_kill_qty_storno 589342 589342 0 0.00 0 46418 73 0 0
sp_lock_selected_doc 20307 20307 0 0.00 0 8495 6 0 0
sp_make_cost_storno 27117 27117 0 0.00 0 23833 54 0 0
sp_multiply_rows_for_pdistr 27117 27117 0 0.00 0 9123 33 0 0
sp_multiply_rows_for_qdistr 19998 19998 0 0.00 0 23342 217 0 0
sp_pay_from_customer 6851 6851 0 0.00 4 17652 110 0 0
sp_pay_to_supplier 5605 5605 0 0.00 5 9259 130 0 0
sp_payment_common 12456 12456 0 0.00 1 8433 24 0 0
sp_qd_handle_on_cancel_clo 1695 1695 0 0.00 0 13930 306 0 0
sp_qd_handle_on_invoice_upd_sts 10559 10559 0 0.00 0 26030 322 0 0
sp_qd_handle_on_reserve_upd_sts 9779 9779 0 0.00 0 19712 56 0 0
sp_reserve_write_off 9779 9779 0 0.00 7 21451 185 0 0
sp_supplier_invoice 5244 5244 0 0.00 8 80578 3650 0 0
sp_supplier_order 5320 5320 0 0.00 6 64946 1525 0 0
srv_aggregate_perf_data 101 101 0 0.00 2838 282917 14841 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1302 139853 48446 0 0
srv_find_qd_qs_mism 1036120 1036120 0 0.00 0 20005 6 0 0
srv_make_invnt_saldo 423 423 0 0.00 32 107944 3778 0 0
srv_make_money_saldo 715 715 0 0.00 0 12148 454 0 0
srv_recalc_idx_stat 4 4 0 0.00 129837 503399 305147 0 0
t$perf-abend:sp_add_invoice_to_stock 106 106 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 12 12 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4884 4884 0 0.00 0 12718 166 0 0
t$perf-norm:sp_cancel_adding_invoice 416 416 0 0.00 0 18473 139 0 0
t$perf-norm:sp_cancel_client_order 1695 1695 0 0.00 0 12033 20 0 0
t$perf-norm:sp_cancel_customer_reserve 698 698 0 0.00 0 422 4 0 0
t$perf-norm:sp_cancel_pay_from_customer 670 670 0 0.00 0 1361 7 0 0
t$perf-norm:sp_cancel_pay_to_supplier 395 395 0 0.00 0 619 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 416 416 0 0.00 0 6188 36 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 20866 1432 0 0
t$perf-norm:sp_cancel_write_off 953 953 0 0.00 0 2537 8 0 0
t$perf-norm:sp_client_order 19998 19998 0 0.00 0 16331 20 0 0
t$perf-norm:sp_customer_reserve 984 984 0 0.00 0 1581 6 0 0
t$perf-norm:sp_pay_from_customer 6851 6851 0 0.00 0 24013 17 0 0
t$perf-norm:sp_pay_to_supplier 5605 5605 0 0.00 0 12559 10 0 0
t$perf-norm:sp_reserve_write_off 9779 9779 0 0.00 0 10581 11 0 0
t$perf-norm:sp_supplier_invoice 5244 5244 0 0.00 0 7710 15 0 0
t$perf-norm:sp_supplier_order 5320 5320 0 0.00 0 7541 17 0 0
t$perf-norm:srv_aggregate_perf_data 101 101 0 0.00 0 336 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 6337 101 0 0
t$perf-norm:srv_make_invnt_saldo 423 423 0 0.00 0 7466 23 0 0
t$perf-norm:srv_make_money_saldo 715 715 0 0.00 0 486 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 9974 9762 212 2.12 0 9899 19 0 212
v_all_customers 36290 36290 0 0.00 0 32 1 0 0
v_all_suppliers 28976 28976 0 0.00 0 17 1 0 0
v_all_wares 39996 39996 0 0.00 0 46 1 0 0
v_cancel_adding_invoice 832 832 0 0.00 0 1759 10 0 0
v_cancel_client_order 3390 3390 0 0.00 0 2970 9 0 0
v_cancel_customer_prepayment 1340 1340 0 0.00 0 1401 6 0 0
v_cancel_customer_reserve 1396 1396 0 0.00 0 1048 5 0 0
v_cancel_payment_to_supplier 790 790 0 0.00 0 1841 13 0 0
v_cancel_supplier_invoice 856 832 24 2.80 0 1951 14 0 24
v_cancel_supplier_order 810 810 0 0.00 0 443 4 0 0
v_cancel_write_off 1906 1906 0 0.00 0 1857 14 0 0
v_max_id_clo_ord 5310 5310 0 0.00 0 256 1 0 0
v_max_id_clo_res 984 984 0 0.00 0 5694 19 0 0
v_max_id_ord_sup 5233 5233 0 0.00 0 5515 7 0 0
v_max_non_paid_invoice 1660 1660 0 0.00 0 637 3 0 0
v_max_non_paid_realizn 3647 3647 0 0.00 0 2942 5 0 0
v_min_id_clo_ord 5310 5310 0 0.00 0 17573 8 0 0
v_min_id_clo_res 984 984 0 0.00 0 5907 18 0 0
v_min_id_ord_sup 5233 5233 0 0.00 0 5417 6 0 0
v_min_non_paid_invoice 5605 5605 0 0.00 0 8000 17 0 0
v_min_non_paid_realizn 6851 6851 0 0.00 0 7967 15 0 0
v_our_firm 10114 10114 0 0.00 0 18 1 0 0
v_reserve_write_off 19556 19556 0 0.00 0 3988 6 0 0
x_lock_depdocs_on_canc_invoice 11566 11566 0 0.00 0 14363 109 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 9488 317 0 0
x_make_qstorno_1000_1200 5320 5320 0 0.00 2 64699 1441 0 0
x_make_qstorno_1200_2000 5244 5244 0 0.00 4 80570 3472 0 0
x_make_qstorno_2100_3300 447469 447469 0 0.00 0 87423 167 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 26-11-2021 13:38:36 to 26-11-2021 13:38:40.

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:09 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:10 21492 8192000000 9713444704 10008801280 785418192 8.748 100 85 170 0 813225312 7312560 20494560
11:09:35 23930 8192000000 9756090176 10057777152 825961760 9.159 100 95 190 0 861323968 12484032 22878176
11:11:04 19026 8192000000 9803658208 10120785920 862055840 9.521 100 81 162 0 905122400 24784480 18282080
11:12:30 13230 8192000000 9877659456 10212651008 920724768 10.103 100 74 148 1 987787424 51053040 16009616
11:13:46 5248 8192000000 9927973168 10271969280 956241024 10.452 100 83 166 1 1036959456 63375792 17342640
11:14:54 4145 8192000000 9955915136 10310356992 996576864 10.845 100 86 172 1 1068560080 54312656 17670560
11:16:00 3041 8192000000 10005893728 10367594496 1031963424 11.187 100 94 188 1 1120194432 69410848 18820160
11:17:04 1967 8192000000 10055381216 10427002880 1063456016 11.490 100 86 172 0 1170075392 88423648 18195728
11:18:07 4335 8192000000 10032278688 10424733696 1075760352 11.607 100 55 110 0 1154524688 67168512 11595824
11:19:12 7255 8192000000 9993050080 10389114880 1078781376 11.636 100 32 65 2 1116437232 30569872 7085984
11:20:21 13450 8192000000 9993522000 10408407040 1082339424 11.670 100 45 90 0 1121919120 29829376 9750320
11:21:36 4597 8192000000 10010276416 10403213312 1093620096 11.777 100 52 106 2 1119716048 14098640 11997312
11:22:41 4305 8192000000 9999189488 10399834112 1095463776 11.795 100 39 78 2 1113223120 10220224 7539120
11:23:47 11314 8192000000 10008973984 10414727168 1094036960 11.781 100 20 40 0 1122553408 23932336 4584112
11:25:02 6838 8192000000 10024170304 10417360896 1101224176 11.849 100 63 125 0 1130381440 15495168 13662096
11:26:10 6110 8192000000 10006477520 10409467904 1067588688 11.529 98 32 64 0 1099796896 25296480 6911728
11:27:18 5560 8192000000 9968817008 10361622528 1051005984 11.370 98 31 62 0 1073178592 14680944 7491664
11:28:24 10453 8192000000 9950703232 10346700800 1023424992 11.105 98 36 71 1 1051291104 19479600 8386512
11:29:35 47310 8192000000 9928581872 10309787648 906539200 9.963 87 28 55 0 928080000 15172768 6368032
11:31:23 7250 8192000000 9945036112 10326204416 1003813232 10.915 97 32 63 1 1037560864 27491984 6255648
11:32:30 10512 8192000000 9958615808 10348609536 1042018832 11.284 100 46 92 0 1069659776 16997984 10642960
11:33:43 7456 8192000000 9983259184 10380951552 1062029360 11.476 100 43 86 0 1092367200 21529216 8808624
11:34:54 31761 8192000000 9977689600 10388189184 1062411904 11.480 100 14 28 0 1087507168 22440416 2654848
11:36:26 4450 8192000000 9996663376 10389344256 1074647744 11.596 100 66 135 0 1107494576 17959104 14887728
11:37:37 2042 8192000000 10021053104 10409086976 1097308432 11.812 100 65 130 1 1127531376 16158352 14064592
11:38:44 20749 8192000000 10047134256 10445037568 1111856128 11.950 100 39 78 0 1155961200 36374128 7730944
11:40:06 61945 8192000000 10088627568 10497064960 1120576432 12.032 100 33 64 2 1186384304 58718960 7088912
11:42:13 55850 8192000000 10136823664 10551631872 1137172128 12.189 100 46 92 1 1237303376 89517120 10614128
11:44:22 6254 8192000000 10220976096 10624266240 1155567936 12.362 100 59 118 1 1319736976 151547008 12622032
11:45:42 14133 8192000000 10265843312 10673553408 1162962304 12.431 100 62 124 0 1368634512 191948736 13723472
11:47:12 2545 8192000000 10300755584 10711457792 1162692272 12.428 99 72 142 1 1393676928 215826864 15157792
11:48:19 5510 8192000000 10335656032 10742317056 1176671472 12.559 100 68 132 1 1438218624 246492384 15054768
11:49:28 5534 8192000000 10383189328 10786713600 1166539344 12.464 100 78 155 1 1482656064 297535824 18580896
11:50:38 3302 8192000000 10389553040 10790711296 1164645632 12.447 99 83 167 1 1479348960 296061184 18642144
11:51:44 1301 8192000000 10405526880 10805768192 1155210720 12.358 100 91 180 0 1500644512 324174976 21258816
11:52:51 2403 8192000000 10447928240 10846806016 1147861200 12.289 100 82 163 0 1552185024 384875760 19448064
11:54:01 7302 8192000000 10394041456 10820894720 1132411280 12.144 99 66 132 1 1503645040 354874608 16359152
11:55:15 10584 8192000000 10383172080 10796560384 1128573744 12.108 100 47 92 1 1515161696 374264304 12323648
11:56:29 11669 8192000000 10328709696 10759196672 1117902048 12.007 99 42 84 1 1417398896 288778592 10718256
11:57:44 8404 8192000000 10320479952 10768285696 1116342816 11.992 99 43 84 0 1413863648 286506768 11014064
11:58:53 56205 8192000000 10157466640 10606575616 1110547728 11.938 99 17 34 1 1303320192 188523008 4249456
12:00:58 23685 8192000000 10085649152 10586955776 1103641968 11.872 99 18 36 0 1182256976 74046784 4568224
12:02:23 22330 8192000000 10110167328 10578247680 1109297376 11.926 99 15 30 2 1217425680 104976752 3151552
12:03:57 63072 8192000000 10066331856 10602983424 1080667488 11.654 97 13 26 1 1140063360 56087568 3308304
12:06:02 58398 8192000000 10070322144 10517573632 1111062592 11.942 99 25 50 0 1190262000 73946272 5253136
12:08:39 55191 8192000000 10100410528 10534633472 1131510528 12.136 100 19 38 1 1201411456 65563088 4337840
12:10:42 22105 8192000000 10090511296 10504261632 1098319584 11.822 96 43 81 1 1168053840 61149152 8585104
12:12:12 57646 8192000000 10094418896 10559393792 1121646672 12.043 99 21 42 0 1201744176 75344128 4753376
12:14:11 101441 8192000000 10157871088 10593603584 1137046960 12.188 99 16 32 0 1241773296 101248352 3477984
12:16:57 47434 8192000000 10118501312 10582581248 1133910864 12.158 99 21 40 1 1210806576 72553712 4342000
12:18:45 22857 8192000000 10095795024 10529193984 1129229248 12.114 99 37 73 1 1186087504 48867392 7990864
12:20:08 78547 8192000000 10109882912 10649358336 1149286848 12.303 100 17 34 2 1255108080 101557408 4263824
12:22:32 11661 8192000000 10084413200 10510438400 1130773088 12.129 98 46 90 2 1161233376 20213200 10247088
12:23:47 71906 8192000000 10172951984 10629566464 1136834704 12.186 98 23 46 1 1252000512 110100304 5065504
12:26:03 40888 8192000000 10079744528 10527776768 1137995712 12.197 99 18 34 2 1189543568 48330976 3216880
12:27:54 130609 8192000000 10048694736 10496012288 1112846480 11.959 97 27 53 1 1225917024 107356464 5714080
12:31:18 41694 8192000000 10061133776 10517819392 1099629408 11.834 97 35 69 0 1137888192 31080816 7177968
12:33:10 76345 8192000000 10072977120 10525437952 1101499616 11.852 97 12 24 0 1143191104 39451280 2240208
12:35:34 72624 8192000000 10142719152 10586697728 1138176448 12.198 99 31 62 2 1224495520 78103552 8215520
12:37:47 47624 8192000000 10138456192 10603995136 1138801280 12.204 100 20 40 0 1244184720 101058672 4324768
12:39:35 28756 8192000000 10073042320 10508079104 1097207008 11.811 96 34 67 3 1138699616 35026944 6465664
12:41:04 55756 8192000000 10078827456 10525073408 1116570592 11.995 98 19 38 0 1143683136 23297200 3815344
12:43:05 38004 8192000000 10085497568 10532143104 1106827488 11.902 98 38 76 2 1162339728 47764880 7747360
12:44:43 35399 8192000000 10084400032 10510061568 1115316944 11.983 99 39 78 1 1157262080 34012256 7932880
12:46:25 84671 8192000000 10061863376 10539388928 1091132672 11.753 96 36 72 1 1178333280 80078864 7121744
12:48:50 60727 8192000000 10065942400 10481262592 1084414464 11.690 96 34 68 0 1121307248 30422528 6470256
12:50:55 94705 8192000000 10076988240 10582704128 1122300704 12.049 100 9 18 0 1193698528 69574688 1823136
12:53:38 61062 8192000000 10079310320 10523959296 1119668448 12.024 99 18 36 2 1161730576 38535776 3526352
12:55:42 34362 8192000000 10095027584 10532052992 1117954400 12.008 99 15 31 1 1193574000 72151456 3468144
12:57:17 51490 8192000000 10090035088 10588839936 1115282224 11.982 99 10 20 0 1229467712 111737504 2447984
12:59:25 41891 8192000000 10088088368 10522554368 1101618848 11.853 97 30 61 0 1166186096 57265328 7301920
13:01:12 68500 8192000000 10125611216 10557308928 1137232896 12.189 100 25 50 0 1228752224 86022016 5497312
13:03:25 65607 8192000000 10157627040 10630369280 1126957312 12.093 99 18 36 1 1249214768 118062464 4194992
13:05:49 59774 8192000000 10089998384 10528718848 1113274240 11.963 98 15 29 1 1151352544 35189360 2888944
13:07:49 21849 8192000000 10117786608 10545012736 1147675808 12.288 100 35 68 1 1208049008 53586992 6786208
13:09:11 109906 8192000000 10092310816 10539696128 1127693136 12.100 99 37 74 1 1182324112 47024208 7606768
13:12:01 68944 8192000000 10083220704 10551353344 1126173104 12.085 99 8 16 0 1176472656 48863216 1436336
13:14:10 40527 8192000000 10089905632 10534305792 1135924928 12.177 99 33 66 1 1180809040 37606672 7277440
13:15:51 124586 8192000000 10129840176 10588639232 1117767392 12.006 97 26 52 0 1167502096 44477680 5257024
13:19:01 78750 8192000000 10111302832 10589413376 1149802848 12.308 100 14 28 0 1213599280 60621440 3174992
13:21:21 67254 8192000000 10119194144 10596294656 1133267376 12.152 99 27 54 0 1221349040 82402288 5679376
13:23:31 139853 8192000000 10064209536 10530336768 1055471648 11.413 92 8 16 0 1131875600 74486480 1917472
13:26:52 126565 8192000000 10096384096 10574852096 1111300800 11.945 98 34 68 0 1201304208 83174224 6829184
13:30:51 50211 8192000000 10071806912 10544648192 1128298672 12.105 99 12 23 1 1183052928 52148240 2606016
13:33:06 63976 8192000000 10120814496 10617102336 1145063600 12.263 100 12 24 0 1207695152 60033360 2598192
13:35:17 36951 8192000000 10071361536 10500386816 1108521440 11.918 97 35 70 0 1135910224 20491168 6897616
13:36:57 20177 8192000000 8899416000 9106571264 62021280 0.751 6 3 4 1 71866512 9338720 506512

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 26-11-2021 13:38:42 to 26-11-2021 13:38:57.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 106 v_add_invoice_to_stock 335544517
except 12 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 26-11-2021 13:38:58 to 26-11-2021 13:38:59.

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          236349
MON$OLDEST_ACTIVE               236350
MON$OLDEST_SNAPSHOT             236350
MON$NEXT_TRANSACTION            236350
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                       2041632
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             800
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2669 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2669 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2669 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2669 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 ---
--- end of diff output ---

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

26.11.21 13:39:00. 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-26 13:39:03 No severe PSQL-related problems occured
26.11.21 13:39:04. End of report.