Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 22.03.2021 10:27
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 df9f488d08424df294c459d6c283e9c3
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-03-04 16:07
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.26
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 16798744 kB
MemAvailable 31553128 kB
Buffers 0 kB
Cached 14880936 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.0
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-03-22 10:26:57
DTS_END                         2021-03-22 10:26:57.1560
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/52182
TRN_ID                          229820
ATT_ID                          677
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: 8830.00 28582 1059600
customer order: creation 160.44 404 19253
customer order: refuse 14.39 408 1727
order to supplier: creation 44.93 1472 5391
order to supplier: removal 3.41 125352 409
invoice (draft): creation 44.70 4147 5364
invoice (draft): removal 51.16 1021 6139
invoice accept: apply 39.76 18858 4771
invoice accept: cancel 45.67 1013 5480
customer reserve: creation 3678.57 183 441428
customer reserve: removal 4463.93 74 535672
realization accept: apply 79.89 182 9587
realization accept: cancel 79.50 138 9540
payment from customer: creation 55.78 101 6694
payment from customer: removal 5.45 66 654
payment to supplier: creation 48.23 122 5788
payment to supplier: removal 3.56 91 427
service: total inventory turnovers 3.67 3668 440
service: total monetary turnovers 6.03 606 723
service: aggregate perf. data 0.90 15548 108
service: refresh index statistics 0.04 398192 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 22-03-2021 10:28:21 to 22-03-2021 10:28:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 1435
TEST_TIME 2 2809
TEST_TIME 3 3789
TEST_TIME 4 4360
TEST_TIME 5 4825
TEST_TIME 6 4917
TEST_TIME 7 4925
TEST_TIME 8 5143
TEST_TIME 9 5194
TEST_TIME 10 5680
TEST_TIME 11 5690
TEST_TIME 12 5854
TEST_TIME 13 6113
TEST_TIME 14 6248
TEST_TIME 15 6253
TEST_TIME 16 6404
TEST_TIME 17 6623
TEST_TIME 18 6640
TEST_TIME 19 6777
TEST_TIME 20 6818
TEST_TIME 21 7193
TEST_TIME 22 7404
TEST_TIME 23 7698
TEST_TIME 24 7817
TEST_TIME 25 7742
TEST_TIME 26 8496
TEST_TIME 27 8778
TEST_TIME 28 8819
TEST_TIME 29 8881
TEST_TIME 30 9634
TEST_TIME 31 10068
TEST_TIME 32 10346
TEST_TIME 33 10322
TEST_TIME 34 10286
TEST_TIME 35 10201
TEST_TIME 36 10092
TEST_TIME 37 10015
TEST_TIME 38 10146
TEST_TIME 39 10118
TEST_TIME 40 10047
TEST_TIME 41 9888
TEST_TIME 42 9670
TEST_TIME 43 9567
TEST_TIME 44 9510
TEST_TIME 45 9476
TEST_TIME 46 9351
TEST_TIME 47 9311
TEST_TIME 48 9472
TEST_TIME 49 9519
TEST_TIME 50 9430
TEST_TIME 51 9349
TEST_TIME 52 9246
TEST_TIME 53 9165
TEST_TIME 54 9141
TEST_TIME 55 9265
TEST_TIME 56 9202
TEST_TIME 57 9194
TEST_TIME 58 9244
TEST_TIME 59 9279
TEST_TIME 60 9421
TEST_TIME 61 9362
TEST_TIME 62 9426
TEST_TIME 63 9493
TEST_TIME 64 9540
TEST_TIME 65 9538
TEST_TIME 66 9582
TEST_TIME 67 9682
TEST_TIME 68 9685
TEST_TIME 69 9569
TEST_TIME 70 9451
TEST_TIME 71 9446
TEST_TIME 72 9387
TEST_TIME 73 9318
TEST_TIME 74 9263
TEST_TIME 75 9216
TEST_TIME 76 9202
TEST_TIME 77 9141
TEST_TIME 78 9160
TEST_TIME 79 9163
TEST_TIME 80 9134
TEST_TIME 81 9145
TEST_TIME 82 9112
TEST_TIME 83 9120
TEST_TIME 84 9232
TEST_TIME 85 9255
TEST_TIME 86 9275
TEST_TIME 87 9284
TEST_TIME 88 9304
TEST_TIME 89 9347
TEST_TIME 90 9282
TEST_TIME 91 9190
TEST_TIME 92 9162
TEST_TIME 93 9097
TEST_TIME 94 9050
TEST_TIME 95 8990
TEST_TIME 96 8993
TEST_TIME 97 9014
TEST_TIME 98 8979
TEST_TIME 99 8952
TEST_TIME 100 8947
TEST_TIME 101 8923
TEST_TIME 102 8965
TEST_TIME 103 8945
TEST_TIME 104 9012
TEST_TIME 105 8972
TEST_TIME 106 8993
TEST_TIME 107 9004
TEST_TIME 108 9158
TEST_TIME 109 9116
TEST_TIME 110 9039
TEST_TIME 111 8995
TEST_TIME 112 8950
TEST_TIME 113 8928
TEST_TIME 114 8908
TEST_TIME 115 8904
TEST_TIME 116 8900
TEST_TIME 117 8886
TEST_TIME 118 8884
TEST_TIME 119 8852
TEST_TIME 120 8835

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 22-03-2021 10:28:24 to 22-03-2021 10:28:28.

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 1467685 1467685 0 0.00 0 18847 7 0 0
doc_list_biud 1467685 1467685 0 0.00 0 43943 35 0 0
sp_add_invoice_to_stock 4850 4771 79 1.62 31 265854 18859 0 79
sp_cancel_adding_invoice 5480 5480 0 0.00 6 76909 1013 0 0
sp_cancel_client_order 1727 1727 0 0.00 5 11902 409 0 0
sp_cancel_customer_reserve 535672 535672 0 0.00 1 21610 75 0 0
sp_cancel_pay_from_customer 654 654 0 0.00 3 1304 67 0 0
sp_cancel_pay_to_supplier 427 427 0 0.00 3 4943 91 0 0
sp_cancel_supplier_invoice 6147 6139 8 0.13 2 17526 1021 0 8
sp_cancel_supplier_order 409 409 0 0.00 9 1257730 125352 0 0
sp_cancel_write_off 9540 9540 0 0.00 1 13520 139 0 0
sp_client_order 19253 19253 0 0.00 4 17018 405 0 0
sp_customer_reserve 441428 441428 0 0.00 0 175183 184 0 0
sp_fill_shopping_cart 29991 29991 0 0.00 1 46867 47 0 0
sp_fill_shopping_cart_clo_res 441419 441419 0 0.00 0 18158 16 0 0
sp_get_clo_for_invoice 4762 4762 0 0.00 0 34343 362 0 0
sp_kill_cost_storno 16101 16101 0 0.00 0 6152 67 0 0
sp_kill_qstorno_ret_qs2qd 1087432 1087432 0 0.00 0 21604 40 0 0
sp_kill_qty_storno 573316 573316 0 0.00 0 43943 88 0 0
sp_lock_selected_doc 20055 20055 0 0.00 0 11585 6 0 0
sp_make_cost_storno 26831 26831 0 0.00 0 13780 58 0 0
sp_multiply_rows_for_pdistr 26831 26831 0 0.00 0 7672 31 0 0
sp_multiply_rows_for_qdistr 19253 19253 0 0.00 0 17005 223 0 0
sp_pay_from_customer 6694 6694 0 0.00 4 22249 101 0 0
sp_pay_to_supplier 5788 5788 0 0.00 4 14642 123 0 0
sp_payment_common 12482 12482 0 0.00 1 21561 21 0 0
sp_qd_handle_on_cancel_clo 1727 1727 0 0.00 0 10904 368 0 0
sp_qd_handle_on_invoice_upd_sts 10242 10242 0 0.00 0 43943 463 0 0
sp_qd_handle_on_reserve_upd_sts 9587 9587 0 0.00 0 32124 60 0 0
sp_reserve_write_off 9587 9587 0 0.00 6 32149 183 0 0
sp_supplier_invoice 5364 5364 0 0.00 7 128461 4147 0 0
sp_supplier_order 5391 5391 0 0.00 5 20872 1472 0 0
srv_aggregate_perf_data 108 108 0 0.00 2607 187331 15548 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1514 169481 47200 0 0
srv_find_qd_qs_mism 1013727 1013727 0 0.00 0 18846 6 0 0
srv_make_invnt_saldo 440 440 0 0.00 67 100767 3669 0 0
srv_make_money_saldo 723 723 0 0.00 0 11842 607 0 0
srv_recalc_idx_stat 5 5 0 0.00 217925 577002 398192 0 0
t$perf-abend:sp_add_invoice_to_stock 79 79 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 8 8 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4771 4771 0 0.00 0 18881 195 0 0
t$perf-norm:sp_cancel_adding_invoice 389 389 0 0.00 0 1475 91 0 0
t$perf-norm:sp_cancel_client_order 1727 1727 0 0.00 0 4023 17 0 0
t$perf-norm:sp_cancel_customer_reserve 714 714 0 0.00 0 858 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 654 654 0 0.00 0 2085 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 427 427 0 0.00 0 2507 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 410 410 0 0.00 0 4460 40 0 0
t$perf-norm:sp_cancel_supplier_order 409 409 0 0.00 0 30802 1726 0 0
t$perf-norm:sp_cancel_write_off 976 976 0 0.00 0 1470 5 0 0
t$perf-norm:sp_client_order 19253 19253 0 0.00 0 9828 19 0 0
t$perf-norm:sp_customer_reserve 1013 1013 0 0.00 0 1365 6 0 0
t$perf-norm:sp_pay_from_customer 6694 6694 0 0.00 0 6672 10 0 0
t$perf-norm:sp_pay_to_supplier 5788 5788 0 0.00 0 6842 13 0 0
t$perf-norm:sp_reserve_write_off 9587 9587 0 0.00 0 6157 11 0 0
t$perf-norm:sp_supplier_invoice 5364 5364 0 0.00 0 8387 27 0 0
t$perf-norm:sp_supplier_order 5391 5391 0 0.00 0 12811 20 0 0
t$perf-norm:srv_aggregate_perf_data 108 108 0 0.00 0 185 8 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 47 2 0 0
t$perf-norm:srv_make_invnt_saldo 440 440 0 0.00 0 2381 17 0 0
t$perf-norm:srv_make_money_saldo 723 723 0 0.00 0 2928 20 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 2 26 9 0 0
v_add_invoice_to_stock 9682 9524 158 1.63 0 3678 17 0 158
v_all_customers 35124 35124 0 0.00 0 39 1 0 0
v_all_suppliers 30004 30004 0 0.00 0 22 1 0 0
v_all_wares 38506 38506 0 0.00 0 27 1 0 0
v_cancel_adding_invoice 778 778 0 0.00 0 327 7 0 0
v_cancel_client_order 3454 3454 0 0.00 0 1131 4 0 0
v_cancel_customer_prepayment 1308 1308 0 0.00 0 943 5 0 0
v_cancel_customer_reserve 1428 1428 0 0.00 0 2236 5 0 0
v_cancel_payment_to_supplier 854 854 0 0.00 0 4330 16 0 0
v_cancel_supplier_invoice 836 820 16 1.91 0 986 13 0 16
v_cancel_supplier_order 818 818 0 0.00 0 4012 10 0 0
v_cancel_write_off 1952 1952 0 0.00 0 1543 15 0 0
v_max_id_clo_ord 5391 5391 0 0.00 0 610 1 0 0
v_max_id_clo_res 1013 1013 0 0.00 0 1721 9 0 0
v_max_id_ord_sup 5347 5347 0 0.00 0 6169 6 0 0
v_max_non_paid_invoice 1524 1524 0 0.00 0 1458 4 0 0
v_max_non_paid_realizn 3556 3556 0 0.00 0 338 2 0 0
v_min_id_clo_ord 5391 5391 0 0.00 0 3179 7 0 0
v_min_id_clo_res 1013 1013 0 0.00 0 8356 19 0 0
v_min_id_ord_sup 5347 5347 0 0.00 0 9917 8 0 0
v_min_non_paid_invoice 5788 5788 0 0.00 0 7372 13 0 0
v_min_non_paid_realizn 6694 6694 0 0.00 0 6747 13 0 0
v_our_firm 9658 9658 0 0.00 0 19 1 0 0
v_reserve_write_off 19174 19174 0 0.00 0 3677 5 0 0
x_lock_depdocs_on_canc_invoice 11209 11209 0 0.00 0 16666 127 0 0
x_lock_depdocs_on_canc_sup_ord 409 409 0 0.00 0 6509 396 0 0
x_make_qstorno_1000_1200 5391 5391 0 0.00 2 20861 1382 0 0
x_make_qstorno_1200_2000 5364 5364 0 0.00 4 128451 3968 0 0
x_make_qstorno_2100_3300 441428 441428 0 0.00 0 175181 165 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 22-03-2021 10:28:29 to 22-03-2021 10:28:33.

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
07:57:00 2 8192000000 8796668848 8807174144 3189104 0.038 1 1 2 0 3286720 61840 35776
07:58:01 31345 8192000000 9729045872 10027335680 805417664 8.951 100 84 168 0 833675520 7656832 20601024
07:59:43 20745 8192000000 9759672736 10065588224 833927072 9.239 100 89 178 0 869462768 14018688 21517008
08:01:11 13478 8192000000 9803565296 10120806400 865186096 9.552 100 87 174 3 916581072 30756976 20638000
08:02:28 16280 8192000000 9861119824 10191486976 913555664 10.032 100 70 138 0 971413520 42402848 15455008
08:03:48 7013 8192000000 9903027120 10246049792 953940416 10.430 100 81 162 1 1016189664 45702480 16546768
08:04:59 7216 8192000000 9938935456 10295341056 991086464 10.792 100 76 150 0 1067263248 60970128 15206656
08:06:08 3875 8192000000 9981873264 10337517568 1018919376 11.062 100 88 176 0 1087758896 50989024 17850496
08:07:15 5994 8192000000 9992434192 10357518336 1030857600 11.177 100 76 152 2 1110298256 64714592 14726064
08:08:22 1911 8192000000 10043728320 10410975232 1052810752 11.388 100 89 177 1 1154952736 84499744 17642240
08:09:25 8800 8192000000 10009401520 10403254272 1060378512 11.460 100 42 85 0 1140912624 72143920 8390192
08:10:36 5426 8192000000 9992987664 10384015360 1070402896 11.556 100 47 95 3 1108181904 27519792 10259216
08:11:43 6303 8192000000 9989666080 10388312064 1077240400 11.621 100 43 85 2 1103394256 17292832 8861024
08:12:51 57426 8192000000 9989424736 10385313792 1082913280 11.675 100 41 82 1 1108298752 17234512 8150960
08:14:49 6305 8192000000 10025832288 10420178944 1087577552 11.720 100 53 105 2 1128392464 29260560 11554352
08:15:56 4389 8192000000 10001790720 10398715904 1092691040 11.768 100 53 111 2 1120473872 15131744 12651088
08:17:01 3867 8192000000 10012380400 10413047808 1095941248 11.799 100 38 75 0 1124079680 20429312 7709120
08:18:07 32103 8192000000 9978199504 10377494528 1039875072 11.263 96 37 73 2 1064423360 16149888 8398400
08:19:40 5543 8192000000 9982173984 10364121088 1035719264 11.224 98 45 90 1 1079621968 33941488 9961216
08:20:46 7373 8192000000 9943063376 10319802368 1015456384 11.028 99 38 76 2 1044852112 20875008 8520720
08:21:54 61736 8192000000 9947103456 10341937152 1015060448 11.024 97 33 66 0 1051244880 28718992 7465440
08:23:56 4380 8192000000 9952528768 10338476032 1035488560 11.221 99 37 73 5 1052865744 8634016 8743168
08:25:05 2699 8192000000 9983567424 10365403136 1057894704 11.436 100 54 110 3 1083451920 13990864 11566352
08:26:10 3378 8192000000 9997067968 10385612800 1065060800 11.505 100 64 128 0 1109137808 29664128 14412880
08:27:14 76754 8192000000 10001563536 10400051200 1071290160 11.564 100 56 110 2 1114395600 30007360 13098080
08:29:33 16026 8192000000 10059926688 10460823552 1092756848 11.769 100 57 112 2 1156241760 51798224 11686688
08:30:52 2284 8192000000 10082958992 10481192960 1103344384 11.869 100 68 135 1 1190485408 73058368 14082656
08:32:01 14075 8192000000 10097039040 10499567616 1113447328 11.965 100 52 104 1 1198736160 74050752 11238080
08:33:19 13846 8192000000 10130042400 10534305792 1125003088 12.074 100 52 104 1 1233120016 97324192 10792736
08:34:42 25169 8192000000 10136877856 10557001728 1133498032 12.154 100 45 87 1 1250148336 106356512 10293792
08:36:12 22311 8192000000 10176382192 10596540416 1143897856 12.252 100 46 87 0 1268594112 115398528 9297728
08:37:41 4749 8192000000 10228620832 10649243648 1169004544 12.488 100 57 114 1 1331062048 149318176 12739328
08:39:00 4820 8192000000 10235800672 10646732800 1172429936 12.520 100 64 127 0 1341901600 155053536 14418128
08:40:11 2446 8192000000 10247749168 10651488256 1180288352 12.593 100 81 159 2 1347057328 149576064 17192912
08:41:18 4841 8192000000 10299248928 10707886080 1177502864 12.567 99 82 163 0 1397727328 203160064 17064400
08:42:27 3138 8192000000 10301005936 10706620416 1158534176 12.390 99 72 143 0 1394255040 220540672 15180192
08:43:36 2966 8192000000 10323930880 10737594368 1169642624 12.493 100 84 165 0 1421053248 232334608 19076016
08:44:44 4573 8192000000 10355396080 10766483456 1176234656 12.555 100 62 123 1 1456944112 266261296 14448160
08:45:57 2227 8192000000 10387379984 10787225600 1170473936 12.501 100 89 177 2 1490684112 298928672 21281504
08:47:06 2282 8192000000 10410683424 10802593792 1141026560 12.225 99 92 182 0 1501813152 339941840 20844752
08:48:11 4347 8192000000 10436952512 10856513536 1157296736 12.378 100 57 112 0 1539920128 368695056 13928336
08:49:23 8956 8192000000 10429269072 10867208192 1167914544 12.477 100 48 96 0 1536484352 356197584 12372224
08:50:36 6209 8192000000 10428947248 10846781440 1146496080 12.277 98 54 108 0 1515613632 356031616 13085936
08:51:47 1513 8192000000 10453405680 10870915072 1145002560 12.263 100 52 104 1 1553178480 395898752 12277168
08:52:52 17588 8192000000 10366584336 10797060096 1140888272 12.224 100 37 74 2 1467265232 316743008 9633952
08:54:20 19372 8192000000 10365014432 10825637888 1134822240 12.167 99 23 46 0 1460807440 319884992 6100208
08:56:02 41354 8192000000 10269975888 10755993600 1120768320 12.034 99 27 54 1 1443249728 314680000 7801408
08:57:50 40254 8192000000 10064781696 10578182144 1132246160 12.143 100 18 37 1 1222641600 85905088 4490352
08:59:30 63598 8192000000 10071488864 10517319680 1110989872 11.942 98 17 33 0 1132525776 18281856 3254048
09:01:53 28994 8192000000 10063599872 10490281984 1131738272 12.138 100 57 112 2 1163928672 19769296 12421104
09:03:23 80783 8192000000 10086156208 10576273408 1105587072 11.891 97 17 34 1 1176777840 67733824 3456944
09:05:51 110472 8192000000 10077408544 10526806016 1066932624 11.523 94 13 26 0 1116449408 46789600 2727184
09:08:49 117177 8192000000 10094152032 10551939072 1124586944 12.070 99 27 54 1 1196223712 65319168 6317600
09:11:48 65406 8192000000 10179783744 10624208896 1151588416 12.324 100 56 112 0 1266870672 102513056 12769200
09:14:04 24214 8192000000 10147362160 10638688256 1137030928 12.188 99 10 20 0 1248434864 108521040 2882896
09:15:54 91926 8192000000 10056409840 10515656704 1131593024 12.136 99 14 28 0 1156955808 22908192 2454592
09:18:29 169481 8192000000 10150594960 10652405760 1141647488 12.231 99 10 20 1 1230554496 86864560 2042448
09:22:22 25190 8192000000 10118393504 10562879488 1149484176 12.305 99 22 43 2 1182246624 28288368 4474080
09:23:49 61730 8192000000 10146387520 10592342016 1122141632 12.047 97 27 54 0 1229274928 101199328 5933968
09:25:52 40670 8192000000 10145598880 10610040832 1132599072 12.146 99 18 36 1 1233960064 97184432 4176560
09:27:51 73384 8192000000 10162469664 10644377600 1133932480 12.158 99 29 58 0 1240358800 100124992 6301328
09:30:09 67156 8192000000 10088942960 10564624384 1108089744 11.914 97 20 40 0 1166494896 54445792 3959360
09:32:17 45806 8192000000 10132591264 10599960576 1118414384 12.012 98 24 48 1 1225117312 100320128 6382800
09:34:40 132888 8192000000 10088065584 10531045376 1094802192 11.788 96 21 41 1 1150247040 51267264 4177584
09:38:14 57712 8192000000 10052218848 10511667200 1111056432 11.942 98 14 27 1 1154149248 40275040 2817776
09:40:11 48384 8192000000 10082800464 10541555712 1127766560 12.100 99 16 31 0 1186561088 55545648 3248880
09:42:03 51642 8192000000 10076635040 10553393152 1116721712 11.996 99 8 16 0 1154205824 35871520 1612592
09:43:55 81177 8192000000 10107924224 10584133632 1113260128 11.963 98 23 47 1 1188947536 70435344 5252064
09:46:28 110229 8192000000 10102059120 10540552192 1105973760 11.894 97 27 52 1 1196813392 85004528 5835104
09:49:18 72028 8192000000 10089764864 10592395264 1058775040 11.445 94 29 58 1 1170379808 105653472 5951296
09:51:31 44444 8192000000 10072677344 10495438848 1100199920 11.840 98 28 56 0 1157548928 51750944 5598064
09:53:20 37746 8192000000 10096157248 10555494400 1109090736 11.924 98 36 72 2 1170787920 54610128 7087056
09:55:01 122485 8192000000 10060882736 10546528256 1065853872 11.512 94 22 42 0 1096954176 26861952 4238352
09:58:04 22304 8192000000 10084923152 10517114880 1126703728 12.090 100 45 88 2 1163490944 27450416 9336800
09:59:30 52311 8192000000 10064501744 10515267584 1115838608 11.988 98 7 13 1 1167190368 49863424 1488336
10:01:54 98784 8192000000 10077210864 10580275200 1120632864 12.033 99 14 28 1 1200796928 77078416 3085648
10:04:33 45524 8192000000 10090708368 10521182208 1107596800 11.910 98 28 56 3 1149167104 36073360 5496944
10:06:19 47204 8192000000 10114519936 10570297344 1119336880 12.021 98 25 50 1 1200031888 74885088 5809920
10:08:13 83922 8192000000 10140992000 10607656960 1134826928 12.167 99 19 39 0 1234977648 96174432 3976288
10:10:49 30937 8192000000 10120854880 10557956096 1132292304 12.143 98 37 68 2 1182353488 42965184 7096000
10:12:20 54730 8192000000 10188047328 10652745728 1136719520 12.185 98 27 54 0 1225528512 83195312 5613680
10:14:21 53099 8192000000 10084972576 10610581504 1123136816 12.057 99 6 12 0 1219175328 94632784 1405728
10:16:15 84504 8192000000 10049960880 10514960384 1119698528 12.024 99 12 24 2 1144765552 22921328 2145696
10:18:46 72097 8192000000 10055281088 10551873536 1123960800 12.064 99 6 12 0 1156456224 31532752 962672
10:20:58 49846 8192000000 10069609968 10544844800 1118135504 12.009 98 22 43 0 1159492672 37197520 4159648
10:23:15 45683 8192000000 10091696864 10550931456 1128744352 12.110 98 38 76 3 1168430048 31390560 8295136
10:25:10 65782 8192000000 10141153536 10591617024 1138752592 12.204 98 29 56 2 1205177264 60349440 6075232

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 22-03-2021 10:28:35 to 22-03-2021 10:28:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 79 v_add_invoice_to_stock 335544517
except 8 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 22-03-2021 10:28:50 to 22-03-2021 10:28:52.

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          225678
MON$OLDEST_ACTIVE               229939
MON$OLDEST_SNAPSHOT             229939
MON$NEXT_TRANSACTION            229939
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                       2110208
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             786
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2390 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
on disk structure version 13.0

Database statistics

Database statistics was not gathered, see config parameter 'run_db_statistics'.
===============================================================================

Database validation

Database validation was not performed, see config parameter 'run_db_validation'
===============================================================================

Comparison of old and new firebird.log: get messages that appeared during test

--- start of diff output ---
:191: 
:192: mscs-test-161.budzdorov.ru    Mon Mar 22 10:27:01 2021
:193:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:194: 
:195: 
:196: mscs-test-161.budzdorov.ru    Mon Mar 22 10:27:02 2021
:197:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:198: 
:199: 
:200: mscs-test-161.budzdorov.ru    Mon Mar 22 10:27:06 2021
:201:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:202: 
--- end of diff output ---

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

22.03.21 10:28:53. 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-03-22 10:28:56 No severe PSQL-related problems occured
22.03.21 10:28:57. End of report.