Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 02.09.2021 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 16044004 kB
MemAvailable 30946696 kB
Buffers 0 kB
Cached 15683644 kB
SwapCached 336 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-09-02 10:37:08
DTS_END                         2021-09-02 10:37:08.5250
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_SUPPLIER_ORDER(116:18) ==> SP_CANCEL_CUSTOMER_RESERVE(36:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/35064
TRN_ID                          230516
ATT_ID                          643
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: 8986.89 28962 1078427
customer order: creation 158.90 437 19068
customer order: refuse 13.61 329 1633
order to supplier: creation 44.07 1427 5288
order to supplier: removal 3.38 112574 406
invoice (draft): creation 43.98 3722 5278
invoice (draft): removal 50.96 925 6115
invoice accept: apply 40.23 19674 4827
invoice accept: cancel 46.82 860 5618
customer reserve: creation 3749.13 192 449895
customer reserve: removal 4547.66 65 545719
realization accept: apply 79.21 193 9505
realization accept: cancel 86.51 125 10381
payment from customer: creation 56.30 126 6756
payment from customer: removal 5.34 78 641
payment to supplier: creation 46.48 137 5577
payment to supplier: removal 3.53 87 423
service: total inventory turnovers 3.87 3804 464
service: total monetary turnovers 6.21 585 745
service: aggregate perf. data 0.70 25750 84
service: refresh index statistics 0.03 408156 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 02-09-2021 10:38:55 to 02-09-2021 10:38:57.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7265
TEST_TIME 2 7347
TEST_TIME 3 6574
TEST_TIME 4 6943
TEST_TIME 5 6961
TEST_TIME 6 7221
TEST_TIME 7 6656
TEST_TIME 8 6477
TEST_TIME 9 6739
TEST_TIME 10 7271
TEST_TIME 11 7488
TEST_TIME 12 7651
TEST_TIME 13 7906
TEST_TIME 14 8074
TEST_TIME 15 8200
TEST_TIME 16 8139
TEST_TIME 17 8191
TEST_TIME 18 8756
TEST_TIME 19 8940
TEST_TIME 20 9007
TEST_TIME 21 9139
TEST_TIME 22 9505
TEST_TIME 23 9262
TEST_TIME 24 9330
TEST_TIME 25 9765
TEST_TIME 26 9577
TEST_TIME 27 9691
TEST_TIME 28 10348
TEST_TIME 29 11396
TEST_TIME 30 11193
TEST_TIME 31 11073
TEST_TIME 32 11343
TEST_TIME 33 11034
TEST_TIME 34 10767
TEST_TIME 35 10642
TEST_TIME 36 10442
TEST_TIME 37 10380
TEST_TIME 38 10290
TEST_TIME 39 10099
TEST_TIME 40 9975
TEST_TIME 41 10014
TEST_TIME 42 9987
TEST_TIME 43 9908
TEST_TIME 44 9889
TEST_TIME 45 9717
TEST_TIME 46 9632
TEST_TIME 47 9650
TEST_TIME 48 9702
TEST_TIME 49 9615
TEST_TIME 50 9655
TEST_TIME 51 9602
TEST_TIME 52 9545
TEST_TIME 53 9632
TEST_TIME 54 9693
TEST_TIME 55 9665
TEST_TIME 56 9633
TEST_TIME 57 9570
TEST_TIME 58 9715
TEST_TIME 59 9735
TEST_TIME 60 9745
TEST_TIME 61 9672
TEST_TIME 62 9585
TEST_TIME 63 9439
TEST_TIME 64 9546
TEST_TIME 65 9433
TEST_TIME 66 9403
TEST_TIME 67 9441
TEST_TIME 68 9384
TEST_TIME 69 9333
TEST_TIME 70 9265
TEST_TIME 71 9169
TEST_TIME 72 9120
TEST_TIME 73 9167
TEST_TIME 74 9128
TEST_TIME 75 9201
TEST_TIME 76 9164
TEST_TIME 77 9285
TEST_TIME 78 9270
TEST_TIME 79 9254
TEST_TIME 80 9321
TEST_TIME 81 9313
TEST_TIME 82 9403
TEST_TIME 83 9362
TEST_TIME 84 9372
TEST_TIME 85 9442
TEST_TIME 86 9392
TEST_TIME 87 9306
TEST_TIME 88 9276
TEST_TIME 89 9251
TEST_TIME 90 9199
TEST_TIME 91 9155
TEST_TIME 92 9178
TEST_TIME 93 9125
TEST_TIME 94 9120
TEST_TIME 95 9135
TEST_TIME 96 9153
TEST_TIME 97 9162
TEST_TIME 98 9122
TEST_TIME 99 9169
TEST_TIME 100 9188
TEST_TIME 101 9201
TEST_TIME 102 9189
TEST_TIME 103 9256
TEST_TIME 104 9224
TEST_TIME 105 9298
TEST_TIME 106 9319
TEST_TIME 107 9311
TEST_TIME 108 9372
TEST_TIME 109 9325
TEST_TIME 110 9258
TEST_TIME 111 9265
TEST_TIME 112 9207
TEST_TIME 113 9205
TEST_TIME 114 9202
TEST_TIME 115 9193
TEST_TIME 116 9164
TEST_TIME 117 9117
TEST_TIME 118 9073
TEST_TIME 119 9052
TEST_TIME 120 8994

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 02-09-2021 10:38:59 to 02-09-2021 10:39:02.

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 1493621 1493621 0 0.00 0 17482 7 0 0
doc_list_biud 1493621 1493621 0 0.00 0 37010 30 0 0
sp_add_invoice_to_stock 4950 4827 123 2.48 40 205822 19674 0 123
sp_cancel_adding_invoice 5618 5618 0 0.00 5 78586 861 0 0
sp_cancel_client_order 1633 1633 0 0.00 4 11328 329 0 0
sp_cancel_customer_reserve 545719 545719 0 0.00 1 37016 65 0 0
sp_cancel_pay_from_customer 641 641 0 0.00 3 2843 79 0 0
sp_cancel_pay_to_supplier 423 423 0 0.00 3 6094 88 0 0
sp_cancel_supplier_invoice 6126 6115 11 0.17 2 35779 926 0 11
sp_cancel_supplier_order 406 406 0 0.00 12 1179077 112575 0 0
sp_cancel_write_off 10381 10381 0 0.00 1 32701 126 0 0
sp_client_order 19068 19068 0 0.00 4 32821 438 0 0
sp_customer_reserve 449895 449895 0 0.00 0 135960 192 0 0
sp_fill_shopping_cart 29630 29630 0 0.00 1 14103 45 0 0
sp_fill_shopping_cart_clo_res 449890 449890 0 0.00 0 17027 16 0 0
sp_get_clo_for_invoice 4819 4819 0 0.00 0 31355 379 0 0
sp_kill_cost_storno 17063 17063 0 0.00 0 17464 64 0 0
sp_kill_qstorno_ret_qs2qd 1108340 1108340 0 0.00 0 37003 35 0 0
sp_kill_qty_storno 584196 584196 0 0.00 0 37009 76 0 0
sp_lock_selected_doc 19837 19837 0 0.00 0 7692 7 0 0
sp_make_cost_storno 26657 26657 0 0.00 0 17282 54 0 0
sp_multiply_rows_for_pdistr 26657 26657 0 0.00 0 7829 33 0 0
sp_multiply_rows_for_qdistr 19068 19068 0 0.00 0 15387 175 0 0
sp_pay_from_customer 6756 6756 0 0.00 4 13001 126 0 0
sp_pay_to_supplier 5577 5577 0 0.00 5 17290 137 0 0
sp_payment_common 12333 12333 0 0.00 1 7514 21 0 0
sp_qd_handle_on_cancel_clo 1633 1633 0 0.00 0 11303 285 0 0
sp_qd_handle_on_invoice_upd_sts 10437 10437 0 0.00 0 29799 357 0 0
sp_qd_handle_on_reserve_upd_sts 9505 9505 0 0.00 0 32622 65 0 0
sp_reserve_write_off 9505 9505 0 0.00 7 32700 194 0 0
sp_supplier_invoice 5278 5278 0 0.00 7 67926 3722 0 0
sp_supplier_order 5288 5288 0 0.00 6 34884 1427 0 0
srv_aggregate_perf_data 84 84 0 0.00 2733 508326 25751 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1813 141545 45191 0 0
srv_find_qd_qs_mism 1032214 1032214 0 0.00 0 12900 6 0 0
srv_make_invnt_saldo 464 464 0 0.00 43 106890 3805 0 0
srv_make_money_saldo 745 745 0 0.00 1 15376 586 0 0
srv_recalc_idx_stat 4 4 0 0.00 217530 562561 408157 0 0
t$perf-abend:sp_add_invoice_to_stock 123 123 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4828 4828 0 0.00 0 18681 196 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 0 6156 118 0 0
t$perf-norm:sp_cancel_client_order 1633 1633 0 0.00 0 8022 22 0 0
t$perf-norm:sp_cancel_customer_reserve 641 641 0 0.00 0 3550 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 641 641 0 0.00 0 582 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 423 423 0 0.00 0 1878 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 371 371 0 0.00 0 1701 23 0 0
t$perf-norm:sp_cancel_supplier_order 406 406 0 0.00 0 21052 1734 0 0
t$perf-norm:sp_cancel_write_off 1012 1012 0 0.00 0 10188 19 0 0
t$perf-norm:sp_client_order 19068 19068 0 0.00 0 9472 20 0 0
t$perf-norm:sp_customer_reserve 960 960 0 0.00 0 1723 5 0 0
t$perf-norm:sp_pay_from_customer 6756 6756 0 0.00 0 13632 10 0 0
t$perf-norm:sp_pay_to_supplier 5577 5577 0 0.00 0 10980 10 0 0
t$perf-norm:sp_reserve_write_off 9505 9505 0 0.00 0 10743 16 0 0
t$perf-norm:sp_supplier_invoice 5278 5278 0 0.00 0 9228 27 0 0
t$perf-norm:sp_supplier_order 5288 5288 0 0.00 0 14939 24 0 0
t$perf-norm:srv_aggregate_perf_data 84 84 0 0.00 0 127 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 101 2 0 0
t$perf-norm:srv_make_invnt_saldo 464 464 0 0.00 0 5501 17 0 0
t$perf-norm:srv_make_money_saldo 745 745 0 0.00 0 8916 17 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 9884 9638 246 2.48 0 5949 20 0 246
v_all_customers 35160 35160 0 0.00 0 19 1 0 0
v_all_suppliers 29162 29162 0 0.00 0 25 1 0 0
v_all_wares 38136 38136 0 0.00 0 22 1 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 4552 23 0 0
v_cancel_client_order 3266 3266 0 0.00 0 776 3 0 0
v_cancel_customer_prepayment 1282 1282 0 0.00 0 1590 8 0 0
v_cancel_customer_reserve 1282 1282 0 0.00 0 3356 12 0 0
v_cancel_payment_to_supplier 846 846 0 0.00 0 475 5 0 0
v_cancel_supplier_invoice 764 742 22 2.87 0 2506 24 0 22
v_cancel_supplier_order 812 812 0 0.00 0 399 4 0 0
v_cancel_write_off 2024 2024 0 0.00 0 5838 19 0 0
v_max_id_clo_ord 5288 5288 0 0.00 0 823 2 0 0
v_max_id_clo_res 960 960 0 0.00 0 3496 9 0 0
v_max_id_ord_sup 5274 5274 0 0.00 0 4102 4 0 0
v_max_non_paid_invoice 1558 1558 0 0.00 0 154 2 0 0
v_max_non_paid_realizn 3513 3513 0 0.00 0 2913 3 0 0
v_min_id_clo_ord 5288 5288 0 0.00 0 1991 5 0 0
v_min_id_clo_res 960 960 0 0.00 0 695 3 0 0
v_min_id_ord_sup 5274 5274 0 0.00 0 7728 6 0 0
v_min_non_paid_invoice 5577 5577 0 0.00 0 4510 10 0 0
v_min_non_paid_realizn 6756 6756 0 0.00 0 5174 19 0 0
v_our_firm 9462 9462 0 0.00 0 22 1 0 0
v_reserve_write_off 19010 19010 0 0.00 0 4431 7 0 0
x_lock_depdocs_on_canc_invoice 11362 11362 0 0.00 0 33307 132 0 0
x_lock_depdocs_on_canc_sup_ord 406 406 0 0.00 0 16185 412 0 0
x_make_qstorno_1000_1200 5288 5288 0 0.00 2 34811 1340 0 0
x_make_qstorno_1200_2000 5278 5278 0 0.00 3 67920 3564 0 0
x_make_qstorno_2100_3300 449895 449895 0 0.00 0 135959 173 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 02-09-2021 10:39:03 to 02-09-2021 10:39:07.

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
08:07:12 1 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:13 16271 8192000000 9698130192 9987608576 771988320 8.612 100 85 170 0 800488864 7284528 21216016
08:09:36 21260 8192000000 9744607072 10047668224 816042528 9.059 100 79 158 0 846103040 10982768 19077744
08:11:09 18227 8192000000 9804418928 10121506816 865293264 9.553 100 85 170 2 906499328 21178832 20027232
08:12:36 11976 8192000000 9868651264 10206597120 922811840 10.124 100 65 130 0 981155984 44475280 13868864
08:13:51 8400 8192000000 9930470064 10276950016 964901744 10.537 100 86 170 1 1040399520 57482528 18015248
08:15:02 6102 8192000000 9960974400 10312794112 993746384 10.818 100 87 172 2 1086203760 74989824 17467552
08:16:09 3639 8192000000 10007148304 10369445888 1018612816 11.059 100 91 182 1 1126234800 88818864 18803120
08:17:15 1223 8192000000 10005158032 10371346432 1039629712 11.261 100 93 188 0 1122110720 63813152 18667856
08:18:18 1604 8192000000 10035404624 10406027264 1062885184 11.484 100 95 191 1 1145393408 62177280 20330944
08:19:21 2642 8192000000 10011615040 10404364288 1068410640 11.537 100 41 82 0 1138145472 61356176 8378656
08:20:25 4917 8192000000 9995576192 10387558400 1076463088 11.614 100 46 89 0 1112671680 26328368 9880224
08:21:30 5467 8192000000 10017428832 10412240896 1082393168 11.670 100 35 68 1 1126302896 36902528 7007200
08:22:36 4914 8192000000 10014322176 10398240768 1087370176 11.718 100 64 128 0 1110133216 8370720 14392320
08:23:42 7900 8192000000 10024556848 10435416064 1091767664 11.759 100 35 70 2 1132729968 33696288 7266016
08:24:51 7507 8192000000 10004084352 10418810880 1095400208 11.794 100 34 67 0 1133445200 31340752 6704240
08:25:59 22975 8192000000 9985053376 10401513472 1078789408 11.636 99 32 59 6 1107216464 21225152 7201904
08:27:32 9746 8192000000 9998617280 10411413504 1091321184 11.755 100 26 50 0 1118866016 21828208 5716624
08:28:43 5607 8192000000 9963750576 10358206464 1040420176 11.269 97 41 80 4 1065918112 16002992 9494944
08:29:51 3910 8192000000 9952593264 10332672000 1024349744 11.114 99 42 80 2 1056320496 22966736 9004016
08:30:57 71716 8192000000 9913542912 10307678208 929177456 10.187 91 21 42 1 947869872 14149568 4542848
08:33:09 7956 8192000000 9956294144 10346799104 1034359760 11.210 100 46 92 2 1060000528 14759104 10881664
08:34:20 13589 8192000000 9951890240 10357473280 1053350560 11.393 100 35 70 1 1078209008 17050224 7808224
08:35:34 66541 8192000000 9983424464 10375839744 1063968096 11.494 100 41 83 3 1093588176 20833184 8786896
08:37:45 10971 8192000000 10004066736 10403037184 1083022880 11.676 100 41 82 3 1117112624 25220000 8869744
08:39:01 4111 8192000000 10051473952 10449846272 1095939936 11.799 100 57 114 3 1161245456 53599312 11706208
08:40:10 18031 8192000000 10077648160 10482634752 1107282416 11.907 100 56 110 1 1180940640 61307232 12350992
08:41:30 37081 8192000000 10090951392 10514259968 1118274800 12.011 100 34 68 1 1194299296 68939440 7085056
08:43:11 14730 8192000000 10131253776 10538950656 1133810944 12.157 100 60 118 0 1230110176 84268960 12030272
08:44:44 21301 8192000000 10163961584 10576687104 1146125136 12.273 100 53 105 0 1274491632 116981072 11385424
08:46:09 13050 8192000000 10205048576 10629967872 1153120592 12.339 100 57 113 1 1314387984 148871008 12396384
08:47:30 2718 8192000000 10240623760 10651262976 1164826640 12.448 100 72 142 1 1346969680 166401344 15741696
08:48:37 11754 8192000000 10238543632 10655850496 1169513136 12.492 100 60 120 1 1348436080 166055008 12867936
08:49:52 1811 8192000000 10296981664 10697699328 1163879840 12.440 100 85 169 0 1389855760 207900544 18075376
08:50:59 3316 8192000000 10296210624 10702594048 1163148704 12.433 100 73 146 0 1398314144 218831104 16334336
08:52:06 4577 8192000000 10306634976 10718744576 1163985504 12.441 100 60 121 1 1402231648 224809792 13436352
08:53:14 4627 8192000000 10289198576 10687787008 1147458992 12.286 100 83 164 2 1383335456 217507104 18369360
08:54:30 2270 8192000000 10348898144 10751938560 1153280864 12.340 100 76 152 2 1447017920 276090624 17646432
08:55:37 4731 8192000000 10376594432 10796007424 1155871824 12.365 100 54 108 3 1479864032 311178144 12814064
08:56:46 15514 8192000000 10332684896 10739904512 1147460400 12.286 99 53 105 3 1436908048 276894256 12553392
08:58:03 11753 8192000000 10351786000 10790412288 1150724896 12.316 100 44 88 1 1462413632 301191776 10496960
08:59:31 8848 8192000000 10317415056 10751545344 1133114560 12.151 98 40 80 1 1411799984 269615168 9070256
09:00:47 5924 8192000000 10367107312 10777096192 1120471952 12.031 98 62 124 0 1442344016 306791584 15080480
09:01:57 12365 8192000000 10392123728 10842853376 1144239840 12.255 100 34 67 0 1504853760 351781904 8832016
09:03:27 15843 8192000000 10420555808 10853187584 1140213040 12.218 99 28 57 0 1515666784 367880624 7573120
09:04:49 14326 8192000000 10441034800 10895200256 1132036864 12.141 100 30 60 2 1538774864 398155184 8582816
09:06:10 27435 8192000000 10238406720 10735841280 1129534176 12.117 100 19 39 0 1375055248 240738080 4782992
09:07:39 50675 8192000000 10170471840 10628886528 1127925600 12.102 99 31 60 2 1267871728 133430992 6515136
09:09:34 74290 8192000000 10040847632 10477350912 1104569360 11.881 98 30 59 1 1145888448 35339536 5979552
09:12:08 6296 8192000000 10067408512 10494894080 1123654240 12.062 100 59 118 1 1166404016 29171152 13578624
09:13:15 98437 8192000000 10108525984 10574114816 1136091136 12.179 100 24 48 1 1184560112 43112720 5356256
09:16:09 73610 8192000000 10129452064 10581282816 1114746240 11.977 97 18 36 0 1198969312 80236640 3986432
09:18:32 97306 8192000000 10112322368 10580291584 1126907040 12.092 98 17 34 2 1210536768 80299728 3330000
09:21:09 84177 8192000000 10107088640 10604072960 1143802320 12.251 100 14 28 0 1217939232 71498240 2638672
09:24:13 94231 8192000000 10082846832 10551173120 1107567648 11.909 97 9 18 1 1206041232 96659248 1814336
09:26:47 64856 8192000000 10055046464 10512265216 1129232816 12.114 99 16 32 0 1160409584 27847536 3329232
09:28:52 43962 8192000000 10123234896 10591612928 1146845152 12.280 100 24 48 1 1225479456 73813472 4820832
09:30:47 93735 8192000000 10085427216 10574016512 1135240288 12.171 99 10 20 1 1187311088 50108336 1962464
09:33:22 62466 8192000000 10107790400 10574700544 1133922080 12.158 99 15 30 0 1185822512 48883136 3017296
09:35:24 76792 8192000000 10078568288 10564116480 1111881696 11.950 97 11 22 0 1207018352 92514864 2621792
09:37:43 77091 8192000000 10070014448 10503798784 1073064400 11.581 94 21 42 1 1113796368 36074960 4657008
09:40:06 115509 8192000000 10078790576 10544975872 1113775184 11.968 99 39 78 0 1206737632 84105424 8857024
09:43:03 11534 8192000000 10128722080 10565382144 1138905712 12.205 100 51 102 2 1224287728 74954752 10427264
09:44:15 36123 8192000000 10057443472 10559500288 1131590048 12.136 100 11 22 0 1161589760 27717760 2281952
09:45:51 32434 8192000000 10045379360 10484031488 1083760784 11.683 96 23 46 1 1110903456 22505344 4637328
09:47:43 23056 8192000000 10032119552 10477539328 1095258880 11.793 99 5 10 0 1144065072 47831168 975024
09:49:08 77636 8192000000 10047581920 10551918592 1106461536 11.899 98 19 38 1 1173241568 63033200 3746832
09:51:26 59517 8192000000 10101784624 10543087616 1089625936 11.739 96 14 28 0 1170177168 77505808 3045424
09:53:29 39304 8192000000 10118098000 10560573440 1112455408 11.956 98 31 62 1 1184282432 65577296 6249728
09:55:10 44749 8192000000 10089612320 10532061184 1101973456 11.856 97 21 42 1 1139476240 33317856 4184928
09:57:15 43496 8192000000 10081385408 10564751360 1081988080 11.666 97 22 43 1 1176851888 90105952 4757856
09:59:05 62445 8192000000 10075515376 10523504640 1108801408 11.921 99 19 38 2 1143392064 31122608 3468048
10:01:08 23183 8192000000 10099266368 10539073536 1107619856 11.910 99 38 76 2 1176156448 61264928 7271664
10:02:49 141545 8192000000 10064799584 10520567808 1113736640 11.968 98 18 36 0 1186316336 68734336 3845360
10:06:14 19532 8192000000 10099715456 10550263808 1135126816 12.170 100 22 44 0 1218002976 78211712 4664448
10:07:42 56971 8192000000 10101199008 10567467008 1142602144 12.240 100 10 20 0 1215263040 70346272 2314624
10:09:42 66855 8192000000 10116724320 10565894144 1127823072 12.101 98 15 30 0 1204174672 73140368 3211232
10:11:50 68781 8192000000 10087146128 10592354304 1131090096 12.132 99 10 20 0 1172478592 39150576 2237920
10:13:59 82493 8192000000 10087748560 10518609920 1114927664 11.979 97 30 58 2 1160163392 39110016 6125712
10:16:30 64427 8192000000 10091166912 10573725696 1151097680 12.320 100 30 60 0 1208586192 51247312 6241200
10:18:38 74991 8192000000 10126012512 10590777344 1152677952 12.335 100 14 27 2 1234264624 78924784 2661888
10:20:55 33328 8192000000 10093683616 10530254848 1142479040 12.239 99 28 56 1 1176487920 28345552 5663328
10:22:36 39131 8192000000 10115602816 10603704320 1117408880 12.003 97 18 35 0 1164721680 43813664 3499136
10:24:16 97426 8192000000 10085917008 10612142080 1128243568 12.105 98 15 30 0 1182713456 51628768 2841120
10:26:54 112026 8192000000 10080568480 10553819136 1155039072 12.357 100 24 48 0 1249812688 89257056 5516560
10:29:49 84293 8192000000 10115159536 10646011904 1125152176 12.076 98 15 30 0 1243835952 115210160 3473616
10:32:14 45821 8192000000 10047312800 10495717376 1132634656 12.146 100 17 34 0 1161772448 26051696 3086096
10:34:01 60994 8192000000 10052695744 10507481088 1124781744 12.072 99 14 28 0 1164607296 36733744 3091808
10:36:24 54703 8192000000 9340714560 9684901888 430197616 4.989 38 3 6 0 465751408 34970944 582848

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 02-09-2021 10:39:09 to 02-09-2021 10:39:24.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 123 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 02-09-2021 10:39:25 to 02-09-2021 10:39:27.

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          230837
MON$OLDEST_ACTIVE               230838
MON$OLDEST_SNAPSHOT             230838
MON$NEXT_TRANSACTION            230838
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                       2056096
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.1.2579 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2579 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2579 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2579 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 ---
:15: 
:16: mscs-test-161.budzdorov.ru    Thu Sep  2 10:37:30 2021
:17:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:18: 
:19: 
:20: mscs-test-161.budzdorov.ru    Thu Sep  2 10:37:30 2021
:21:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:22: 
:23: 
:24: mscs-test-161.budzdorov.ru    Thu Sep  2 10:37:30 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Thu Sep  2 10:37:30 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
:31: 
:32: mscs-test-161.budzdorov.ru    Thu Sep  2 10:37:30 2021
:33:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:34: 
--- end of diff output ---

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

02.09.21 10:39:28. 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-09-02 10:39:31 No severe PSQL-related problems occured
02.09.21 10:39:32. End of report.