Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 23.08.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 16482100 kB
MemAvailable 31006740 kB
Buffers 0 kB
Cached 15252032 kB
SwapCached 2420 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-08-23 10:37:06
DTS_END                         2021-08-23 10:37:06.5820
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/46132
TRN_ID                          226060
ATT_ID                          639
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: 8641.03 22829 1036923
customer order: creation 162.60 371 19512
customer order: refuse 13.65 316 1638
order to supplier: creation 42.67 1380 5120
order to supplier: removal 3.25 107861 390
invoice (draft): creation 43.72 3331 5246
invoice (draft): removal 48.44 945 5813
invoice accept: apply 39.73 17752 4768
invoice accept: cancel 44.08 839 5290
customer reserve: creation 3645.92 176 437510
customer reserve: removal 4316.27 64 517952
realization accept: apply 79.05 170 9486
realization accept: cancel 81.10 129 9732
payment from customer: creation 55.36 107 6643
payment from customer: removal 5.58 76 669
payment to supplier: creation 46.21 122 5545
payment to supplier: removal 3.14 70 377
service: total inventory turnovers 3.60 3436 432
service: total monetary turnovers 5.73 494 687
service: aggregate perf. data 0.91 17498 109
service: refresh index statistics 0.03 301445 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 23-08-2021 10:38:53 to 23-08-2021 10:38:55.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4578
TEST_TIME 2 5202
TEST_TIME 3 5008
TEST_TIME 4 4671
TEST_TIME 5 4763
TEST_TIME 6 4801
TEST_TIME 7 4572
TEST_TIME 8 4915
TEST_TIME 9 4982
TEST_TIME 10 5285
TEST_TIME 11 5678
TEST_TIME 12 6714
TEST_TIME 13 6959
TEST_TIME 14 7106
TEST_TIME 15 7874
TEST_TIME 16 7894
TEST_TIME 17 8697
TEST_TIME 18 9312
TEST_TIME 19 9413
TEST_TIME 20 9229
TEST_TIME 21 9575
TEST_TIME 22 10452
TEST_TIME 23 10418
TEST_TIME 24 10663
TEST_TIME 25 10406
TEST_TIME 26 10250
TEST_TIME 27 9951
TEST_TIME 28 9720
TEST_TIME 29 9691
TEST_TIME 30 9714
TEST_TIME 31 9664
TEST_TIME 32 9591
TEST_TIME 33 9397
TEST_TIME 34 9251
TEST_TIME 35 9173
TEST_TIME 36 9035
TEST_TIME 37 8966
TEST_TIME 38 9564
TEST_TIME 39 9460
TEST_TIME 40 9363
TEST_TIME 41 9364
TEST_TIME 42 9467
TEST_TIME 43 9397
TEST_TIME 44 9377
TEST_TIME 45 9395
TEST_TIME 46 9415
TEST_TIME 47 9574
TEST_TIME 48 9509
TEST_TIME 49 9541
TEST_TIME 50 9429
TEST_TIME 51 9280
TEST_TIME 52 9262
TEST_TIME 53 9261
TEST_TIME 54 9201
TEST_TIME 55 9139
TEST_TIME 56 9052
TEST_TIME 57 9021
TEST_TIME 58 8898
TEST_TIME 59 8898
TEST_TIME 60 8953
TEST_TIME 61 8925
TEST_TIME 62 8974
TEST_TIME 63 9016
TEST_TIME 64 9082
TEST_TIME 65 9213
TEST_TIME 66 9221
TEST_TIME 67 9229
TEST_TIME 68 9200
TEST_TIME 69 9197
TEST_TIME 70 9220
TEST_TIME 71 9222
TEST_TIME 72 9290
TEST_TIME 73 9344
TEST_TIME 74 9251
TEST_TIME 75 9158
TEST_TIME 76 9107
TEST_TIME 77 9082
TEST_TIME 78 9008
TEST_TIME 79 8969
TEST_TIME 80 8934
TEST_TIME 81 8917
TEST_TIME 82 8892
TEST_TIME 83 8900
TEST_TIME 84 8875
TEST_TIME 85 8883
TEST_TIME 86 8851
TEST_TIME 87 8823
TEST_TIME 88 8869
TEST_TIME 89 8920
TEST_TIME 90 8878
TEST_TIME 91 8986
TEST_TIME 92 8978
TEST_TIME 93 8997
TEST_TIME 94 9004
TEST_TIME 95 8924
TEST_TIME 96 8928
TEST_TIME 97 8891
TEST_TIME 98 8829
TEST_TIME 99 8779
TEST_TIME 100 8764
TEST_TIME 101 8810
TEST_TIME 102 8803
TEST_TIME 103 8807
TEST_TIME 104 8797
TEST_TIME 105 8771
TEST_TIME 106 8838
TEST_TIME 107 8816
TEST_TIME 108 8865
TEST_TIME 109 8830
TEST_TIME 110 8874
TEST_TIME 111 8939
TEST_TIME 112 8939
TEST_TIME 113 8919
TEST_TIME 114 8880
TEST_TIME 115 8839
TEST_TIME 116 8811
TEST_TIME 117 8784
TEST_TIME 118 8746
TEST_TIME 119 8719
TEST_TIME 120 8649

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 23-08-2021 10:38:56 to 23-08-2021 10:39:00.

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 1442980 1442980 0 0.00 0 23136 7 0 0
doc_list_biud 1442980 1442980 0 0.00 0 51888 29 0 0
sp_add_invoice_to_stock 4868 4768 100 2.05 39 309976 17752 0 100
sp_cancel_adding_invoice 5290 5290 0 0.00 3 77439 840 0 0
sp_cancel_client_order 1638 1638 0 0.00 4 10615 317 0 0
sp_cancel_customer_reserve 517952 517952 0 0.00 1 51894 65 0 0
sp_cancel_pay_from_customer 669 669 0 0.00 3 6108 76 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 8967 70 0 0
sp_cancel_supplier_invoice 5819 5813 6 0.10 2 18844 946 0 6
sp_cancel_supplier_order 390 390 0 0.00 11 995230 107862 0 0
sp_cancel_write_off 9732 9732 0 0.00 1 18050 130 0 0
sp_client_order 19512 19512 0 0.00 4 21159 371 0 0
sp_customer_reserve 437510 437510 0 0.00 0 41748 177 0 0
sp_fill_shopping_cart 29868 29868 0 0.00 1 11852 49 0 0
sp_fill_shopping_cart_clo_res 437499 437499 0 0.00 0 21120 19 0 0
sp_get_clo_for_invoice 4757 4757 0 0.00 0 32950 474 0 0
sp_kill_cost_storno 16068 16068 0 0.00 0 7975 55 0 0
sp_kill_qstorno_ret_qs2qd 1051839 1051839 0 0.00 0 51885 36 0 0
sp_kill_qty_storno 555057 555057 0 0.00 0 51888 76 0 0
sp_lock_selected_doc 19721 19721 0 0.00 0 10597 4 0 0
sp_make_cost_storno 26430 26430 0 0.00 0 22226 52 0 0
sp_multiply_rows_for_pdistr 26430 26430 0 0.00 0 11319 31 0 0
sp_multiply_rows_for_qdistr 19512 19512 0 0.00 0 21145 212 0 0
sp_pay_from_customer 6643 6643 0 0.00 4 17008 108 0 0
sp_pay_to_supplier 5545 5545 0 0.00 4 27682 122 0 0
sp_payment_common 12188 12188 0 0.00 1 12319 23 0 0
sp_qd_handle_on_cancel_clo 1638 1638 0 0.00 0 10607 284 0 0
sp_qd_handle_on_invoice_upd_sts 10046 10046 0 0.00 0 34618 307 0 0
sp_qd_handle_on_reserve_upd_sts 9486 9486 0 0.00 0 11297 55 0 0
sp_reserve_write_off 9486 9486 0 0.00 7 28626 170 0 0
sp_supplier_invoice 5246 5246 0 0.00 6 66381 3331 0 0
sp_supplier_order 5120 5120 0 0.00 6 32948 1381 0 0
srv_aggregate_perf_data 109 109 0 0.00 2845 322739 17499 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 1480 116102 49802 0 0
srv_find_qd_qs_mism 992408 992408 0 0.00 0 11162 6 0 0
srv_make_invnt_saldo 432 432 0 0.00 33 111405 3436 0 0
srv_make_money_saldo 687 687 0 0.00 1 15097 494 0 0
srv_recalc_idx_stat 4 4 0 0.00 207910 530022 301445 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 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 4768 4768 0 0.00 0 14139 162 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 0 5351 73 0 0
t$perf-norm:sp_cancel_client_order 1638 1638 0 0.00 0 1792 8 0 0
t$perf-norm:sp_cancel_customer_reserve 682 682 0 0.00 0 5143 26 0 0
t$perf-norm:sp_cancel_pay_from_customer 669 669 0 0.00 0 2926 9 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 337 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 367 367 0 0.00 0 3654 20 0 0
t$perf-norm:sp_cancel_supplier_order 390 390 0 0.00 0 38906 1247 0 0
t$perf-norm:sp_cancel_write_off 971 971 0 0.00 0 1460 9 0 0
t$perf-norm:sp_client_order 19512 19512 0 0.00 0 13719 19 0 0
t$perf-norm:sp_customer_reserve 983 983 0 0.00 0 2855 11 0 0
t$perf-norm:sp_pay_from_customer 6643 6643 0 0.00 0 8201 8 0 0
t$perf-norm:sp_pay_to_supplier 5545 5545 0 0.00 0 9507 13 0 0
t$perf-norm:sp_reserve_write_off 9486 9486 0 0.00 0 9529 14 0 0
t$perf-norm:sp_supplier_invoice 5246 5246 0 0.00 0 7025 18 0 0
t$perf-norm:sp_supplier_order 5120 5120 0 0.00 0 7878 17 0 0
t$perf-norm:srv_aggregate_perf_data 109 109 0 0.00 0 90 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 12 1 0 0
t$perf-norm:srv_make_invnt_saldo 432 432 0 0.00 0 9145 29 0 0
t$perf-norm:srv_make_money_saldo 687 687 0 0.00 0 904 4 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 4 3 0 0
v_add_invoice_to_stock 9712 9512 200 2.05 0 9664 16 0 200
v_all_customers 35364 35364 0 0.00 0 106 1 0 0
v_all_suppliers 28738 28738 0 0.00 0 13 1 0 0
v_all_wares 39024 39024 0 0.00 0 88 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 3133 11 0 0
v_cancel_client_order 3276 3276 0 0.00 0 6288 5 0 0
v_cancel_customer_prepayment 1338 1338 0 0.00 0 6100 10 0 0
v_cancel_customer_reserve 1364 1364 0 0.00 0 301 3 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 6061 10 0 0
v_cancel_supplier_invoice 746 734 12 1.60 0 706 12 0 12
v_cancel_supplier_order 780 780 0 0.00 0 2165 7 0 0
v_cancel_write_off 1942 1942 0 0.00 0 10579 17 0 0
v_max_id_clo_ord 5120 5120 0 0.00 0 1569 1 0 0
v_max_id_clo_res 983 983 0 0.00 0 14198 38 0 0
v_max_id_ord_sup 5236 5236 0 0.00 0 8145 7 0 0
v_max_non_paid_invoice 1532 1532 0 0.00 0 2245 4 0 0
v_max_non_paid_realizn 3616 3616 0 0.00 0 3184 4 0 0
v_min_id_clo_ord 5120 5120 0 0.00 0 5487 5 0 0
v_min_id_clo_res 983 983 0 0.00 0 4050 16 0 0
v_min_id_ord_sup 5236 5236 0 0.00 0 11812 12 0 0
v_min_non_paid_invoice 5545 5545 0 0.00 0 6282 18 0 0
v_min_non_paid_realizn 6643 6643 0 0.00 0 6559 15 0 0
v_our_firm 9714 9714 0 0.00 0 28 1 0 0
v_reserve_write_off 18972 18972 0 0.00 0 9631 5 0 0
x_lock_depdocs_on_canc_invoice 10736 10736 0 0.00 0 14898 102 0 0
x_lock_depdocs_on_canc_sup_ord 390 390 0 0.00 0 8382 283 0 0
x_make_qstorno_1000_1200 5120 5120 0 0.00 2 32938 1311 0 0
x_make_qstorno_1200_2000 5246 5246 0 0.00 3 66327 3130 0 0
x_make_qstorno_2100_3300 437510 437510 0 0.00 0 40957 155 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 23-08-2021 10:39:01 to 23-08-2021 10:39:05.

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:10 1 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:11 19452 8192000000 9710086720 10003779584 785803520 8.752 100 86 172 0 814330880 7560384 20966976
08:09:35 25600 8192000000 9757065312 10058428416 825502288 9.154 100 91 182 1 859798768 12374640 21921840
08:11:14 18698 8192000000 9803548928 10122022912 862765072 9.528 100 81 162 1 905450448 24135408 18549968
08:12:41 11897 8192000000 9866917360 10202013696 919540336 10.092 100 80 159 1 976585680 39592496 17452848
08:13:57 8103 8192000000 9920417712 10265587712 962960336 10.518 100 73 146 0 1031147648 53205264 14982048
08:15:08 5202 8192000000 9975635696 10335625216 1001686512 10.895 100 76 151 3 1089258560 71965584 15606464
08:16:15 3070 8192000000 9989286768 10352783360 1028229984 11.151 100 80 157 0 1106606464 62853216 15523264
08:17:21 2100 8192000000 10032929424 10396336128 1045988112 11.322 100 90 179 1 1139857632 75904192 17965328
08:18:26 1560 8192000000 10044757360 10414141440 1059087504 11.448 100 95 190 0 1156953936 78587248 19279184
08:19:29 1516 8192000000 10023669104 10400100352 1069881344 11.551 100 79 161 1 1131199232 44491408 16826480
08:20:32 5966 8192000000 9990970816 10383519744 1075136512 11.601 100 37 71 1 1123340000 40895312 7308176
08:21:39 39272 8192000000 10008703648 10390958080 1082242736 11.669 100 79 160 2 1114374128 15304272 16827120
08:23:20 4554 8192000000 10018574656 10413170688 1093508736 11.776 100 46 92 2 1131301408 27657136 10135536
08:24:26 8096 8192000000 10005355264 10412138496 1097666208 11.815 100 47 95 1 1122677824 14359152 10652464
08:25:34 5235 8192000000 10017310064 10427150336 1101558816 11.852 100 27 54 1 1122835680 15427104 5849760
08:26:41 52907 8192000000 10010696448 10427977728 1065317552 11.507 96 28 56 2 1094235984 22483344 6435088
08:28:34 9548 8192000000 10001675552 10411839488 1072166192 11.573 99 39 81 1 1101856560 21512192 8178176
08:29:47 10557 8192000000 9946507536 10341732352 1003455264 10.912 95 25 49 0 1027469280 18568112 5445904
08:30:59 5846 8192000000 9947725360 10321350656 995236848 10.832 96 36 70 1 1025780384 22216384 8327152
08:32:10 40914 8192000000 9939824544 10336145408 985106688 10.734 96 45 89 1 1006616240 11297792 10211760
08:33:51 10703 8192000000 9969134288 10370281472 1036753568 11.233 99 50 100 0 1073427520 25380368 11293584
08:35:03 8081 8192000000 9987953728 10391965696 1055063008 11.409 100 44 86 4 1092247824 26861328 10323488
08:36:11 9365 8192000000 9972877088 10380873728 1062814480 11.483 100 28 54 0 1088369280 19075920 6478880
08:37:28 23914 8192000000 10008268864 10407690240 1080066560 11.648 100 62 124 1 1124194816 29935632 14192624
08:39:10 2085 8192000000 10062264464 10458886144 1101284944 11.850 100 65 130 1 1178380016 63313536 13781536
08:40:17 28744 8192000000 10085858064 10492100608 1115711424 11.986 100 36 72 1 1184744176 61152432 7880320
08:41:50 48991 8192000000 10136043120 10556256256 1133105680 12.151 100 39 77 1 1234433440 92298016 9029744
08:43:42 10932 8192000000 10197802272 10604797952 1157722976 12.382 100 52 103 0 1299830368 130329504 11777888
08:44:58 18290 8192000000 10268146688 10685411328 1168871696 12.486 100 54 108 0 1366455280 184737120 12846464
08:46:29 6879 8192000000 10317451536 10723450880 1179799616 12.588 100 75 149 0 1420393072 223772032 16821424
08:47:41 6773 8192000000 10338291888 10752557056 1175253648 12.546 99 63 125 1 1433070688 243619808 14197232
08:48:53 1480 8192000000 10362489968 10770825216 1176968784 12.562 100 84 169 1 1458393136 262677824 18746528
08:49:56 15745 8192000000 10341388432 10781810688 1179106624 12.582 100 32 64 1 1473863760 286680704 8076432
08:51:16 10820 8192000000 10347888208 10770583552 1180528192 12.595 100 36 72 0 1443745488 253580048 9637248
08:52:33 9086 8192000000 10340001632 10799210496 1163364016 12.435 99 40 80 2 1444694032 271518144 9811872
08:53:45 3864 8192000000 10345520960 10769227776 1145825632 12.270 100 50 98 2 1447713904 290055664 11832608
08:54:51 17525 8192000000 10260614512 10690924544 1126730464 12.091 99 40 78 3 1378816720 242379920 9706336
08:56:19 20588 8192000000 10189565600 10615640064 1103596544 11.872 98 27 54 1 1272100304 161909456 6594304
08:57:40 8409 8192000000 10259259664 10688892928 1123585488 12.061 100 34 66 2 1348865904 216813696 8466720
08:58:52 23382 8192000000 10171546816 10643468288 1104931152 11.884 99 32 64 1 1329597680 216296704 8369824
09:00:19 26823 8192000000 10123124832 10571100160 1098835712 11.827 99 17 34 1 1223490768 120564736 4090320
09:01:55 95426 8192000000 10026980480 10465665024 1058816560 11.445 96 29 58 1 1101917728 36804944 6296224
09:04:35 64100 8192000000 10036938928 10466160640 1072998288 11.581 98 21 40 2 1136474128 58401840 5074000
09:06:40 51252 8192000000 10098051440 10546839552 1076987520 11.619 98 21 42 0 1177305200 95443648 4874032
09:08:37 34993 8192000000 10057643280 10536792064 1094132512 11.782 99 15 31 0 1181322704 84108832 3081360
09:10:14 45327 8192000000 10151289760 10583453696 1129805088 12.120 100 26 52 1 1222809824 87542336 5462400
09:12:00 50925 8192000000 10202026080 10660315136 1123242064 12.058 99 14 28 0 1314672832 187976752 3454016
09:13:58 48737 8192000000 10203915424 10663657472 1120616800 12.033 99 34 67 1 1315850736 187920368 7313568
09:15:48 78744 8192000000 10051267408 10495979520 1098385728 11.822 98 21 41 0 1142412528 39917856 4108944
09:18:08 43307 8192000000 10098049920 10552115200 1132301008 12.143 100 25 50 0 1213718384 76374720 5042656
09:19:59 81825 8192000000 10081912656 10558537728 1136945056 12.187 100 26 51 2 1199237952 57023296 5269600
09:22:22 64121 8192000000 10112400816 10546692096 1100554224 11.843 96 31 62 0 1173820528 66444976 6821328
09:24:39 41036 8192000000 10073026224 10552729600 1106559712 11.900 99 7 14 0 1167713200 59855488 1298000
09:26:29 10686 8192000000 10077803952 10498756608 1122487936 12.050 100 43 86 1 1176148704 44896688 8764080
09:27:45 116101 8192000000 10121083200 10572406784 1120294784 12.030 99 49 98 0 1205177248 74898944 9983520
09:30:47 35916 8192000000 10050999680 10513018880 1123024176 12.056 100 13 26 0 1163418224 37871648 2522400
09:32:26 48545 8192000000 10060009984 10514427904 1128162208 12.104 99 12 24 2 1165346720 34964912 2219600
09:34:14 100158 8192000000 10126603488 10592043008 1133746384 12.157 99 12 24 1 1189209552 52762352 2700816
09:37:27 56887 8192000000 10077869120 10517737472 1125159136 12.076 98 13 26 0 1173552352 45737488 2655728
09:39:42 56199 8192000000 10104802272 10574606336 1146784640 12.279 99 17 34 1 1223184304 73027296 3372368
09:41:43 64182 8192000000 10112269392 10589188096 1127375504 12.097 98 10 20 0 1255127776 125282096 2470176
09:43:52 46726 8192000000 10083008656 10526801920 1109188576 11.925 97 25 50 1 1165649984 51564128 4897280
09:46:16 61894 8192000000 10112746688 10548158464 1121398032 12.040 99 39 78 0 1174877968 45687216 7792720
09:48:20 52436 8192000000 10111403440 10586279936 1083524832 11.681 96 33 66 0 1147644000 57776512 6342656
09:50:13 112290 8192000000 10039395024 10476101632 1096584176 11.805 98 9 18 1 1147237296 49092656 1560464
09:53:52 39895 8192000000 10038244752 10513227776 1107988112 11.913 99 10 20 0 1142209232 32214272 2006848
09:56:00 82969 8192000000 10058930656 10501857280 1094158048 11.782 98 13 27 0 1139682640 42691712 2832880
09:58:44 63364 8192000000 10035214608 10475937792 1107085488 11.905 99 18 36 0 1134298992 23331584 3881920
10:00:49 42987 8192000000 10036999616 10473521152 1104543840 11.881 98 33 66 1 1141089296 29319424 7226032
10:02:33 37458 8192000000 10046142160 10470166528 1090149280 11.744 97 19 35 0 1110939120 17050976 3738864
10:04:10 79931 8192000000 10123277312 10605617152 1109147472 11.924 98 31 62 1 1201026704 85486688 6392544
10:06:31 55076 8192000000 10101202160 10538287104 1106884480 11.903 98 22 45 0 1159886672 48868624 4133568
10:08:26 97776 8192000000 10081385776 10521100288 1058806512 11.445 93 12 24 1 1088427744 27405728 2215504
10:11:04 84570 8192000000 10109472752 10576781312 1137860448 12.195 100 13 27 0 1183392608 42999536 2532624
10:13:34 69889 8192000000 10087067728 10539540480 1127294144 12.096 98 25 50 1 1164462608 32231584 4936880
10:15:47 105590 8192000000 10110346960 10609524736 1117301904 12.001 98 11 22 1 1198441088 78524224 2614960
10:18:57 82815 8192000000 10096031664 10540195840 1145559296 12.268 100 16 30 2 1205994512 57272096 3163120
10:21:20 69394 8192000000 10127646064 10586976256 1146888368 12.280 99 27 54 1 1196158032 43743920 5525744
10:23:30 48400 8192000000 10112123952 10569035776 1147121696 12.282 99 20 40 0 1186473056 35024064 4327296
10:25:30 51039 8192000000 10100967616 10555322368 1128445376 12.107 98 31 61 1 1186707984 52067392 6195216
10:27:26 90258 8192000000 10094392064 10627760128 1117457904 12.003 98 17 34 1 1208463408 87354848 3650656
10:29:58 94791 8192000000 10064036352 10532732928 1112545280 11.957 98 24 48 1 1161722064 44090272 5086512
10:32:53 47160 8192000000 10053384432 10532417536 1091257952 11.755 97 14 28 0 1129502768 35319248 2925568
10:36:36 48193 8192000000 9359247472 9716568064 493813872 5.685 45 9 18 1 522829456 27289472 1726112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 23-08-2021 10:39:07 to 23-08-2021 10:39:22.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 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 23-08-2021 10:39:23 to 23-08-2021 10:39:24.

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          226358
MON$OLDEST_ACTIVE               226359
MON$OLDEST_SNAPSHOT             226359
MON$NEXT_TRANSACTION            226359
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                       2011840
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.2571 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2571 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2571 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 ---
:183: 
:184: mscs-test-161.budzdorov.ru    Mon Aug 23 10:37:32 2021
:185:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:186: 
--- end of diff output ---

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

23.08.21 10:39:26. 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-08-23 10:39:28 No severe PSQL-related problems occured
23.08.21 10:39:29. End of report.