Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 13.09.2021 10:39
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 16031088 kB
MemAvailable 30889452 kB
Buffers 0 kB
Cached 15697124 kB
SwapCached 396 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-13 10:37:08
DTS_END                         2021-09-13 10:37:08.7220
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/39340
TRN_ID                          236929
ATT_ID                          685
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: 9082.49 22691 1089899
customer order: creation 176.80 416 21216
customer order: refuse 14.78 365 1773
order to supplier: creation 46.58 1488 5589
order to supplier: removal 3.42 119057 410
invoice (draft): creation 46.29 3571 5555
invoice (draft): removal 49.52 988 5942
invoice accept: apply 43.14 21920 5177
invoice accept: cancel 44.46 978 5335
customer reserve: creation 3970.95 217 476514
customer reserve: removal 4393.13 73 527175
realization accept: apply 85.94 185 10313
realization accept: cancel 76.75 146 9210
payment from customer: creation 59.88 127 7186
payment from customer: removal 5.43 100 652
payment to supplier: creation 50.42 138 6050
payment to supplier: removal 3.65 90 438
service: total inventory turnovers 3.69 4018 443
service: total monetary turnovers 6.77 578 812
service: aggregate perf. data 0.88 15715 105
service: refresh index statistics 0.03 283646 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 13-09-2021 10:39:26 to 13-09-2021 10:39:28.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4608
TEST_TIME 2 4945
TEST_TIME 3 4130
TEST_TIME 4 4246
TEST_TIME 5 4023
TEST_TIME 6 4121
TEST_TIME 7 4713
TEST_TIME 8 5458
TEST_TIME 9 5722
TEST_TIME 10 6060
TEST_TIME 11 6365
TEST_TIME 12 6535
TEST_TIME 13 6704
TEST_TIME 14 7131
TEST_TIME 15 7109
TEST_TIME 16 7534
TEST_TIME 17 7613
TEST_TIME 18 7827
TEST_TIME 19 7872
TEST_TIME 20 7942
TEST_TIME 21 8187
TEST_TIME 22 8584
TEST_TIME 23 8603
TEST_TIME 24 8543
TEST_TIME 25 9029
TEST_TIME 26 9008
TEST_TIME 27 9582
TEST_TIME 28 9797
TEST_TIME 29 10845
TEST_TIME 30 10702
TEST_TIME 31 10582
TEST_TIME 32 10494
TEST_TIME 33 10336
TEST_TIME 34 10480
TEST_TIME 35 10439
TEST_TIME 36 10321
TEST_TIME 37 10082
TEST_TIME 38 10011
TEST_TIME 39 9897
TEST_TIME 40 9717
TEST_TIME 41 9651
TEST_TIME 42 9630
TEST_TIME 43 9485
TEST_TIME 44 9500
TEST_TIME 45 9644
TEST_TIME 46 9583
TEST_TIME 47 9801
TEST_TIME 48 9765
TEST_TIME 49 9724
TEST_TIME 50 9678
TEST_TIME 51 9761
TEST_TIME 52 9640
TEST_TIME 53 9816
TEST_TIME 54 9824
TEST_TIME 55 9809
TEST_TIME 56 9825
TEST_TIME 57 9811
TEST_TIME 58 9858
TEST_TIME 59 9882
TEST_TIME 60 9844
TEST_TIME 61 9710
TEST_TIME 62 9749
TEST_TIME 63 9734
TEST_TIME 64 9667
TEST_TIME 65 9590
TEST_TIME 66 9508
TEST_TIME 67 9474
TEST_TIME 68 9572
TEST_TIME 69 9555
TEST_TIME 70 9512
TEST_TIME 71 9558
TEST_TIME 72 9547
TEST_TIME 73 9577
TEST_TIME 74 9614
TEST_TIME 75 9628
TEST_TIME 76 9593
TEST_TIME 77 9584
TEST_TIME 78 9590
TEST_TIME 79 9553
TEST_TIME 80 9464
TEST_TIME 81 9451
TEST_TIME 82 9417
TEST_TIME 83 9379
TEST_TIME 84 9348
TEST_TIME 85 9309
TEST_TIME 86 9264
TEST_TIME 87 9282
TEST_TIME 88 9239
TEST_TIME 89 9209
TEST_TIME 90 9222
TEST_TIME 91 9246
TEST_TIME 92 9268
TEST_TIME 93 9254
TEST_TIME 94 9291
TEST_TIME 95 9260
TEST_TIME 96 9268
TEST_TIME 97 9257
TEST_TIME 98 9291
TEST_TIME 99 9299
TEST_TIME 100 9332
TEST_TIME 101 9303
TEST_TIME 102 9228
TEST_TIME 103 9167
TEST_TIME 104 9172
TEST_TIME 105 9121
TEST_TIME 106 9084
TEST_TIME 107 9071
TEST_TIME 108 9027
TEST_TIME 109 9085
TEST_TIME 110 9073
TEST_TIME 111 9082
TEST_TIME 112 9060
TEST_TIME 113 9094
TEST_TIME 114 9107
TEST_TIME 115 9116
TEST_TIME 116 9118
TEST_TIME 117 9136
TEST_TIME 118 9171
TEST_TIME 119 9160
TEST_TIME 120 9085

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 13-09-2021 10:39:30 to 13-09-2021 10:39:33.

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 1530793 1530793 0 0.00 0 27751 9 0 0
doc_list_biud 1530793 1530793 0 0.00 0 40587 32 0 0
sp_add_invoice_to_stock 5340 5177 163 3.05 27 215523 21921 0 163
sp_cancel_adding_invoice 5335 5335 0 0.00 6 65169 979 0 0
sp_cancel_client_order 1773 1773 0 0.00 4 14692 365 0 0
sp_cancel_customer_reserve 527175 527175 0 0.00 1 40589 73 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 3 8006 101 0 0
sp_cancel_pay_to_supplier 438 438 0 0.00 3 3637 90 0 0
sp_cancel_supplier_invoice 5961 5942 19 0.31 3 43309 989 0 19
sp_cancel_supplier_order 410 410 0 0.00 12 1175103 119058 0 0
sp_cancel_write_off 9210 9210 0 0.00 1 26491 147 0 0
sp_client_order 21216 21216 0 0.00 4 19823 416 0 0
sp_customer_reserve 476514 476514 0 0.00 0 54408 218 0 0
sp_fill_shopping_cart 32351 32351 0 0.00 1 19143 40 0 0
sp_fill_shopping_cart_clo_res 476505 476505 0 0.00 0 17505 18 0 0
sp_get_clo_for_invoice 5165 5165 0 0.00 0 33382 341 0 0
sp_kill_cost_storno 15635 15635 0 0.00 0 14311 76 0 0
sp_kill_qstorno_ret_qs2qd 1069912 1069912 0 0.00 0 40585 39 0 0
sp_kill_qty_storno 565323 565323 0 0.00 0 40587 85 0 0
sp_lock_selected_doc 21206 21206 0 0.00 0 8854 5 0 0
sp_make_cost_storno 28714 28714 0 0.00 0 22672 58 0 0
sp_multiply_rows_for_pdistr 28714 28714 0 0.00 0 10860 35 0 0
sp_multiply_rows_for_qdistr 21216 21216 0 0.00 0 14848 206 0 0
sp_pay_from_customer 7186 7186 0 0.00 4 22892 127 0 0
sp_pay_to_supplier 6050 6050 0 0.00 5 17496 139 0 0
sp_payment_common 13236 13236 0 0.00 2 17447 30 0 0
sp_qd_handle_on_cancel_clo 1773 1773 0 0.00 0 5293 323 0 0
sp_qd_handle_on_invoice_upd_sts 10500 10500 0 0.00 0 30735 401 0 0
sp_qd_handle_on_reserve_upd_sts 10313 10313 0 0.00 0 9295 54 0 0
sp_reserve_write_off 10313 10313 0 0.00 7 18172 185 0 0
sp_supplier_invoice 5555 5555 0 0.00 7 84918 3571 0 0
sp_supplier_order 5589 5589 0 0.00 6 34107 1489 0 0
srv_aggregate_perf_data 105 105 0 0.00 2871 101497 15715 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 858 138359 45622 0 0
srv_find_qd_qs_mism 1040902 1040902 0 0.00 0 27750 8 0 0
srv_make_invnt_saldo 443 443 0 0.00 41 105780 4018 0 0
srv_make_money_saldo 812 812 0 0.00 1 13325 578 0 0
srv_recalc_idx_stat 4 4 0 0.00 160866 501740 283647 0 0
t$perf-abend:sp_add_invoice_to_stock 163 163 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 19 19 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5177 5177 0 0.00 0 9704 199 0 0
t$perf-norm:sp_cancel_adding_invoice 402 402 0 0.00 0 3561 91 0 0
t$perf-norm:sp_cancel_client_order 1773 1773 0 0.00 0 4244 14 0 0
t$perf-norm:sp_cancel_customer_reserve 705 705 0 0.00 0 1494 15 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 764 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 438 438 0 0.00 0 2688 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 404 404 0 0.00 0 1328 18 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 36603 1656 0 0
t$perf-norm:sp_cancel_write_off 944 944 0 0.00 0 1042 6 0 0
t$perf-norm:sp_client_order 21216 21216 0 0.00 0 6573 20 0 0
t$perf-norm:sp_customer_reserve 980 980 0 0.00 0 453 3 0 0
t$perf-norm:sp_pay_from_customer 7186 7186 0 0.00 0 9083 13 0 0
t$perf-norm:sp_pay_to_supplier 6050 6050 0 0.00 0 3800 10 0 0
t$perf-norm:sp_reserve_write_off 10313 10313 0 0.00 0 6989 10 0 0
t$perf-norm:sp_supplier_invoice 5555 5555 0 0.00 0 6913 20 0 0
t$perf-norm:sp_supplier_order 5589 5589 0 0.00 0 4660 17 0 0
t$perf-norm:srv_aggregate_perf_data 105 105 0 0.00 0 528 11 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 1280 23 0 0
t$perf-norm:srv_make_invnt_saldo 443 443 0 0.00 0 472 8 0 0
t$perf-norm:srv_make_money_saldo 812 812 0 0.00 0 7235 14 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 3 2 0 0
v_add_invoice_to_stock 10656 10330 326 3.05 0 2861 17 0 326
v_all_customers 38416 38416 0 0.00 0 132 1 0 0
v_all_suppliers 30912 30912 0 0.00 0 75 1 0 0
v_all_wares 42432 42432 0 0.00 0 121 1 0 0
v_cancel_adding_invoice 804 804 0 0.00 0 294 7 0 0
v_cancel_client_order 3546 3546 0 0.00 0 14615 11 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 3950 9 0 0
v_cancel_customer_reserve 1410 1410 0 0.00 0 2705 5 0 0
v_cancel_payment_to_supplier 876 876 0 0.00 0 1138 7 0 0
v_cancel_supplier_invoice 846 808 38 4.49 0 781 16 0 38
v_cancel_supplier_order 820 820 0 0.00 0 1662 9 0 0
v_cancel_write_off 1888 1888 0 0.00 0 1296 15 0 0
v_max_id_clo_ord 5585 5585 0 0.00 0 376 1 0 0
v_max_id_clo_res 980 980 0 0.00 0 3825 24 0 0
v_max_id_ord_sup 5550 5550 0 0.00 0 2286 4 0 0
v_max_non_paid_invoice 1729 1729 0 0.00 0 672 2 0 0
v_max_non_paid_realizn 3823 3823 0 0.00 0 1132 3 0 0
v_min_id_clo_ord 5585 5585 0 0.00 0 7908 7 0 0
v_min_id_clo_res 980 980 0 0.00 0 5271 13 0 0
v_min_id_ord_sup 5550 5550 0 0.00 0 5924 8 0 0
v_min_non_paid_invoice 6050 6050 0 0.00 0 17439 25 0 0
v_min_non_paid_realizn 7186 7186 0 0.00 0 13566 20 0 0
v_our_firm 10742 10742 0 0.00 0 19 1 0 0
v_reserve_write_off 20626 20626 0 0.00 0 3798 5 0 0
x_lock_depdocs_on_canc_invoice 10873 10873 0 0.00 0 23294 124 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 12551 417 0 0
x_make_qstorno_1000_1200 5589 5589 0 0.00 2 34091 1401 0 0
x_make_qstorno_1200_2000 5555 5555 0 0.00 3 84827 3429 0 0
x_make_qstorno_2100_3300 476514 476514 0 0.00 0 54407 196 0 0

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

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:12 4 8192000000 8799338256 8810844160 5349552 0.065 2 1 2 0 5447168 61840 35776
08:08:12 23376 8192000000 9728794192 10025156608 798890896 8.885 100 90 180 0 829008816 7745328 22372592
08:09:40 22578 8192000000 9766655936 10074509312 839250816 9.292 100 84 168 0 870505744 11212896 20042032
08:11:07 26064 8192000000 9806528176 10123165696 873280192 9.633 100 89 178 0 913251456 19486736 20484528
08:12:36 12478 8192000000 9872090352 10203508736 913841552 10.035 100 79 158 0 983782240 52528976 17411712
08:13:56 12363 8192000000 9909250960 10254094336 956328672 10.453 100 72 144 0 1018773968 47367776 15077520
08:15:16 6071 8192000000 9955661840 10308984832 990496544 10.786 100 82 164 0 1078768080 72008800 16262736
08:16:24 3593 8192000000 10006578032 10364055552 1014874000 11.023 100 88 172 1 1118616512 87172816 16569696
08:17:31 2268 8192000000 10021524608 10385530880 1042103552 11.285 100 84 168 0 1130090896 70154576 17832768
08:18:35 2017 8192000000 10041620528 10410393600 1061818336 11.474 100 94 189 0 1153223744 72009840 19395568
08:19:40 21223 8192000000 10017391392 10407280640 1070039168 11.552 100 50 100 1 1160502848 80132032 10331648
08:21:19 4835 8192000000 10004574320 10395754496 1076750736 11.616 100 43 85 2 1135206000 49419216 9036048
08:22:26 3536 8192000000 9995273072 10388070400 1080355136 11.651 100 31 61 0 1105416016 17870272 7190608
08:23:31 7869 8192000000 9994413776 10401607680 1086874224 11.713 100 25 50 0 1117917024 25321904 5720896
08:24:40 26286 8192000000 10013277648 10399997952 1091812208 11.760 100 54 106 1 1118792080 15006784 11973088
08:26:07 7727 8192000000 10026096464 10431197184 1098426336 11.823 100 43 86 2 1148944384 40968192 9549856
08:27:16 6373 8192000000 10021083424 10413588480 1105740848 11.892 100 37 75 1 1132235856 17968880 8526128
08:28:26 3870 8192000000 10001243744 10394378240 1085413392 11.699 100 37 76 3 1114209376 19255120 9540864
08:29:31 53282 8192000000 9946859552 10333196288 962613264 10.515 91 19 38 2 985673968 19662448 3398256
08:31:29 5488 8192000000 9962316784 10343227392 1025874800 11.129 99 39 78 1 1057364448 22463392 9026256
08:32:35 9418 8192000000 9944270816 10337599488 1026447408 11.134 100 40 80 0 1061108928 25955040 8706480
08:33:45 3758 8192000000 9962187120 10346094592 1041234288 11.277 100 58 111 1 1074316496 19767888 13314320
08:34:53 27189 8192000000 9959974208 10369912832 1045415280 11.317 100 25 50 0 1086516800 35737440 5364080
08:36:20 7884 8192000000 9976511776 10382598144 1066118416 11.515 100 58 115 0 1091296720 13383840 11794464
08:37:36 39234 8192000000 10031446864 10427195392 1100178816 11.839 100 52 101 1 1133003680 21834352 10990512
08:39:23 121451 8192000000 10075189104 10489622528 1113515104 11.966 100 41 81 1 1180987216 58972704 8499408
08:42:45 49292 8192000000 10126311248 10561675264 1132104880 12.141 100 30 60 2 1240647696 101825200 6717616
08:44:44 18101 8192000000 10149163424 10575060992 1143089408 12.245 100 48 96 0 1259347264 105597920 10659936
08:46:06 5065 8192000000 10181923568 10590097408 1148509120 12.296 99 69 136 1 1284008976 121175712 14324144
08:47:25 16419 8192000000 10218065136 10643169280 1162711584 12.429 100 65 129 0 1311460576 134791632 13957360
08:48:46 2301 8192000000 10219288864 10636210176 1170856656 12.505 100 68 136 3 1335902032 149724880 15320496
08:49:54 8457 8192000000 10267961152 10684473344 1161744384 12.420 99 79 156 2 1364291360 185410624 17136352
08:51:07 1916 8192000000 10330859184 10741620736 1188109744 12.666 100 90 180 1 1436547680 228645440 19792496
08:52:14 2225 8192000000 10355555360 10761953280 1181019408 12.600 100 96 193 1 1449140192 246170208 21950576
08:53:19 2509 8192000000 10312198816 10718715904 1170650752 12.503 100 85 168 0 1419055008 229964400 18439856
08:54:31 857 8192000000 10342822128 10755563520 1172074976 12.516 100 86 173 0 1441084976 248615536 20394464
08:55:36 1695 8192000000 10356855088 10760040448 1151740256 12.326 99 82 162 1 1449292528 279108112 18444160
08:56:42 1936 8192000000 10389512720 10788904960 1141446176 12.229 99 88 178 0 1480479984 318490096 20543712
08:57:51 3435 8192000000 10384899088 10815160320 1157640976 12.381 100 81 158 1 1482799216 307149552 18008688
08:59:02 3161 8192000000 10402635840 10810232832 1172342864 12.519 100 91 182 1 1503025440 308552640 22129936
09:00:08 16837 8192000000 10359159360 10815737856 1153704656 12.344 100 33 66 0 1475901312 313184992 9011664
09:02:01 8330 8192000000 10454330672 10885062656 1144338352 12.256 100 41 82 1 1551371728 395668304 11365072
09:03:32 28578 8192000000 10377182976 10843324416 1140540640 12.221 100 31 62 1 1477078080 328502208 8035232
09:05:02 38331 8192000000 10269238128 10775756800 1136896640 12.186 100 16 32 0 1358941760 217505664 4539456
09:06:54 66712 8192000000 10057523984 10484715520 1103907456 11.875 97 19 36 1 1130119408 22195696 4016256
09:09:04 59293 8192000000 10063289200 10480922624 1068693216 11.540 95 16 33 0 1089731280 18012160 3025904
09:11:09 138359 8192000000 10064485296 10538262528 1104360912 11.879 97 18 35 0 1175048592 66607760 4079920
09:14:28 114647 8192000000 10109819424 10542088192 1134204016 12.161 99 35 70 0 1196004576 53908784 7891776
09:17:25 21163 8192000000 10141644304 10588041216 1154849600 12.355 100 27 54 0 1236047296 75229920 5967776
09:18:56 57046 8192000000 10193027344 10648272896 1126124496 12.085 97 23 44 1 1265728064 134762400 4841168
09:20:53 19957 8192000000 10233853456 10679037952 1135600992 12.174 100 31 61 0 1329141840 186653248 6887600
09:22:24 32963 8192000000 10180440176 10682720256 1140897776 12.224 100 18 36 0 1280851936 135735680 4218480
09:24:06 33781 8192000000 10143251200 10620456960 1143315872 12.247 100 9 18 0 1259922528 114109600 2497056
09:25:47 35986 8192000000 10062478224 10521104384 1135050096 12.169 99 10 20 1 1173418656 35832560 2536000
09:27:23 48820 8192000000 10125562192 10601570304 1150472032 12.314 100 17 34 1 1214679024 60868672 3338320
09:29:13 53600 8192000000 10136639840 10599743488 1126216352 12.086 98 32 64 1 1216719968 83490960 7012656
09:31:59 50946 8192000000 10109589040 10559565824 1126428352 12.088 98 28 56 1 1165012848 32840976 5743520
09:33:57 44327 8192000000 10111244144 10574761984 1129944640 12.121 99 22 44 0 1187108880 52649600 4514640
09:35:41 23529 8192000000 10104864352 10560745472 1120685024 12.033 99 34 68 0 1177477472 49826288 6966160
09:37:11 120686 8192000000 10137411824 10572746752 1092488000 11.766 97 27 54 0 1164652384 66891712 5272672
09:40:33 28953 8192000000 10060544688 10523295744 1124358192 12.068 100 30 58 3 1193452656 63274208 5820256
09:42:04 71414 8192000000 10079592176 10550816768 1093485440 11.776 97 15 30 0 1170956224 74583104 2887680
09:44:16 44004 8192000000 10097762576 10603573248 1120457072 12.031 99 10 20 0 1208937536 86503168 1977296
09:46:01 39731 8192000000 10059965120 10517110784 1087251760 11.717 96 19 38 2 1117184464 25905856 4026848
09:47:41 98253 8192000000 10104091120 10544054272 1107437056 11.908 98 23 46 1 1173989600 61335248 5217296
09:50:22 28594 8192000000 10087075680 10538823680 1119589152 12.023 99 36 70 1 1165363792 38536528 7238112
09:51:56 70183 8192000000 10072000288 10524618752 1074753632 11.597 96 22 44 0 1126497248 47695216 4048400
09:54:15 17738 8192000000 10100437584 10537070592 1087615536 11.720 98 52 104 1 1163855104 65876192 10363376
09:55:34 22788 8192000000 10070934768 10490699776 1074472688 11.595 97 43 86 1 1132709968 49932880 8304400
09:56:57 119796 8192000000 10075366512 10524213248 1113498736 11.966 100 36 72 0 1204281888 83173120 7610032
09:59:57 53452 8192000000 10080405184 10560622592 1113763744 11.968 99 13 26 0 1165516576 48900752 2852080
10:01:52 53572 8192000000 10092175664 10570162176 1124091296 12.066 100 9 18 1 1184884480 59145296 1647888
10:03:50 55546 8192000000 10068386656 10583711744 1128003328 12.103 100 14 28 0 1175619632 44743616 2872688
10:05:58 82746 8192000000 10110282656 10579030016 1107319312 11.907 97 19 38 0 1196893616 85342064 4232240
10:08:36 40878 8192000000 10088583296 10548998144 1134434592 12.163 99 37 72 0 1176411696 35092160 6884944
10:10:19 56029 8192000000 10086221712 10566373376 1135595008 12.174 99 25 50 1 1199984032 59325840 5063184
10:12:20 69973 8192000000 10086890864 10552332288 1132423824 12.144 99 26 50 0 1173127200 35593312 5110064
10:14:32 75572 8192000000 10103125296 10598715392 1116495216 11.994 98 37 74 0 1190082208 66327840 7259152
10:16:48 39967 8192000000 10107561616 10566389760 1130507440 12.126 99 26 52 1 1191058880 55196768 5354672
10:18:28 62459 8192000000 10106472496 10586009600 1124893792 12.073 99 35 70 0 1195404976 63471072 7040112
10:21:43 46673 8192000000 10085519104 10547859456 1117740176 12.006 98 11 22 0 1186821408 66697152 2384080
10:23:40 53546 8192000000 10090552080 10586234880 1127640576 12.099 99 7 14 0 1203616448 74367856 1608016
10:25:34 70894 8192000000 10090901920 10602696704 1108613760 11.919 98 24 48 1 1178982896 64970800 5398336
10:27:48 40004 8192000000 10097877856 10547556352 1119537680 12.023 98 20 40 0 1170330352 46568032 4224640
10:29:36 47867 8192000000 10116563184 10591555584 1142588144 12.240 100 26 52 1 1206482560 58448288 5446128
10:31:30 87704 8192000000 10095922576 10560917504 1086963504 11.714 95 29 56 1 1151974320 59368160 5642656
10:34:07 54996 8192000000 10083839616 10526961664 1111663648 11.948 99 24 48 0 1171184464 54640288 4880528
10:36:08 94470 8192000000 9102865232 9356021760 241220944 2.860 22 7 12 1 263968768 21536944 1210880

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


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

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 163 v_add_invoice_to_stock 335544517
except 19 v_cancel_supplier_invoice 335544517

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

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          237216
MON$OLDEST_ACTIVE               237217
MON$OLDEST_SNAPSHOT             237217
MON$NEXT_TRANSACTION            237217
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                       2050848
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             802
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2588 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2588 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2588 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2588 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 ---
:87: 
:88: mscs-test-161.budzdorov.ru    Mon Sep 13 10:38:11 2021
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:90: 
--- end of diff output ---

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

13.09.21 10:39:59. 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-13 10:40:01 No severe PSQL-related problems occured
13.09.21 10:40:02. End of report.