Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 19.09.2021 13: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 15927912 kB
MemAvailable 30849876 kB
Buffers 0 kB
Cached 15800532 kB
SwapCached 480 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-19 13:37:07
DTS_END                         2021-09-19 13:37:07.4450
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/49290
TRN_ID                          229601
ATT_ID                          676
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: 9013.30 22331 1081596
customer order: creation 169.29 414 20315
customer order: refuse 14.38 405 1726
order to supplier: creation 44.71 1493 5365
order to supplier: removal 3.77 105498 452
invoice (draft): creation 44.79 3619 5375
invoice (draft): removal 51.98 1023 6238
invoice accept: apply 40.10 19283 4812
invoice accept: cancel 46.46 936 5575
customer reserve: creation 3701.15 189 444138
customer reserve: removal 4611.03 65 553324
realization accept: apply 82.94 266 9953
realization accept: cancel 76.16 136 9139
payment from customer: creation 58.59 155 7031
payment from customer: removal 5.23 95 627
payment to supplier: creation 48.70 178 5844
payment to supplier: removal 3.41 163 409
service: total inventory turnovers 3.51 3836 421
service: total monetary turnovers 6.18 486 741
service: aggregate perf. data 0.89 15097 107
service: refresh index statistics 0.03 293284 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 19-09-2021 13:38:47 to 19-09-2021 13:38:50.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4652
TEST_TIME 2 2472
TEST_TIME 3 1880
TEST_TIME 4 2616
TEST_TIME 5 3337
TEST_TIME 6 3445
TEST_TIME 7 3873
TEST_TIME 8 4511
TEST_TIME 9 5307
TEST_TIME 10 5377
TEST_TIME 11 5391
TEST_TIME 12 5672
TEST_TIME 13 5796
TEST_TIME 14 5996
TEST_TIME 15 6241
TEST_TIME 16 6473
TEST_TIME 17 7166
TEST_TIME 18 7355
TEST_TIME 19 7430
TEST_TIME 20 7571
TEST_TIME 21 7941
TEST_TIME 22 7842
TEST_TIME 23 8267
TEST_TIME 24 8604
TEST_TIME 25 9137
TEST_TIME 26 9650
TEST_TIME 27 10066
TEST_TIME 28 10295
TEST_TIME 29 10857
TEST_TIME 30 10641
TEST_TIME 31 10560
TEST_TIME 32 10545
TEST_TIME 33 10515
TEST_TIME 34 10464
TEST_TIME 35 10378
TEST_TIME 36 10416
TEST_TIME 37 10363
TEST_TIME 38 10284
TEST_TIME 39 10322
TEST_TIME 40 10180
TEST_TIME 41 10040
TEST_TIME 42 9958
TEST_TIME 43 9962
TEST_TIME 44 9837
TEST_TIME 45 9848
TEST_TIME 46 9773
TEST_TIME 47 9729
TEST_TIME 48 9807
TEST_TIME 49 9712
TEST_TIME 50 9598
TEST_TIME 51 9624
TEST_TIME 52 9656
TEST_TIME 53 9542
TEST_TIME 54 9425
TEST_TIME 55 9405
TEST_TIME 56 9380
TEST_TIME 57 9253
TEST_TIME 58 9252
TEST_TIME 59 9331
TEST_TIME 60 9249
TEST_TIME 61 9318
TEST_TIME 62 9320
TEST_TIME 63 9383
TEST_TIME 64 9427
TEST_TIME 65 9442
TEST_TIME 66 9488
TEST_TIME 67 9489
TEST_TIME 68 9578
TEST_TIME 69 9574
TEST_TIME 70 9583
TEST_TIME 71 9608
TEST_TIME 72 9571
TEST_TIME 73 9467
TEST_TIME 74 9496
TEST_TIME 75 9421
TEST_TIME 76 9454
TEST_TIME 77 9405
TEST_TIME 78 9317
TEST_TIME 79 9244
TEST_TIME 80 9238
TEST_TIME 81 9183
TEST_TIME 82 9306
TEST_TIME 83 9285
TEST_TIME 84 9256
TEST_TIME 85 9248
TEST_TIME 86 9215
TEST_TIME 87 9363
TEST_TIME 88 9356
TEST_TIME 89 9374
TEST_TIME 90 9395
TEST_TIME 91 9421
TEST_TIME 92 9392
TEST_TIME 93 9448
TEST_TIME 94 9360
TEST_TIME 95 9290
TEST_TIME 96 9267
TEST_TIME 97 9234
TEST_TIME 98 9198
TEST_TIME 99 9153
TEST_TIME 100 9164
TEST_TIME 101 9134
TEST_TIME 102 9098
TEST_TIME 103 9040
TEST_TIME 104 8994
TEST_TIME 105 9008
TEST_TIME 106 9071
TEST_TIME 107 9059
TEST_TIME 108 9032
TEST_TIME 109 9023
TEST_TIME 110 9080
TEST_TIME 111 9124
TEST_TIME 112 9125
TEST_TIME 113 9128
TEST_TIME 114 9149
TEST_TIME 115 9149
TEST_TIME 116 9219
TEST_TIME 117 9201
TEST_TIME 118 9127
TEST_TIME 119 9089
TEST_TIME 120 9019

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 19-09-2021 13:38:51 to 19-09-2021 13:38:55.

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 1492796 1492796 0 0.00 0 31605 8 0 0
doc_list_biud 1492796 1492796 0 0.00 0 40391 32 0 0
sp_add_invoice_to_stock 4949 4812 137 2.76 46 193185 19283 0 137
sp_cancel_adding_invoice 5575 5575 0 0.00 6 64987 936 0 0
sp_cancel_client_order 1726 1726 0 0.00 5 19822 405 0 0
sp_cancel_customer_reserve 553324 553324 0 0.00 1 36269 66 0 0
sp_cancel_pay_from_customer 627 627 0 0.00 3 4848 95 0 0
sp_cancel_pay_to_supplier 409 409 0 0.00 3 8905 163 0 0
sp_cancel_supplier_invoice 6244 6238 6 0.09 3 24470 1023 0 6
sp_cancel_supplier_order 452 452 0 0.00 7 1314456 105499 0 0
sp_cancel_write_off 9139 9139 0 0.00 1 18724 136 0 0
sp_client_order 20315 20315 0 0.00 4 32033 414 0 0
sp_customer_reserve 444138 444138 0 0.00 0 55088 190 0 0
sp_fill_shopping_cart 31054 31054 0 0.00 1 17295 45 0 0
sp_fill_shopping_cart_clo_res 444131 444131 0 0.00 0 30128 15 0 0
sp_get_clo_for_invoice 4805 4805 0 0.00 0 35563 378 0 0
sp_kill_cost_storno 15750 15750 0 0.00 0 14573 73 0 0
sp_kill_qstorno_ret_qs2qd 1122477 1122477 0 0.00 0 36264 36 0 0
sp_kill_qty_storno 591212 591212 0 0.00 0 40389 79 0 0
sp_lock_selected_doc 20344 20344 0 0.00 0 10850 7 0 0
sp_make_cost_storno 27633 27633 0 0.00 0 31591 74 0 0
sp_multiply_rows_for_pdistr 27633 27633 0 0.00 0 20114 38 0 0
sp_multiply_rows_for_qdistr 20315 20315 0 0.00 0 28693 189 0 0
sp_pay_from_customer 7031 7031 0 0.00 4 23200 156 0 0
sp_pay_to_supplier 5844 5844 0 0.00 4 22474 179 0 0
sp_payment_common 12875 12875 0 0.00 1 15763 33 0 0
sp_qd_handle_on_cancel_clo 1726 1726 0 0.00 0 10766 339 0 0
sp_qd_handle_on_invoice_upd_sts 10380 10380 0 0.00 0 40389 409 0 0
sp_qd_handle_on_reserve_upd_sts 9953 9953 0 0.00 0 17881 67 0 0
sp_reserve_write_off 9953 9953 0 0.00 7 31404 266 0 0
sp_supplier_invoice 5375 5375 0 0.00 8 106710 3619 0 0
sp_supplier_order 5365 5365 0 0.00 6 47162 1494 0 0
srv_aggregate_perf_data 107 107 0 0.00 2456 87752 15097 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1333 157191 44461 0 0
srv_find_qd_qs_mism 1035049 1035049 0 0.00 0 30676 6 0 0
srv_make_invnt_saldo 421 421 0 0.00 61 91275 3836 0 0
srv_make_money_saldo 741 741 0 0.00 1 13921 486 0 0
srv_recalc_idx_stat 4 4 0 0.00 152319 562396 293284 0 0
t$perf-abend:sp_add_invoice_to_stock 137 137 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 4813 4813 0 0.00 0 25787 176 0 0
t$perf-norm:sp_cancel_adding_invoice 386 386 0 0.00 0 9220 150 0 0
t$perf-norm:sp_cancel_client_order 1726 1726 0 0.00 0 3931 14 0 0
t$perf-norm:sp_cancel_customer_reserve 661 661 0 0.00 0 5027 19 0 0
t$perf-norm:sp_cancel_pay_from_customer 627 627 0 0.00 0 830 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 409 409 0 0.00 0 6051 24 0 0
t$perf-norm:sp_cancel_supplier_invoice 392 392 0 0.00 0 651 14 0 0
t$perf-norm:sp_cancel_supplier_order 452 452 0 0.00 0 39906 1620 0 0
t$perf-norm:sp_cancel_write_off 933 933 0 0.00 0 2454 9 0 0
t$perf-norm:sp_client_order 20315 20315 0 0.00 0 12556 18 0 0
t$perf-norm:sp_customer_reserve 991 991 0 0.00 0 6947 25 0 0
t$perf-norm:sp_pay_from_customer 7031 7031 0 0.00 0 11534 13 0 0
t$perf-norm:sp_pay_to_supplier 5844 5844 0 0.00 0 11533 14 0 0
t$perf-norm:sp_reserve_write_off 9953 9953 0 0.00 0 15072 12 0 0
t$perf-norm:sp_supplier_invoice 5375 5375 0 0.00 0 4509 18 0 0
t$perf-norm:sp_supplier_order 5365 5365 0 0.00 0 7777 18 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 213 4 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 562 9 0 0
t$perf-norm:srv_make_invnt_saldo 421 421 0 0.00 0 1812 19 0 0
t$perf-norm:srv_make_money_saldo 741 741 0 0.00 0 1099 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 9 5 0 0
v_add_invoice_to_stock 9884 9610 274 2.77 0 19846 37 0 274
v_all_customers 37610 37610 0 0.00 0 101 1 0 0
v_all_suppliers 29944 29944 0 0.00 0 26 1 0 0
v_all_wares 40630 40630 0 0.00 0 94 1 0 0
v_cancel_adding_invoice 772 772 0 0.00 0 2884 10 0 0
v_cancel_client_order 3452 3452 0 0.00 0 19514 20 0 0
v_cancel_customer_prepayment 1254 1254 0 0.00 0 4798 20 0 0
v_cancel_customer_reserve 1322 1322 0 0.00 0 1417 4 0 0
v_cancel_payment_to_supplier 818 818 0 0.00 0 4787 23 0 0
v_cancel_supplier_invoice 796 784 12 1.50 0 12287 30 0 12
v_cancel_supplier_order 904 904 0 0.00 0 8531 25 0 0
v_cancel_write_off 1866 1866 0 0.00 0 6479 17 0 0
v_max_id_clo_ord 5364 5364 0 0.00 0 186 1 0 0
v_max_id_clo_res 991 991 0 0.00 0 6756 17 0 0
v_max_id_ord_sup 5375 5375 0 0.00 0 2431 4 0 0
v_max_non_paid_invoice 1611 1611 0 0.00 0 2313 3 0 0
v_max_non_paid_realizn 3515 3515 0 0.00 0 3201 4 0 0
v_min_id_clo_ord 5364 5364 0 0.00 0 3453 7 0 0
v_min_id_clo_res 991 991 0 0.00 0 6250 12 0 0
v_min_id_ord_sup 5375 5375 0 0.00 0 6134 9 0 0
v_min_non_paid_invoice 5844 5844 0 0.00 0 9062 25 0 0
v_min_non_paid_realizn 7031 7031 0 0.00 0 15701 24 0 0
v_our_firm 10052 10052 0 0.00 0 23 1 0 0
v_reserve_write_off 19906 19906 0 0.00 0 22409 22 0 0
x_lock_depdocs_on_canc_invoice 11421 11421 0 0.00 0 14212 117 0 0
x_lock_depdocs_on_canc_sup_ord 452 452 0 0.00 0 5636 350 0 0
x_make_qstorno_1000_1200 5365 5365 0 0.00 2 45722 1407 0 0
x_make_qstorno_1200_2000 5375 5375 0 0.00 3 98957 3451 0 0
x_make_qstorno_2100_3300 444138 444138 0 0.00 0 55086 172 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 19-09-2021 13:38:56 to 19-09-2021 13:39:00.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
11:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:12 38342 8192000000 9722481024 10016751616 788918080 8.784 100 90 180 0 819030272 7613936 22498256
11:09:57 15100 8192000000 9759768448 10063147008 834897104 9.248 100 93 186 0 869460960 12589824 21974032
11:11:19 17381 8192000000 9805534144 10122227712 869984416 9.600 100 91 182 0 910531824 19961936 20585472
11:12:42 14636 8192000000 9871296656 10204999680 925278720 10.148 100 76 152 2 978025136 36622704 16123712
11:14:02 11865 8192000000 9924122720 10269364224 965536576 10.543 100 80 160 0 1032744624 50880560 16327488
11:15:18 6240 8192000000 9977431136 10327363584 995240272 10.832 100 86 170 2 1081417408 68380704 17796432
11:16:28 4683 8192000000 9998310864 10357936128 1016533616 11.039 100 89 178 1 1109279456 74070736 18675104
11:17:36 3435 8192000000 10024857616 10394439680 1040611648 11.271 100 94 187 1 1140774624 80602112 19560864
11:18:42 2027 8192000000 10030280720 10397360128 1050678224 11.367 100 91 177 1 1139351344 69929104 18744016
11:19:45 23901 8192000000 10019289744 10396880896 1062450720 11.480 100 74 146 0 1130755184 51965904 16338560
11:21:11 50808 8192000000 9995741152 10378489856 1072687232 11.578 100 53 104 1 1102150912 17545824 11917856
11:23:03 8543 8192000000 9989830528 10391240704 1075664912 11.606 100 43 87 0 1104062704 18069584 10328208
11:24:13 6520 8192000000 9990158976 10391199744 1083673760 11.682 100 33 66 0 1109441328 17722608 8044960
11:25:23 49428 8192000000 10005744688 10410512384 1093974736 11.780 100 38 76 1 1120871904 17087840 9809328
11:27:34 4831 8192000000 10016761968 10418630656 1096057120 11.800 100 38 76 0 1123348736 18964240 8327376
11:28:42 55239 8192000000 9966088064 10366152704 1007919744 10.955 94 33 66 1 1031599424 15712352 7967328
11:30:42 2133 8192000000 9968601584 10342801408 1028057504 11.150 99 65 131 0 1073395728 32452912 12885312
11:31:45 5854 8192000000 9944330464 10329079808 1029556192 11.164 100 39 81 1 1060646720 22170944 8919584
11:32:53 43955 8192000000 9956643488 10364960768 1043910128 11.302 100 34 67 2 1076559392 24393392 8255872
11:34:38 11163 8192000000 9969956576 10375282688 1027386768 11.143 98 41 81 0 1052512576 16663984 8461824
11:35:51 4573 8192000000 9972007712 10355798016 1055261664 11.411 100 40 80 1 1071650624 6699664 9689296
11:36:57 21031 8192000000 9997647216 10401345536 1074712128 11.597 100 17 34 0 1103543136 25476784 3354224
11:38:33 151970 8192000000 10091679920 10508996608 1113582768 11.966 100 32 62 2 1173774816 52794512 7397536
11:42:28 46366 8192000000 10126274416 10533498880 1131313616 12.134 100 31 60 0 1237911920 100141280 6457024
11:44:18 12341 8192000000 10175668672 10587627520 1139866656 12.214 100 43 86 1 1281000496 131663696 9470144
11:45:55 7202 8192000000 10156655280 10584018944 1151908768 12.327 100 50 100 2 1268873712 105820256 11144688
11:47:05 6955 8192000000 10203318224 10615513088 1171749488 12.513 100 57 114 2 1300030896 116399472 11881936
11:48:16 6249 8192000000 10279258992 10688049152 1181270016 12.602 100 76 151 1 1368831776 170589856 16971904
11:49:30 2977 8192000000 10296218800 10704359424 1193857680 12.719 100 77 154 2 1393324560 182661904 16804976
11:50:37 3378 8192000000 10341671248 10761056256 1197402224 12.752 100 64 128 0 1443520880 231280224 14838432
11:51:48 2287 8192000000 10388251856 10807824384 1205738016 12.830 100 86 172 2 1489598368 263933408 19926944
11:52:56 1329 8192000000 10438428288 10852421632 1205338752 12.826 100 91 178 0 1534712944 308358800 21015392
11:54:04 4140 8192000000 10428677488 10847346688 1185379904 12.640 98 82 164 1 1518119392 313450912 19288576
11:55:15 4388 8192000000 10395867008 10821419008 1180884736 12.598 99 54 108 1 1488798176 295092528 12820912
11:56:24 14648 8192000000 10428867744 10861608960 1187782784 12.663 100 47 92 2 1530641776 331387216 11471776
11:57:46 8540 8192000000 10503176160 10936586240 1181943344 12.608 100 49 98 1 1608355984 413995328 12417312
11:59:10 4616 8192000000 10462517232 10892107776 1143021472 12.244 98 56 112 0 1550990880 393528128 14441280
12:00:19 4278 8192000000 10498654112 10936176640 1158288368 12.387 100 50 101 0 1604054384 432180224 13585792
12:01:28 13079 8192000000 10476687152 10932482048 1175278544 12.546 100 43 86 1 1578620576 392223600 11118432
12:02:48 33996 8192000000 10354991488 10812203008 1141506080 12.230 100 27 54 2 1488798512 339550768 7741664
12:04:25 27097 8192000000 10219933040 10713485312 1125953408 12.083 99 29 58 1 1363236352 229741520 7541424
12:06:44 75030 8192000000 10066653568 10505981952 1085748944 11.702 96 14 28 0 1145054592 56673520 2632128
12:09:03 27246 8192000000 10057915264 10487390208 1118644512 12.014 100 37 73 0 1155448464 28603728 8200224
12:10:31 157191 8192000000 10082316736 10571202560 1080259184 11.650 95 13 26 1 1104603792 21182368 3162240
12:14:10 23405 8192000000 10056417904 10487959552 1102158640 11.858 98 37 66 2 1138794304 29182720 7452944
12:15:43 22658 8192000000 10083640288 10531446784 1114419856 11.974 99 28 56 2 1178363504 58069616 5874032
12:17:19 133919 8192000000 10105274240 10554351616 1116545552 11.994 99 25 50 0 1174876688 52486880 5844256
12:20:43 44926 8192000000 10052615392 10480418816 1115591712 11.985 100 28 56 0 1148756272 27666320 5498240
12:22:31 21622 8192000000 10061437824 10520080384 1124794592 12.072 100 16 31 2 1159529056 31730416 3004048
12:24:01 56389 8192000000 10141001568 10604818432 1136613408 12.184 100 44 88 1 1236234512 89884528 9736576
12:26:16 19688 8192000000 10061521936 10512322560 1134055312 12.160 100 17 34 0 1171810240 34274448 3480480
12:27:39 52349 8192000000 10095298224 10572537856 1132188368 12.142 99 14 28 0 1209150368 73931376 3030624
12:29:32 47332 8192000000 10055328912 10516467712 1122598384 12.052 99 15 30 0 1154559264 29032384 2928496
12:31:21 57910 8192000000 10074714416 10529374208 1125971184 12.083 99 25 50 0 1182721536 51616416 5133936
12:33:20 34924 8192000000 10065866144 10522845184 1103216336 11.868 97 19 37 0 1141619376 34865264 3537776
12:35:05 55633 8192000000 10106369328 10587516928 1134132832 12.160 100 17 33 1 1185023440 47210624 3679984
12:37:01 36082 8192000000 10061390096 10503114752 1130833408 12.129 99 21 42 1 1160989280 26201200 3954672
12:38:40 46308 8192000000 10103884976 10559541248 1111370928 11.945 97 24 48 0 1172606272 56652832 4582512
12:40:26 16520 8192000000 10071118784 10514927616 1121151056 12.038 99 29 58 0 1164932272 37418608 6362608
12:41:52 27490 8192000000 10083423232 10539134976 1127952448 12.102 100 22 44 1 1197845648 65374864 4518336
12:43:21 43808 8192000000 10066923280 10539323392 1119477936 12.022 99 15 30 0 1187114032 64325312 3310784
12:45:26 38419 8192000000 10068566144 10532130816 1101122016 11.848 97 14 28 0 1155776176 51631696 3022464
12:47:05 22561 8192000000 10080351760 10549366784 1119710416 12.024 99 24 48 1 1167656128 43376144 4569568
12:48:28 86728 8192000000 10045505376 10497708032 1101323200 11.850 97 19 38 1 1132193792 27245760 3624832
12:51:20 83657 8192000000 10063055472 10545528832 1103143520 11.867 98 20 40 0 1145541712 38085200 4312992
12:53:45 50650 8192000000 10033885136 10509684736 1087675376 11.721 97 29 58 0 1134767808 40686096 6406336
12:55:36 41959 8192000000 10063339888 10510401536 1099636208 11.834 98 11 22 1 1142321440 39993856 2691376
12:57:23 67254 8192000000 10119257072 10595614720 1118816944 12.016 100 11 22 0 1208181696 87026400 2338352
12:59:31 48237 8192000000 10052394320 10498555904 1101198720 11.849 99 9 18 1 1162209056 59146464 1863872
13:01:22 67789 8192000000 10096318416 10559725568 1075304912 11.603 96 24 48 1 1159292768 78765344 5222512
13:03:31 69251 8192000000 10101038048 10588758016 1107135200 11.905 99 14 28 1 1225755840 115292512 3328128
13:05:42 33285 8192000000 10063471872 10492006400 1114207600 11.972 100 53 106 0 1151967024 26540752 11218672
13:07:17 55264 8192000000 10128526864 10593632256 1114667488 11.977 99 30 60 1 1184927296 64605216 5654592
13:09:17 31871 8192000000 10115150416 10542948352 1104622560 11.881 98 24 48 1 1163341344 54316432 4402352
13:10:51 139530 8192000000 10090766512 10539663360 1111798384 11.949 98 23 46 0 1179593872 62985856 4809632
13:14:13 64208 8192000000 10112505584 10583957504 1140086592 12.216 100 8 16 1 1198473568 56915968 1471008
13:16:29 63102 8192000000 10084865776 10585649152 1130961952 12.130 99 10 20 0 1192281712 58836368 2483392
13:18:36 64803 8192000000 10081703744 10554085376 1141844976 12.233 100 12 24 0 1187808800 43699024 2264800
13:20:47 69410 8192000000 10139768688 10617905152 1136668112 12.184 99 12 24 0 1201679312 62619184 2392016
13:23:06 68635 8192000000 10059672368 10543251456 1111653232 11.948 97 13 26 1 1166912448 52311792 2947424
13:25:15 45788 8192000000 10097741024 10544705536 1118707616 12.015 98 24 48 0 1171425664 47390928 5327120
13:27:01 58769 8192000000 10131617888 10641854464 1136997648 12.187 99 16 32 0 1246327424 105789520 3540256
13:29:34 55531 8192000000 10097164928 10581364736 1101834448 11.855 96 24 48 2 1150436464 43478624 5123392
13:31:31 63667 8192000000 10098795936 10556112896 1125628592 12.080 99 34 68 0 1167414720 35421328 6364800
13:33:35 78114 8192000000 10077240080 10619715584 1110009312 11.933 97 20 40 0 1210181840 96176496 3996032
13:35:53 44141 8192000000 10039439904 10474229760 1059970768 11.456 95 40 80 0 1096748896 28575136 8202992

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 137 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:02, from 19-09-2021 13:39:18 to 19-09-2021 13:39:20.

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          228149
MON$OLDEST_ACTIVE               229724
MON$OLDEST_SNAPSHOT             229724
MON$NEXT_TRANSACTION            229724
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                       2052768
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             793
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2605 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2605 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2605 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2605 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 ---
:40: 
:41: mscs-test-161.budzdorov.ru    Sun Sep 19 13:37:07 2021
:42:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:43: 
:44: 
:45: mscs-test-161.budzdorov.ru    Sun Sep 19 13:37:10 2021
:46:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:47: 
:48: 
:49: mscs-test-161.budzdorov.ru    Sun Sep 19 13:37:11 2021
:50:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:51: 
:52: 
:53: mscs-test-161.budzdorov.ru    Sun Sep 19 13:37:14 2021
:54:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:55: 
:56: 
:57: mscs-test-161.budzdorov.ru    Sun Sep 19 13:37:15 2021
:58:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:59: 
--- end of diff output ---

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

19.09.21 13:39:21. 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-19 13:39:23 No severe PSQL-related problems occured
19.09.21 13:39:24. End of report.