Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 18.09.2021 07: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 16176724 kB
MemAvailable 30857856 kB
Buffers 0 kB
Cached 15558080 kB
SwapCached 416 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-18 07:37:07
DTS_END                         2021-09-18 07:37:07.2560
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/57882
TRN_ID                          233689
ATT_ID                          648
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 8681.97 24717 1041836
customer order: creation 171.25 441 20550
customer order: refuse 14.89 341 1787
order to supplier: creation 45.58 1546 5470
order to supplier: removal 3.49 110061 419
invoice (draft): creation 44.83 3522 5379
invoice (draft): removal 47.79 1057 5735
invoice accept: apply 40.87 19416 4904
invoice accept: cancel 42.58 959 5110
customer reserve: creation 3761.33 192 451359
customer reserve: removal 4222.08 72 506649
realization accept: apply 82.59 199 9911
realization accept: cancel 78.08 124 9369
payment from customer: creation 58.37 109 7004
payment from customer: removal 5.46 79 655
payment to supplier: creation 48.57 133 5828
payment to supplier: removal 3.24 109 389
service: total inventory turnovers 3.98 3647 477
service: total monetary turnovers 5.88 538 706
service: aggregate perf. data 1.09 11708 131
service: refresh index statistics 0.03 340087 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 18-09-2021 07:39:19 to 18-09-2021 07:39:21.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8540
TEST_TIME 2 6754
TEST_TIME 3 6307
TEST_TIME 4 5421
TEST_TIME 5 5549
TEST_TIME 6 5158
TEST_TIME 7 5002
TEST_TIME 8 4915
TEST_TIME 9 5493
TEST_TIME 10 5440
TEST_TIME 11 5820
TEST_TIME 12 5959
TEST_TIME 13 6343
TEST_TIME 14 6479
TEST_TIME 15 6817
TEST_TIME 16 7393
TEST_TIME 17 7717
TEST_TIME 18 8326
TEST_TIME 19 8348
TEST_TIME 20 8783
TEST_TIME 21 8918
TEST_TIME 22 8963
TEST_TIME 23 8936
TEST_TIME 24 9345
TEST_TIME 25 9612
TEST_TIME 26 10480
TEST_TIME 27 11306
TEST_TIME 28 11089
TEST_TIME 29 10774
TEST_TIME 30 10846
TEST_TIME 31 10578
TEST_TIME 32 10406
TEST_TIME 33 10266
TEST_TIME 34 10071
TEST_TIME 35 10161
TEST_TIME 36 9983
TEST_TIME 37 9899
TEST_TIME 38 9797
TEST_TIME 39 9757
TEST_TIME 40 9662
TEST_TIME 41 9550
TEST_TIME 42 9566
TEST_TIME 43 9513
TEST_TIME 44 9394
TEST_TIME 45 9272
TEST_TIME 46 9200
TEST_TIME 47 9172
TEST_TIME 48 9155
TEST_TIME 49 9153
TEST_TIME 50 9135
TEST_TIME 51 9241
TEST_TIME 52 9186
TEST_TIME 53 9218
TEST_TIME 54 9207
TEST_TIME 55 9248
TEST_TIME 56 9294
TEST_TIME 57 9243
TEST_TIME 58 9285
TEST_TIME 59 9185
TEST_TIME 60 9047
TEST_TIME 61 9000
TEST_TIME 62 9042
TEST_TIME 63 8999
TEST_TIME 64 8971
TEST_TIME 65 8947
TEST_TIME 66 8895
TEST_TIME 67 9000
TEST_TIME 68 9033
TEST_TIME 69 9007
TEST_TIME 70 9143
TEST_TIME 71 9084
TEST_TIME 72 9126
TEST_TIME 73 9087
TEST_TIME 74 9161
TEST_TIME 75 9247
TEST_TIME 76 9232
TEST_TIME 77 9164
TEST_TIME 78 9075
TEST_TIME 79 9068
TEST_TIME 80 9016
TEST_TIME 81 8938
TEST_TIME 82 8898
TEST_TIME 83 8905
TEST_TIME 84 8885
TEST_TIME 85 8855
TEST_TIME 86 8867
TEST_TIME 87 8902
TEST_TIME 88 8915
TEST_TIME 89 8893
TEST_TIME 90 8937
TEST_TIME 91 8951
TEST_TIME 92 8993
TEST_TIME 93 9004
TEST_TIME 94 9039
TEST_TIME 95 9033
TEST_TIME 96 9021
TEST_TIME 97 8952
TEST_TIME 98 8933
TEST_TIME 99 8890
TEST_TIME 100 8887
TEST_TIME 101 8849
TEST_TIME 102 8876
TEST_TIME 103 8867
TEST_TIME 104 8828
TEST_TIME 105 8849
TEST_TIME 106 8872
TEST_TIME 107 8925
TEST_TIME 108 8910
TEST_TIME 109 8922
TEST_TIME 110 8935
TEST_TIME 111 8947
TEST_TIME 112 8973
TEST_TIME 113 8923
TEST_TIME 114 8865
TEST_TIME 115 8886
TEST_TIME 116 8878
TEST_TIME 117 8841
TEST_TIME 118 8803
TEST_TIME 119 8767
TEST_TIME 120 8695

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 18-09-2021 07:39:22 to 18-09-2021 07:39:26.

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 1459208 1459208 0 0.00 0 20509 7 0 0
doc_list_biud 1459207 1459207 0 0.00 0 53832 32 0 0
sp_add_invoice_to_stock 5037 4904 133 2.64 40 195417 19417 0 133
sp_cancel_adding_invoice 5110 5110 0 0.00 5 72977 960 0 0
sp_cancel_client_order 1787 1787 0 0.00 5 16307 341 0 0
sp_cancel_customer_reserve 506649 506649 0 0.00 1 30338 72 0 0
sp_cancel_pay_from_customer 655 655 0 0.00 3 3231 80 0 0
sp_cancel_pay_to_supplier 389 389 0 0.00 3 5639 110 0 0
sp_cancel_supplier_invoice 5741 5735 6 0.10 2 53903 1058 0 6
sp_cancel_supplier_order 419 419 0 0.00 7 1059825 110061 0 0
sp_cancel_write_off 9369 9369 0 0.00 1 16357 124 0 0
sp_client_order 20550 20550 0 0.00 4 29688 441 0 0
sp_customer_reserve 451359 451359 0 0.00 0 55373 193 0 0
sp_fill_shopping_cart 31394 31394 0 0.00 1 14562 46 0 0
sp_fill_shopping_cart_clo_res 451336 451336 0 0.00 0 15192 17 0 0
sp_get_clo_for_invoice 4871 4871 0 0.00 0 20999 388 0 0
sp_kill_cost_storno 15523 15523 0 0.00 0 17100 66 0 0
sp_kill_qstorno_ret_qs2qd 1028821 1028821 0 0.00 0 53831 39 0 0
sp_kill_qty_storno 543850 543850 0 0.00 0 53832 84 0 0
sp_lock_selected_doc 20583 20583 0 0.00 0 8736 6 0 0
sp_make_cost_storno 27614 27614 0 0.00 0 20494 55 0 0
sp_multiply_rows_for_pdistr 27613 27613 0 0.00 0 8098 34 0 0
sp_multiply_rows_for_qdistr 20550 20550 0 0.00 0 25711 231 0 0
sp_pay_from_customer 7004 7004 0 0.00 4 13760 110 0 0
sp_pay_to_supplier 5828 5828 0 0.00 5 12600 134 0 0
sp_payment_common 12832 12832 0 0.00 1 6757 25 0 0
sp_qd_handle_on_cancel_clo 1787 1787 0 0.00 0 16301 307 0 0
sp_qd_handle_on_invoice_upd_sts 9980 9980 0 0.00 0 24881 356 0 0
sp_qd_handle_on_reserve_upd_sts 9911 9911 0 0.00 0 20328 72 0 0
sp_reserve_write_off 9911 9911 0 0.00 7 20379 200 0 0
sp_supplier_invoice 5379 5379 0 0.00 6 110750 3522 0 0
sp_supplier_order 5470 5470 0 0.00 6 63425 1547 0 0
srv_aggregate_perf_data 131 131 0 0.00 2695 135034 11709 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1107 160292 48222 0 0
srv_find_qd_qs_mism 994598 994598 0 0.00 0 19760 6 0 0
srv_make_invnt_saldo 477 477 0 0.00 45 102860 3648 0 0
srv_make_money_saldo 706 706 0 0.00 0 17999 538 0 0
srv_recalc_idx_stat 4 4 0 0.00 132424 540457 340087 0 0
t$perf-abend:sp_add_invoice_to_stock 133 133 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4904 4904 0 0.00 0 18570 160 0 0
t$perf-norm:sp_cancel_adding_invoice 429 429 0 0.00 0 4920 107 0 0
t$perf-norm:sp_cancel_client_order 1787 1787 0 0.00 0 2142 11 0 0
t$perf-norm:sp_cancel_customer_reserve 671 671 0 0.00 0 6095 16 0 0
t$perf-norm:sp_cancel_pay_from_customer 655 655 0 0.00 0 3565 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 389 389 0 0.00 0 125 1 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 1340 18 0 0
t$perf-norm:sp_cancel_supplier_order 419 419 0 0.00 0 38608 1497 0 0
t$perf-norm:sp_cancel_write_off 1030 1030 0 0.00 0 7810 22 0 0
t$perf-norm:sp_client_order 20550 20550 0 0.00 0 30942 21 0 0
t$perf-norm:sp_customer_reserve 963 963 0 0.00 0 2089 10 0 0
t$perf-norm:sp_pay_from_customer 7004 7004 0 0.00 0 33755 15 0 0
t$perf-norm:sp_pay_to_supplier 5828 5828 0 0.00 0 7809 13 0 0
t$perf-norm:sp_reserve_write_off 9911 9911 0 0.00 0 4791 8 0 0
t$perf-norm:sp_supplier_invoice 5379 5379 0 0.00 0 13216 20 0 0
t$perf-norm:sp_supplier_order 5470 5470 0 0.00 0 7519 16 0 0
t$perf-norm:srv_aggregate_perf_data 131 131 0 0.00 0 291 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 2833 61 0 0
t$perf-norm:srv_make_invnt_saldo 477 477 0 0.00 0 5043 23 0 0
t$perf-norm:srv_make_money_saldo 706 706 0 0.00 0 154 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 68 23 0 0
v_add_invoice_to_stock 10006 9740 266 2.65 0 8415 19 0 266
v_all_customers 37778 37778 0 0.00 0 46 1 0 0
v_all_suppliers 30304 30304 0 0.00 0 20 1 0 0
v_all_wares 41100 41100 0 0.00 0 74 1 0 0
v_cancel_adding_invoice 858 858 0 0.00 0 126 6 0 0
v_cancel_client_order 3574 3574 0 0.00 0 2106 4 0 0
v_cancel_customer_prepayment 1310 1310 0 0.00 0 2118 10 0 0
v_cancel_customer_reserve 1342 1342 0 0.00 0 1518 5 0 0
v_cancel_payment_to_supplier 778 778 0 0.00 0 5271 16 0 0
v_cancel_supplier_invoice 856 844 12 1.40 0 2779 18 0 12
v_cancel_supplier_order 838 838 0 0.00 0 298 4 0 0
v_cancel_write_off 2060 2060 0 0.00 0 2106 13 0 0
v_max_id_clo_ord 5470 5470 0 0.00 0 986 1 0 0
v_max_id_clo_res 963 963 0 0.00 0 3646 17 0 0
v_max_id_ord_sup 5374 5374 0 0.00 0 6658 5 0 0
v_max_non_paid_invoice 1520 1520 0 0.00 0 410 2 0 0
v_max_non_paid_realizn 3644 3644 0 0.00 0 3427 4 0 0
v_min_id_clo_ord 5470 5470 0 0.00 0 5364 8 0 0
v_min_id_clo_res 963 963 0 0.00 0 4089 10 0 0
v_min_id_ord_sup 5374 5374 0 0.00 0 6555 14 0 0
v_min_non_paid_invoice 5828 5828 0 0.00 0 6754 19 0 0
v_min_non_paid_realizn 7004 7004 0 0.00 0 6677 19 0 0
v_our_firm 10042 10042 0 0.00 0 17 1 0 0
v_reserve_write_off 19822 19822 0 0.00 0 5435 5 0 0
x_lock_depdocs_on_canc_invoice 10423 10423 0 0.00 0 21867 114 0 0
x_lock_depdocs_on_canc_sup_ord 419 419 0 0.00 0 11485 320 0 0
x_make_qstorno_1000_1200 5470 5470 0 0.00 2 63402 1471 0 0
x_make_qstorno_1200_2000 5379 5379 0 0.00 3 105084 3338 0 0
x_make_qstorno_2100_3300 451358 451358 0 0.00 0 55372 174 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 18-09-2021 07:39:27 to 18-09-2021 07:39:31.

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
05:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:11 19027 8192000000 9724408752 10023452672 807763072 8.975 100 86 172 0 836219328 7612768 20843488
05:09:36 12342 8192000000 9773888144 10083848192 851355088 9.414 100 87 175 0 883669664 11330512 20984064
05:10:54 20941 8192000000 9802453232 10120568832 869483616 9.595 100 84 168 0 907824480 18756240 19584624
05:12:18 12846 8192000000 9863484352 10195415040 911824048 10.015 100 76 152 0 969774800 41554752 16396000
05:13:36 13119 8192000000 9903511104 10247442432 957204848 10.462 100 74 146 1 1015166224 42764144 15197232
05:14:52 7177 8192000000 9967855904 10321342464 988021600 10.762 100 87 174 0 1077728864 71868112 17839152
05:16:02 3609 8192000000 10019977728 10383319040 1017523776 11.048 100 88 176 2 1135435264 99750080 18161408
05:17:07 1570 8192000000 10012880592 10376134656 1041093808 11.275 100 92 185 0 1130019264 70895776 18029680
05:18:11 1164 8192000000 10055425648 10423361536 1054927424 11.408 100 94 189 1 1158779200 84430656 19421120
05:19:13 12718 8192000000 9993496624 10369056768 1065634592 11.510 100 75 150 1 1097542848 15554464 16353792
05:20:26 7349 8192000000 9989898528 10389028864 1073901360 11.589 100 34 69 0 1116127232 34784432 7441440
05:21:35 3518 8192000000 9997989200 10391699456 1076823536 11.617 100 49 93 2 1107611696 20124544 10663616
05:22:39 11015 8192000000 10011302736 10425569280 1082638640 11.673 100 27 54 1 1111502224 23367168 5496416
05:23:51 80576 8192000000 10000225696 10402537472 1079559968 11.643 99 43 87 1 1104628992 15900176 9168848
05:26:12 3087 8192000000 10013319360 10416214016 1093233632 11.773 100 51 102 2 1134199296 28254848 12710816
05:27:18 17011 8192000000 9992430784 10405769216 1060406272 11.460 97 17 34 1 1087314176 23492272 3415632
05:28:35 5464 8192000000 9985116512 10384113664 1046070048 11.323 98 40 81 3 1081520032 27016464 8433520
05:29:41 6131 8192000000 9948420528 10340782080 1008628672 10.962 96 24 47 2 1033320976 19532240 5160064
05:30:47 37937 8192000000 9947578240 10333782016 991630336 10.797 96 30 57 3 1026484896 28227088 6627472
05:32:27 4313 8192000000 9958328544 10340921344 1025208320 11.122 99 49 98 1 1057162960 21991520 9963120
05:33:35 11331 8192000000 9964684368 10362662912 1042376304 11.287 100 33 66 1 1079344464 28730304 8237856
05:34:51 17493 8192000000 9969554448 10369748992 1064190272 11.497 100 27 54 2 1088075376 16654864 7230240
05:36:35 7662 8192000000 9984184976 10392576000 1065764800 11.512 100 29 58 0 1095320880 22636672 6919408
05:37:49 23047 8192000000 10029174032 10426535936 1095050112 11.791 100 53 107 1 1136490304 30721168 10719024
05:39:16 17487 8192000000 10066695920 10469326848 1116879984 11.998 100 53 106 1 1186559808 58933744 10746080
05:40:37 99185 8192000000 10117125216 10538856448 1128891872 12.111 100 38 76 0 1239028000 101367328 8768800
05:43:21 14986 8192000000 10138608032 10557042688 1141596704 12.231 100 35 70 1 1244994816 95532624 7865488
05:44:41 20725 8192000000 10206081472 10623811584 1152550688 12.333 100 55 108 2 1307329280 142259200 12519392
05:46:09 9980 8192000000 10239903584 10659483648 1172995408 12.525 100 43 86 2 1343457696 160527504 9934784
05:47:24 1914 8192000000 10300283984 10712264704 1185855792 12.645 100 83 163 1 1398629424 194601760 18171872
05:48:29 2914 8192000000 10316134304 10726498304 1170268752 12.499 99 82 164 0 1411216912 222176208 18771952
05:49:35 3729 8192000000 10356694112 10770702336 1178124704 12.573 99 79 156 0 1446730032 250538864 18066464
05:50:46 2430 8192000000 10394594896 10798989312 1184447984 12.632 99 86 171 1 1480454272 276474000 19532288
05:51:54 3002 8192000000 10445357104 10861645824 1206496432 12.837 100 89 177 0 1538878560 312175696 20206432
05:53:06 2495 8192000000 10441088288 10855084032 1192622464 12.708 100 77 153 0 1539329424 327616720 19090240
05:54:14 1106 8192000000 10426460160 10842624000 1183996624 12.627 100 97 195 1 1521288160 315315936 21975600
05:55:19 5448 8192000000 10418928848 10866167808 1180668288 12.596 100 55 110 2 1515859232 321912800 13278144
05:56:31 9285 8192000000 10418457472 10874310656 1181498192 12.604 100 46 92 0 1533752656 340553536 11700928
05:57:52 8253 8192000000 10373987664 10795290624 1159893856 12.402 99 45 87 2 1469807088 299280416 10632816
05:59:03 15749 8192000000 10397195568 10847207424 1157822288 12.383 99 37 74 0 1492137424 325254896 9060240
06:00:44 12712 8192000000 10444603344 10888306688 1159796432 12.401 100 35 70 0 1526238416 357054464 9387520
06:02:28 6779 8192000000 10381881072 10816196608 1133820880 12.157 100 35 68 1 1471281952 328690272 8770800
06:03:37 18399 8192000000 10245490160 10730975232 1110901248 11.941 99 26 52 1 1323187824 205800016 6486560
06:05:09 96232 8192000000 10064442880 10489028608 1099152176 11.830 98 35 70 0 1148866704 42397904 7316624
06:07:59 56966 8192000000 10066444064 10493542400 1106810048 11.902 98 21 41 0 1145723440 35153824 3759568
06:10:04 77676 8192000000 10119748736 10561691648 1117640016 12.005 98 22 44 1 1185894288 63548912 4705360
06:12:22 155176 8192000000 10105886800 10593845248 1124437968 12.069 99 15 30 0 1211384752 82954640 3992144
06:16:12 39963 8192000000 10068341664 10530701312 1126037360 12.084 100 23 46 0 1192340880 61718576 4584944
06:17:56 31664 8192000000 10099348480 10535411712 1143596960 12.249 100 34 68 1 1198283200 47251008 7435232
06:19:38 48425 8192000000 10067416864 10529718272 1143562256 12.249 100 17 33 0 1199920080 52323120 4034704
06:21:46 70549 8192000000 10054022512 10521366528 1119089232 12.018 98 12 24 0 1148067888 26483264 2495392
06:23:57 52776 8192000000 10069960320 10524200960 1139460576 12.210 100 17 34 0 1180194832 36457744 4276512
06:26:00 23658 8192000000 10088084752 10530381824 1123546592 12.060 98 30 60 0 1167092448 37321984 6223872
06:27:32 52392 8192000000 10121390080 10597220352 1146671872 12.278 100 29 58 1 1214791456 61647584 6472000
06:29:33 25345 8192000000 10118187440 10554847232 1138710320 12.203 100 34 68 0 1198149552 52821760 6617472
06:31:46 29745 8192000000 10133593328 10598510592 1143370384 12.247 99 48 96 0 1233140128 80319584 9450160
06:33:21 61966 8192000000 10074164080 10516664320 1117263312 12.001 97 24 48 1 1162505680 40404240 4838128
06:35:23 37944 8192000000 10084715616 10521292800 1149729232 12.307 100 25 50 1 1187415072 32445104 5240736
06:37:02 109780 8192000000 10139469168 10613096448 1109016624 11.923 96 29 58 0 1210829408 96170704 5642080
06:40:00 27957 8192000000 10042632064 10511900672 1105636592 11.891 97 7 13 2 1142990928 36151728 1202608
06:41:28 50980 8192000000 10118067328 10574503936 1123285424 12.058 99 20 40 1 1196343696 69040080 4018192
06:43:19 21987 8192000000 10141972304 10576965632 1127268160 12.096 99 35 71 0 1228445824 93239264 7938400
06:44:43 64111 8192000000 10083489376 10544738304 1093992112 11.781 96 14 28 0 1142056144 44853840 3210192
06:46:48 63369 8192000000 10121205632 10595172352 1106089728 11.895 97 16 32 0 1191579344 81985056 3504560
06:49:07 61657 8192000000 10063600944 10512084992 1057949504 11.437 94 31 61 0 1115246992 50937152 6360336
06:51:09 38774 8192000000 10144102000 10620870656 1125531136 12.079 100 44 88 2 1222556768 88072064 8953568
06:52:54 48334 8192000000 10074779120 10506031104 1098435824 11.823 97 29 59 0 1141605296 36957840 6211632
06:54:45 96679 8192000000 10045723472 10521448448 1124469936 12.069 100 18 36 2 1161083808 32830096 3783776
06:57:25 61165 8192000000 10056738608 10512269312 1112684016 11.958 98 10 20 0 1144496896 29712000 2100880
06:59:44 77579 8192000000 10043711392 10550001664 1077832608 11.627 96 8 16 0 1123081120 43672336 1576176
07:02:04 66966 8192000000 10110571712 10550263808 1124470512 12.069 100 21 42 0 1198233968 69522224 4241232
07:04:12 71607 8192000000 10065533648 10516135936 1075638736 11.606 95 15 30 0 1148021072 69193856 3188480
07:06:32 39498 8192000000 10084967808 10529370112 1106207184 11.896 99 32 64 0 1162371776 49762656 6401936
07:08:12 79454 8192000000 10091834304 10539675648 1079286064 11.641 95 26 52 1 1134736544 50018608 5431872
07:10:43 90511 8192000000 10070953472 10503270400 1103122208 11.867 97 11 22 0 1149316928 44374000 1820720
07:13:13 67789 8192000000 10080952128 10532974592 1121101824 12.037 98 22 44 1 1160852368 35526000 4224544
07:15:27 71886 8192000000 10064372848 10549403648 1140393088 12.219 100 7 14 0 1169114512 27557904 1163520
07:17:45 60688 8192000000 10071096912 10497728512 1120041408 12.027 98 30 60 1 1141193424 14913392 6238624
07:19:49 107739 8192000000 10111255632 10555334656 1133883408 12.158 99 11 22 0 1173549728 37607520 2058800
07:22:39 110834 8192000000 10097124704 10548203520 1101094016 11.848 96 15 29 1 1191715552 87004752 3616784
07:25:30 46564 8192000000 10086368800 10537222144 1095465120 11.795 96 35 69 1 1144953904 42595024 6893760
07:27:21 76548 8192000000 10095166944 10554163200 1123218704 12.057 99 18 36 0 1173489232 46834624 3435904
07:29:41 160292 8192000000 10079362752 10559283200 1118412320 12.012 98 26 52 0 1204291168 80649888 5228960
07:33:21 48740 8192000000 10069697680 10545999872 1117834736 12.007 98 16 32 1 1172901280 51701600 3364944
07:35:10 36644 8192000000 10090088032 10563506176 1125969184 12.083 99 8 16 0 1179511040 51878448 1663408
07:36:47 39647 8192000000 9145212496 9457389568 285729168 3.370 25 10 16 1 313668848 26096768 1842912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 18-09-2021 07:39:33 to 18-09-2021 07:39:48.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 133 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 18-09-2021 07:39:49 to 18-09-2021 07:39:50.

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          234321
MON$OLDEST_ACTIVE               234322
MON$OLDEST_SNAPSHOT             234322
MON$NEXT_TRANSACTION            234322
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                       2028000
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             795
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2604 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2604 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2604 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2604 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 ---
:50: 
:51: mscs-test-161.budzdorov.ru    Sat Sep 18 07:37:34 2021
:52:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:53: 
:54: 
:55: mscs-test-161.budzdorov.ru    Sat Sep 18 07:37:49 2021
:56:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:57: 
--- end of diff output ---

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

18.09.21 07:39:52. 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-18 07:39:54 No severe PSQL-related problems occured
18.09.21 07:39:55. End of report.