Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.10.2021 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 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 15899652 kB
MemAvailable 30688496 kB
Buffers 0 kB
Cached 15842692 kB
SwapCached 3724 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-10-17 13:37:08
DTS_END                         2021-10-17 13:37:08.6220
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/34060
TRN_ID                          236213
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: 8849.56 27499 1061947
customer order: creation 165.42 383 19850
customer order: refuse 14.49 353 1739
order to supplier: creation 44.68 1568 5362
order to supplier: removal 3.27 108482 392
invoice (draft): creation 44.91 3661 5389
invoice (draft): removal 50.03 966 6003
invoice accept: apply 40.39 18952 4847
invoice accept: cancel 45.71 842 5485
customer reserve: creation 3696.05 188 443526
customer reserve: removal 4460.34 62 535241
realization accept: apply 79.53 206 9544
realization accept: cancel 80.59 128 9671
payment from customer: creation 56.59 124 6791
payment from customer: removal 5.28 76 633
payment to supplier: creation 47.75 144 5730
payment to supplier: removal 3.59 89 431
service: total inventory turnovers 3.74 3505 449
service: total monetary turnovers 6.18 410 742
service: aggregate perf. data 0.98 14464 118
service: refresh index statistics 0.03 395379 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-10-2021 13:39:56 to 17-10-2021 13:39:58.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 2600
TEST_TIME 2 2113
TEST_TIME 3 1961
TEST_TIME 4 3040
TEST_TIME 5 3249
TEST_TIME 6 3475
TEST_TIME 7 4108
TEST_TIME 8 4917
TEST_TIME 9 4885
TEST_TIME 10 5268
TEST_TIME 11 5316
TEST_TIME 12 5893
TEST_TIME 13 6006
TEST_TIME 14 6271
TEST_TIME 15 7015
TEST_TIME 16 7591
TEST_TIME 17 8076
TEST_TIME 18 8187
TEST_TIME 19 8772
TEST_TIME 20 8598
TEST_TIME 21 8667
TEST_TIME 22 8711
TEST_TIME 23 9105
TEST_TIME 24 9688
TEST_TIME 25 10041
TEST_TIME 26 10536
TEST_TIME 27 10487
TEST_TIME 28 10338
TEST_TIME 29 10413
TEST_TIME 30 10155
TEST_TIME 31 9938
TEST_TIME 32 10002
TEST_TIME 33 9859
TEST_TIME 34 9712
TEST_TIME 35 9741
TEST_TIME 36 9827
TEST_TIME 37 9678
TEST_TIME 38 9489
TEST_TIME 39 9393
TEST_TIME 40 9310
TEST_TIME 41 9337
TEST_TIME 42 9458
TEST_TIME 43 9526
TEST_TIME 44 9603
TEST_TIME 45 9535
TEST_TIME 46 9616
TEST_TIME 47 9511
TEST_TIME 48 9547
TEST_TIME 49 9541
TEST_TIME 50 9540
TEST_TIME 51 9668
TEST_TIME 52 9587
TEST_TIME 53 9594
TEST_TIME 54 9656
TEST_TIME 55 9560
TEST_TIME 56 9443
TEST_TIME 57 9386
TEST_TIME 58 9314
TEST_TIME 59 9330
TEST_TIME 60 9261
TEST_TIME 61 9184
TEST_TIME 62 9167
TEST_TIME 63 9080
TEST_TIME 64 9079
TEST_TIME 65 9098
TEST_TIME 66 9134
TEST_TIME 67 9098
TEST_TIME 68 9176
TEST_TIME 69 9307
TEST_TIME 70 9259
TEST_TIME 71 9307
TEST_TIME 72 9273
TEST_TIME 73 9282
TEST_TIME 74 9308
TEST_TIME 75 9313
TEST_TIME 76 9215
TEST_TIME 77 9244
TEST_TIME 78 9177
TEST_TIME 79 9150
TEST_TIME 80 9101
TEST_TIME 81 9046
TEST_TIME 82 8990
TEST_TIME 83 8941
TEST_TIME 84 8958
TEST_TIME 85 8907
TEST_TIME 86 8893
TEST_TIME 87 8944
TEST_TIME 88 8901
TEST_TIME 89 8918
TEST_TIME 90 8938
TEST_TIME 91 8968
TEST_TIME 92 8971
TEST_TIME 93 8964
TEST_TIME 94 9058
TEST_TIME 95 9035
TEST_TIME 96 9087
TEST_TIME 97 9081
TEST_TIME 98 9019
TEST_TIME 99 8979
TEST_TIME 100 8944
TEST_TIME 101 8933
TEST_TIME 102 8941
TEST_TIME 103 8884
TEST_TIME 104 8852
TEST_TIME 105 8847
TEST_TIME 106 8802
TEST_TIME 107 8779
TEST_TIME 108 8814
TEST_TIME 109 8844
TEST_TIME 110 8830
TEST_TIME 111 8819
TEST_TIME 112 8806
TEST_TIME 113 8859
TEST_TIME 114 8919
TEST_TIME 115 8946
TEST_TIME 116 8940
TEST_TIME 117 8990
TEST_TIME 118 8972
TEST_TIME 119 8925
TEST_TIME 120 8852

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 17-10-2021 13:39:59 to 17-10-2021 13:40:03.

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 1474362 1474362 0 0.00 0 24108 7 0 0
doc_list_biud 1474347 1474347 0 0.00 0 64640 29 0 0
sp_add_invoice_to_stock 4906 4847 59 1.20 15 233684 18953 0 59
sp_cancel_adding_invoice 5485 5485 0 0.00 5 61554 842 0 0
sp_cancel_client_order 1739 1739 0 0.00 4 15935 353 0 0
sp_cancel_customer_reserve 535241 535241 0 0.00 1 64642 63 0 0
sp_cancel_pay_from_customer 633 633 0 0.00 3 4747 77 0 0
sp_cancel_pay_to_supplier 431 431 0 0.00 3 5319 90 0 0
sp_cancel_supplier_invoice 6005 6003 2 0.03 2 29666 966 0 2
sp_cancel_supplier_order 392 392 0 0.00 11 850319 108483 0 0
sp_cancel_write_off 9671 9671 0 0.00 1 10862 129 0 0
sp_client_order 19850 19850 0 0.00 4 28105 384 0 0
sp_customer_reserve 443526 443526 0 0.00 0 73264 189 0 0
sp_fill_shopping_cart 30595 30595 0 0.00 1 19283 52 0 0
sp_fill_shopping_cart_clo_res 443519 443519 0 0.00 0 27961 17 0 0
sp_get_clo_for_invoice 4840 4840 0 0.00 0 47609 413 0 0
sp_kill_cost_storno 16220 16220 0 0.00 0 7525 58 0 0
sp_kill_qstorno_ret_qs2qd 1086548 1086548 0 0.00 0 64629 35 0 0
sp_kill_qty_storno 572909 572909 0 0.00 0 64640 74 0 0
sp_lock_selected_doc 20002 20002 0 0.00 0 9506 6 0 0
sp_make_cost_storno 26905 26905 0 0.00 0 21907 71 0 0
sp_multiply_rows_for_pdistr 26893 26893 0 0.00 0 10453 35 0 0
sp_multiply_rows_for_qdistr 19850 19850 0 0.00 0 28094 192 0 0
sp_pay_from_customer 6791 6791 0 0.00 4 13954 125 0 0
sp_pay_to_supplier 5730 5730 0 0.00 5 20738 144 0 0
sp_payment_common 12515 12515 0 0.00 1 12590 27 0 0
sp_qd_handle_on_cancel_clo 1739 1739 0 0.00 0 15921 325 0 0
sp_qd_handle_on_invoice_upd_sts 10322 10322 0 0.00 0 23065 340 0 0
sp_qd_handle_on_reserve_upd_sts 9541 9541 0 0.00 0 18762 61 0 0
sp_reserve_write_off 9544 9544 0 0.00 7 20751 206 0 0
sp_supplier_invoice 5389 5389 0 0.00 8 107944 3661 0 0
sp_supplier_order 5362 5362 0 0.00 6 80677 1569 0 0
srv_aggregate_perf_data 118 118 0 0.00 3131 99147 14465 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1422 121835 47510 0 0
srv_find_qd_qs_mism 1016843 1016843 0 0.00 0 19402 6 0 0
srv_make_invnt_saldo 449 449 0 0.00 39 137009 3506 0 0
srv_make_money_saldo 742 742 0 0.00 1 15001 410 0 0
srv_recalc_idx_stat 4 4 0 0.00 348235 430578 395380 0 0
t$perf-abend:sp_add_invoice_to_stock 59 59 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 2 2 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4847 4847 0 0.00 0 32124 165 0 0
t$perf-norm:sp_cancel_adding_invoice 444 444 0 0.00 0 6004 88 0 0
t$perf-norm:sp_cancel_client_order 1739 1739 0 0.00 0 1079 9 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 3002 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 633 633 0 0.00 0 1059 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 431 431 0 0.00 0 428 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 364 364 0 0.00 0 1609 15 0 0
t$perf-norm:sp_cancel_supplier_order 392 392 0 0.00 0 22193 1361 0 0
t$perf-norm:sp_cancel_write_off 960 960 0 0.00 0 1191 6 0 0
t$perf-norm:sp_client_order 19850 19850 0 0.00 0 9228 17 0 0
t$perf-norm:sp_customer_reserve 1011 1011 0 0.00 0 273 2 0 0
t$perf-norm:sp_pay_from_customer 6791 6791 0 0.00 0 3392 7 0 0
t$perf-norm:sp_pay_to_supplier 5730 5730 0 0.00 0 9369 9 0 0
t$perf-norm:sp_reserve_write_off 9544 9544 0 0.00 0 8203 10 0 0
t$perf-norm:sp_supplier_invoice 5389 5389 0 0.00 0 10095 18 0 0
t$perf-norm:sp_supplier_order 5362 5362 0 0.00 0 8122 13 0 0
t$perf-norm:srv_aggregate_perf_data 118 118 0 0.00 0 241 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 4 1 0 0
t$perf-norm:srv_make_invnt_saldo 449 449 0 0.00 0 1992 11 0 0
t$perf-norm:srv_make_money_saldo 742 742 0 0.00 0 5951 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 16 9 0 0
v_add_invoice_to_stock 9792 9674 118 1.20 0 6103 20 0 118
v_all_customers 36442 36442 0 0.00 0 19 1 0 0
v_all_suppliers 29518 29518 0 0.00 0 19 1 0 0
v_all_wares 39698 39698 0 0.00 0 100 1 0 0
v_cancel_adding_invoice 888 888 0 0.00 0 3249 10 0 0
v_cancel_client_order 3478 3478 0 0.00 0 3753 7 0 0
v_cancel_customer_prepayment 1266 1266 0 0.00 0 2084 9 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 2914 9 0 0
v_cancel_payment_to_supplier 862 862 0 0.00 0 1211 5 0 0
v_cancel_supplier_invoice 732 728 4 0.54 0 1961 17 0 4
v_cancel_supplier_order 784 784 0 0.00 0 616 4 0 0
v_cancel_write_off 1920 1920 0 0.00 0 3256 17 0 0
v_max_id_clo_ord 5359 5359 0 0.00 0 897 1 0 0
v_max_id_clo_res 1011 1011 0 0.00 0 20003 33 0 0
v_max_id_ord_sup 5387 5387 0 0.00 0 9619 9 0 0
v_max_non_paid_invoice 1715 1715 0 0.00 0 145 2 0 0
v_max_non_paid_realizn 3539 3539 0 0.00 0 4578 5 0 0
v_min_id_clo_ord 5359 5359 0 0.00 0 13960 21 0 0
v_min_id_clo_res 1011 1011 0 0.00 0 8179 33 0 0
v_min_id_ord_sup 5387 5387 0 0.00 0 16691 17 0 0
v_min_non_paid_invoice 5728 5728 0 0.00 0 5799 16 0 0
v_min_non_paid_realizn 6786 6786 0 0.00 0 12586 19 0 0
v_our_firm 9750 9750 0 0.00 0 24 1 0 0
v_reserve_write_off 19082 19082 0 0.00 0 4769 8 0 0
x_lock_depdocs_on_canc_invoice 11124 11124 0 0.00 0 15738 109 0 0
x_lock_depdocs_on_canc_sup_ord 392 392 0 0.00 0 15587 434 0 0
x_make_qstorno_1000_1200 5362 5362 0 0.00 2 80609 1471 0 0
x_make_qstorno_1200_2000 5389 5389 0 0.00 3 107897 3471 0 0
x_make_qstorno_2100_3300 443526 443526 0 0.00 0 73263 169 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-10-2021 13:40:04 to 17-10-2021 13:40:08.

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:12 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:13 24971 8192000000 9718162736 10012831744 796985536 8.866 100 90 179 1 826169648 7536880 21647232
11:09:49 23218 8192000000 9746330176 10050822144 821261200 9.111 100 89 178 0 856710032 13854080 21594752
11:11:19 13335 8192000000 9796506752 10112528384 856398096 9.464 100 90 180 0 908054224 30215184 21440944
11:12:39 9723 8192000000 9861051200 10195808256 920328272 10.099 100 84 168 1 979790576 40909344 18552960
11:13:52 6845 8192000000 9929018720 10272411648 961220368 10.501 100 88 176 1 1042751280 62862768 18668144
11:15:01 3927 8192000000 9976966368 10330943488 1002274080 10.901 100 85 169 0 1090417808 70909312 17234416
11:16:06 2663 8192000000 9996104544 10363670528 1031508688 11.183 100 85 170 0 1112740560 64222448 17009424
11:17:11 1153 8192000000 10029112400 10396655616 1053052096 11.390 100 96 195 3 1144074720 71704256 19318368
11:18:13 1730 8192000000 10014374608 10394030080 1061875280 11.474 100 68 137 0 1131602336 56455728 13271328
11:19:15 5659 8192000000 9970320576 10367901696 1065327712 11.507 100 32 64 0 1085885856 13596000 6962144
11:20:23 7597 8192000000 10006010592 10409865216 1077800448 11.627 100 37 72 1 1132226992 46913360 7513184
11:21:31 10909 8192000000 10014924272 10421661696 1083089920 11.677 100 27 54 0 1130919680 42348352 5481408
11:22:43 60364 8192000000 10022858816 10413027328 1090530432 11.748 100 49 100 0 1120870224 19582208 10757584
11:24:43 3884 8192000000 10023500240 10423037952 1098396560 11.822 100 49 100 2 1137589408 28277536 10915312
11:25:49 7079 8192000000 10016909696 10416852992 1091801376 11.760 99 36 70 1 1115748176 16325424 7621376
11:27:01 4478 8192000000 10000554736 10395430912 1072106320 11.572 98 50 102 3 1097010576 13919040 10985216
11:28:08 29123 8192000000 9947885312 10346512384 984608384 10.729 91 17 34 0 1017698784 29441376 3649024
11:29:39 8318 8192000000 9939572736 10331463680 1000673824 10.885 97 37 74 0 1034154592 25513792 7966976
11:30:47 6112 8192000000 9925014400 10298269696 984511136 10.728 97 41 77 2 1011418528 17816000 9091392
11:31:59 71989 8192000000 9947137568 10349793280 1033169056 11.199 100 27 53 1 1064310416 25608048 5533312
11:34:20 4655 8192000000 9979734864 10361819136 1059547408 11.452 100 63 126 1 1090766160 16043856 15174896
11:35:26 10725 8192000000 9983674272 10390716416 1071041248 11.562 100 23 46 3 1094808176 18639344 5127584
11:36:42 8639 8192000000 9981258624 10386976768 1078686896 11.635 100 27 54 2 1100992976 16947168 5358912
11:37:57 94375 8192000000 10078093376 10479796224 1105410480 11.889 100 42 84 0 1148700864 34441600 8848784
11:41:27 25730 8192000000 10116328304 10530181120 1131876544 12.139 100 33 66 3 1199888384 61313712 6698128
11:43:02 15506 8192000000 10146044768 10555072512 1147720912 12.288 100 44 88 1 1269600288 111705568 10173808
11:44:26 10829 8192000000 10159880656 10569879552 1148991616 12.300 100 44 88 0 1263177856 104006480 10179760
11:45:42 25712 8192000000 10180628992 10602041344 1165322896 12.453 100 37 74 0 1282599760 108922064 8354800
11:47:12 2533 8192000000 10228793792 10634981376 1172483296 12.520 100 75 149 1 1334764256 145211104 17069856
11:48:19 2005 8192000000 10253101968 10667794432 1183433952 12.622 100 63 123 1 1354494464 157256320 13804192
11:49:24 1418 8192000000 10319144752 10726223872 1199199312 12.769 100 87 172 1 1423192240 205979856 18013072
11:50:31 2505 8192000000 10342580944 10745626624 1198760624 12.765 100 90 178 0 1448231008 229646512 19823872
11:51:40 3475 8192000000 10370939616 10774749184 1177777280 12.569 100 91 182 0 1469478528 270240544 21460704
11:52:46 2953 8192000000 10314552976 10724937728 1148680944 12.297 99 60 119 1 1410361328 247466688 14213696
11:53:55 13543 8192000000 10315395888 10748547072 1165309648 12.453 100 34 68 0 1424502000 250419760 8772592
11:55:11 12508 8192000000 10252930240 10678996992 1115486176 11.984 98 40 80 0 1347156368 221065856 10604336
11:56:29 19429 8192000000 10223023424 10667253760 1128287616 12.105 99 28 56 1 1335562752 200420400 6854736
11:57:52 9837 8192000000 10296798848 10712313856 1140500656 12.220 100 44 87 0 1396592736 245573920 10518160
11:59:12 12104 8192000000 10319511024 10743738368 1125833840 12.082 99 35 70 1 1414443360 279264064 9345456
12:00:29 14447 8192000000 10278564768 10740842496 1121663936 12.043 100 26 50 2 1399516288 270830256 7022096
12:01:54 23658 8192000000 10189975104 10669969408 1120194256 12.029 100 14 28 1 1336564880 212992608 3378016
12:03:22 33877 8192000000 10133431936 10569437184 1124567984 12.070 100 10 20 0 1231485664 104435888 2481792
12:05:01 18535 8192000000 10078399888 10495197184 1098326256 11.822 98 53 103 3 1160835216 50924496 11584464
12:06:26 90397 8192000000 10072903584 10504945664 1104236064 11.878 99 21 42 1 1163988624 55248208 4504352
12:09:00 90381 8192000000 10130783312 10593329152 1152631552 12.334 100 25 50 0 1297650080 139794064 5224464
12:11:33 43294 8192000000 10130595168 10574823424 1133243024 12.152 99 34 67 0 1223808816 83191744 7374048
12:13:17 63557 8192000000 10094413696 10529009664 1101749312 11.854 96 18 37 0 1172612480 66665568 4197600
12:15:21 53548 8192000000 10174099072 10608824320 1110846720 11.940 97 32 64 0 1242049936 124184272 7018944
12:17:21 48678 8192000000 10188647072 10659184640 1136483616 12.182 100 15 30 0 1305049440 164655152 3910672
12:19:10 22271 8192000000 10161008192 10681577472 1139481840 12.211 100 9 18 1 1287279776 145602192 2195744
12:21:12 40838 8192000000 10043561072 10509877248 1119665744 12.024 99 15 30 0 1186052272 63241968 3144560
12:22:56 56987 8192000000 10082493824 10564866048 1135346480 12.172 100 17 34 0 1209293040 70521184 3425376
12:24:55 36639 8192000000 10077560608 10547462144 1134352608 12.162 99 31 61 2 1183918192 43232416 6333168
12:26:39 42861 8192000000 10114182224 10581811200 1139207824 12.208 99 24 48 2 1210545520 66317712 5019984
12:28:25 72864 8192000000 10108413376 10537525248 1152819568 12.336 100 13 26 1 1193635360 38233392 2582400
12:30:41 14883 8192000000 10090558432 10514231296 1139242608 12.208 99 40 79 3 1168315216 21176176 7896432
12:32:04 97874 8192000000 10085206592 10535124992 1117249296 12.001 97 9 18 0 1153563344 34104880 2209168
12:35:29 41424 8192000000 10127733856 10572562432 1147226816 12.283 100 20 40 1 1211489056 60032816 4229424
12:38:04 43626 8192000000 10098753392 10550530048 1148147280 12.292 99 10 20 0 1201165264 51020192 1997792
12:39:50 64118 8192000000 10101232048 10536902656 1090629200 11.749 95 10 20 0 1152078288 59331856 2117232
12:41:56 52905 8192000000 10108466912 10551103488 1128536064 12.108 99 13 26 1 1180678048 49578480 2563504
12:43:52 33224 8192000000 10169208272 10606628864 1147130688 12.283 100 28 56 1 1240449808 87175152 6143968
12:46:05 94424 8192000000 10088113408 10546487296 1100768304 11.845 96 14 28 1 1174592096 70859904 2963888
12:48:42 78388 8192000000 10098159952 10513997824 1089173904 11.735 96 36 72 0 1143639648 47524064 6941680
12:51:01 78050 8192000000 10077272208 10558803968 1094981424 11.790 97 19 38 0 1154734192 56018464 3734304
12:53:26 121835 8192000000 10054094672 10508374016 1099362240 11.832 98 19 38 1 1140637408 37253728 4021440
12:56:29 92215 8192000000 10037399888 10497798144 1081713072 11.664 97 11 22 0 1128902400 44526768 2662560
12:59:01 39678 8192000000 10020075904 10465189888 1075259216 11.602 97 12 24 1 1123089168 44889264 2940688
13:01:01 63974 8192000000 10046766304 10518827008 1108880992 11.922 100 14 28 0 1174736096 62722752 3132352
13:03:19 22512 8192000000 10050090144 10477019136 1115626464 11.986 100 45 87 1 1161117808 36094624 9396720
13:04:44 53895 8192000000 10055066640 10488020992 1104735584 11.883 98 22 44 0 1128034864 18179312 5119968
13:06:40 50565 8192000000 10093367376 10570731520 1106763440 11.902 98 26 52 1 1161841104 49949248 5128416
13:08:38 79810 8192000000 10025915792 10460098560 1062971936 11.485 94 16 32 2 1100191520 34066432 3153152
13:10:58 71526 8192000000 10062095856 10571165696 1106817616 11.902 99 7 14 1 1155151904 47191696 1142592
13:13:10 93275 8192000000 10061427472 10500399104 1111169040 11.943 99 16 33 2 1160077152 45346624 3561488
13:15:43 121079 8192000000 10064281040 10502639616 1108456912 11.918 98 32 63 1 1167795664 53380832 5957920
13:18:51 57729 8192000000 10049703920 10526040064 1112652960 11.958 99 10 20 0 1162027424 47145616 2228848
13:21:43 73458 8192000000 10051318000 10539745280 1135131728 12.170 100 22 45 1 1181904080 41766800 5005552
13:24:00 33742 8192000000 10115328560 10551808000 1131631856 12.137 99 32 64 1 1208767696 70332288 6803552
13:25:49 55446 8192000000 10102322560 10551681024 1132344608 12.143 99 15 30 1 1186658144 51077104 3236432
13:27:53 45208 8192000000 10124556544 10573991936 1145174832 12.264 100 28 56 0 1207733232 56861440 5696960
13:30:01 87729 8192000000 10113745328 10588389376 1112789808 11.959 97 18 36 0 1196432032 79857216 3785008
13:32:40 40063 8192000000 10085023984 10509418496 1093502896 11.776 96 37 73 1 1134400096 33611648 7285552
13:34:21 50685 8192000000 10080812384 10578968576 1109109232 11.924 99 13 26 0 1182373232 70887168 2376832
13:36:12 84850 8192000000 9298138688 9651871744 432446096 5.014 39 4 4 2 451601248 18465984 689168

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-10-2021 13:40:10 to 17-10-2021 13:40:25.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 59 v_add_invoice_to_stock 335544517
except 2 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-10-2021 13:40:26 to 17-10-2021 13:40:28.

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          236190
MON$OLDEST_ACTIVE               236510
MON$OLDEST_SNAPSHOT             236510
MON$NEXT_TRANSACTION            236510
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                       2037472
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             797
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2631 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2631 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2631 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2631 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 ---
:244: 
:245: mscs-test-161.budzdorov.ru    Sun Oct 17 13:37:55 2021
:246:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:247: 
--- end of diff output ---

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

17.10.21 13:40:29. 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-10-17 13:40:31 No severe PSQL-related problems occured
17.10.21 13:40:32. End of report.