Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 27.09.2021 04: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 15898120 kB
MemAvailable 30785852 kB
Buffers 20 kB
Cached 15783952 kB
SwapCached 3068 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-27 04:37:07
DTS_END                         2021-09-27 04:37:07.7850
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/55682
TRN_ID                          236629
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: 9112.89 19111 1093547
customer order: creation 175.28 398 21033
customer order: refuse 14.36 318 1723
order to supplier: creation 45.23 1451 5428
order to supplier: removal 3.38 108174 405
invoice (draft): creation 46.38 3443 5565
invoice (draft): removal 52.88 967 6346
invoice accept: apply 41.07 20351 4928
invoice accept: cancel 47.38 929 5685
customer reserve: creation 3777.73 204 453328
customer reserve: removal 4616.57 62 553988
realization accept: apply 84.77 186 10172
realization accept: cancel 78.95 128 9474
payment from customer: creation 59.13 108 7095
payment from customer: removal 5.59 95 671
payment to supplier: creation 50.10 120 6012
payment to supplier: removal 3.35 108 402
service: total inventory turnovers 3.87 3589 464
service: total monetary turnovers 5.98 529 717
service: aggregate perf. data 0.89 14714 107
service: refresh index statistics 0.03 226347 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 27-09-2021 04:40:08 to 27-09-2021 04:40:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 8732
TEST_TIME 2 6634
TEST_TIME 3 6592
TEST_TIME 4 6266
TEST_TIME 5 5899
TEST_TIME 6 5720
TEST_TIME 7 6118
TEST_TIME 8 6415
TEST_TIME 9 6317
TEST_TIME 10 6628
TEST_TIME 11 6915
TEST_TIME 12 7351
TEST_TIME 13 7639
TEST_TIME 14 7683
TEST_TIME 15 8053
TEST_TIME 16 8067
TEST_TIME 17 8336
TEST_TIME 18 9237
TEST_TIME 19 9339
TEST_TIME 20 9263
TEST_TIME 21 9764
TEST_TIME 22 10508
TEST_TIME 23 10836
TEST_TIME 24 10867
TEST_TIME 25 11288
TEST_TIME 26 11055
TEST_TIME 27 10725
TEST_TIME 28 10507
TEST_TIME 29 10422
TEST_TIME 30 10591
TEST_TIME 31 10395
TEST_TIME 32 10269
TEST_TIME 33 10140
TEST_TIME 34 10087
TEST_TIME 35 10004
TEST_TIME 36 9974
TEST_TIME 37 9924
TEST_TIME 38 9858
TEST_TIME 39 9733
TEST_TIME 40 9774
TEST_TIME 41 10072
TEST_TIME 42 10220
TEST_TIME 43 10184
TEST_TIME 44 10233
TEST_TIME 45 10181
TEST_TIME 46 10182
TEST_TIME 47 10146
TEST_TIME 48 10063
TEST_TIME 49 10179
TEST_TIME 50 10139
TEST_TIME 51 10171
TEST_TIME 52 10030
TEST_TIME 53 9933
TEST_TIME 54 9960
TEST_TIME 55 9863
TEST_TIME 56 9827
TEST_TIME 57 9755
TEST_TIME 58 9814
TEST_TIME 59 9720
TEST_TIME 60 9678
TEST_TIME 61 9571
TEST_TIME 62 9649
TEST_TIME 63 9625
TEST_TIME 64 9647
TEST_TIME 65 9592
TEST_TIME 66 9609
TEST_TIME 67 9623
TEST_TIME 68 9599
TEST_TIME 69 9528
TEST_TIME 70 9532
TEST_TIME 71 9538
TEST_TIME 72 9574
TEST_TIME 73 9535
TEST_TIME 74 9557
TEST_TIME 75 9528
TEST_TIME 76 9586
TEST_TIME 77 9543
TEST_TIME 78 9485
TEST_TIME 79 9431
TEST_TIME 80 9370
TEST_TIME 81 9341
TEST_TIME 82 9380
TEST_TIME 83 9311
TEST_TIME 84 9301
TEST_TIME 85 9323
TEST_TIME 86 9274
TEST_TIME 87 9346
TEST_TIME 88 9348
TEST_TIME 89 9350
TEST_TIME 90 9342
TEST_TIME 91 9337
TEST_TIME 92 9358
TEST_TIME 93 9366
TEST_TIME 94 9428
TEST_TIME 95 9443
TEST_TIME 96 9402
TEST_TIME 97 9408
TEST_TIME 98 9404
TEST_TIME 99 9316
TEST_TIME 100 9300
TEST_TIME 101 9278
TEST_TIME 102 9263
TEST_TIME 103 9221
TEST_TIME 104 9168
TEST_TIME 105 9137
TEST_TIME 106 9133
TEST_TIME 107 9216
TEST_TIME 108 9163
TEST_TIME 109 9164
TEST_TIME 110 9230
TEST_TIME 111 9222
TEST_TIME 112 9215
TEST_TIME 113 9193
TEST_TIME 114 9209
TEST_TIME 115 9208
TEST_TIME 116 9232
TEST_TIME 117 9284
TEST_TIME 118 9253
TEST_TIME 119 9192
TEST_TIME 120 9119

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 27-09-2021 04:40:12 to 27-09-2021 04:40:16.

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 1514122 1514122 0 0.00 0 22181 7 0 0
doc_list_biud 1514114 1514114 0 0.00 0 69235 29 0 0
sp_add_invoice_to_stock 5079 4928 151 2.97 27 245197 20351 0 151
sp_cancel_adding_invoice 5685 5685 0 0.00 6 78397 929 0 0
sp_cancel_client_order 1723 1723 0 0.00 5 10042 318 0 0
sp_cancel_customer_reserve 553988 553988 0 0.00 1 22598 62 0 0
sp_cancel_pay_from_customer 671 671 0 0.00 3 8118 95 0 0
sp_cancel_pay_to_supplier 402 402 0 0.00 3 10935 108 0 0
sp_cancel_supplier_invoice 6367 6346 21 0.32 3 69239 967 0 21
sp_cancel_supplier_order 405 405 0 0.00 6 1014217 108175 0 0
sp_cancel_write_off 9474 9474 0 0.00 1 12108 129 0 0
sp_client_order 21033 21033 0 0.00 4 33006 399 0 0
sp_customer_reserve 453328 453328 0 0.00 0 185331 204 0 0
sp_fill_shopping_cart 32008 32008 0 0.00 1 13481 41 0 0
sp_fill_shopping_cart_clo_res 453307 453307 0 0.00 0 16477 14 0 0
sp_get_clo_for_invoice 4907 4907 0 0.00 0 37253 319 0 0
sp_kill_cost_storno 16232 16232 0 0.00 0 22172 56 0 0
sp_kill_qstorno_ret_qs2qd 1124201 1124201 0 0.00 0 69233 34 0 0
sp_kill_qty_storno 592700 592700 0 0.00 0 69235 74 0 0
sp_lock_selected_doc 20765 20765 0 0.00 0 7607 6 0 0
sp_make_cost_storno 28186 28186 0 0.00 0 19515 50 0 0
sp_multiply_rows_for_pdistr 28184 28184 0 0.00 0 11381 31 0 0
sp_multiply_rows_for_qdistr 21033 21033 0 0.00 0 31542 180 0 0
sp_pay_from_customer 7095 7095 0 0.00 5 19235 108 0 0
sp_pay_to_supplier 6012 6012 0 0.00 4 12243 120 0 0
sp_payment_common 13105 13105 0 0.00 1 13381 26 0 0
sp_qd_handle_on_cancel_clo 1723 1723 0 0.00 0 10032 276 0 0
sp_qd_handle_on_invoice_upd_sts 10592 10592 0 0.00 0 20598 338 0 0
sp_qd_handle_on_reserve_upd_sts 10172 10172 0 0.00 0 15862 64 0 0
sp_reserve_write_off 10172 10172 0 0.00 7 19027 186 0 0
sp_supplier_invoice 5565 5565 0 0.00 7 89206 3444 0 0
sp_supplier_order 5428 5428 0 0.00 6 69718 1452 0 0
srv_aggregate_perf_data 107 107 0 0.00 2877 122456 14715 0 0
srv_fill_mon_cache_memory 63 63 0 0.00 1813 214474 49159 0 0
srv_find_qd_qs_mism 1046335 1046335 0 0.00 0 17999 7 0 0
srv_make_invnt_saldo 464 464 0 0.00 63 144386 3590 0 0
srv_make_money_saldo 717 717 0 0.00 1 10976 529 0 0
srv_recalc_idx_stat 4 4 0 0.00 184454 270713 226347 0 0
t$perf-abend:sp_add_invoice_to_stock 151 151 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 21 21 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4928 4928 0 0.00 0 24770 184 0 0
t$perf-norm:sp_cancel_adding_invoice 442 442 0 0.00 0 21158 141 0 0
t$perf-norm:sp_cancel_client_order 1723 1723 0 0.00 0 17455 29 0 0
t$perf-norm:sp_cancel_customer_reserve 634 634 0 0.00 0 13280 57 0 0
t$perf-norm:sp_cancel_pay_from_customer 671 671 0 0.00 0 2946 10 0 0
t$perf-norm:sp_cancel_pay_to_supplier 402 402 0 0.00 0 137 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 422 422 0 0.00 0 2459 26 0 0
t$perf-norm:sp_cancel_supplier_order 405 405 0 0.00 0 18338 1324 0 0
t$perf-norm:sp_cancel_write_off 988 988 0 0.00 0 16456 25 0 0
t$perf-norm:sp_client_order 21033 21033 0 0.00 0 18773 19 0 0
t$perf-norm:sp_customer_reserve 989 989 0 0.00 0 8859 12 0 0
t$perf-norm:sp_pay_from_customer 7095 7095 0 0.00 0 20618 14 0 0
t$perf-norm:sp_pay_to_supplier 6012 6012 0 0.00 0 18647 13 0 0
t$perf-norm:sp_reserve_write_off 10172 10172 0 0.00 0 4772 10 0 0
t$perf-norm:sp_supplier_invoice 5565 5565 0 0.00 0 3689 14 0 0
t$perf-norm:sp_supplier_order 5428 5428 0 0.00 0 4397 17 0 0
t$perf-norm:srv_aggregate_perf_data 107 107 0 0.00 0 274 13 0 0
t$perf-norm:srv_fill_mon_cache_memory 63 63 0 0.00 0 5 0 0 0
t$perf-norm:srv_make_invnt_saldo 464 464 0 0.00 0 535 3 0 0
t$perf-norm:srv_make_money_saldo 717 717 0 0.00 0 398 3 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 3 0 0
v_add_invoice_to_stock 10112 9810 302 2.98 0 4274 16 0 302
v_all_customers 37776 37776 0 0.00 0 17 1 0 0
v_all_suppliers 30876 30876 0 0.00 0 23 1 0 0
v_all_wares 42066 42066 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 884 884 0 0.00 0 7294 19 0 0
v_cancel_client_order 3446 3446 0 0.00 0 6935 8 0 0
v_cancel_customer_prepayment 1342 1342 0 0.00 0 765 5 0 0
v_cancel_customer_reserve 1268 1268 0 0.00 0 1304 5 0 0
v_cancel_payment_to_supplier 804 804 0 0.00 0 10921 29 0 0
v_cancel_supplier_invoice 886 844 42 4.74 0 3327 21 0 42
v_cancel_supplier_order 810 810 0 0.00 0 220 3 0 0
v_cancel_write_off 1976 1976 0 0.00 0 6979 18 0 0
v_max_id_clo_ord 5428 5428 0 0.00 0 942 2 0 0
v_max_id_clo_res 989 989 0 0.00 0 3701 18 0 0
v_max_id_ord_sup 5547 5547 0 0.00 0 4471 7 0 0
v_max_non_paid_invoice 1547 1547 0 0.00 0 2828 6 0 0
v_max_non_paid_realizn 3931 3931 0 0.00 0 1210 3 0 0
v_min_id_clo_ord 5428 5428 0 0.00 0 1094 4 0 0
v_min_id_clo_res 989 989 0 0.00 0 4257 22 0 0
v_min_id_ord_sup 5547 5547 0 0.00 0 6319 5 0 0
v_min_non_paid_invoice 6010 6010 0 0.00 0 6477 19 0 0
v_min_non_paid_realizn 7095 7095 0 0.00 0 7712 19 0 0
v_our_firm 10618 10618 0 0.00 0 11 1 0 0
v_reserve_write_off 20344 20344 0 0.00 0 11003 7 0 0
x_lock_depdocs_on_canc_invoice 11609 11609 0 0.00 0 18650 120 0 0
x_lock_depdocs_on_canc_sup_ord 405 405 0 0.00 0 2492 268 0 0
x_make_qstorno_1000_1200 5428 5428 0 0.00 2 69693 1376 0 0
x_make_qstorno_1200_2000 5565 5565 0 0.00 3 82818 3288 0 0
x_make_qstorno_2100_3300 453328 453328 0 0.00 0 185330 187 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 27-09-2021 04:40:17 to 27-09-2021 04:40:21.

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
02:07:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
02:08:12 40961 8192000000 9734510816 10034679808 802183728 8.918 100 90 180 1 832863824 8364608 22315488
02:09:58 16888 8192000000 9769052336 10079764480 845840048 9.358 100 86 172 0 879113024 12688896 20584080
02:11:21 14301 8192000000 9809893440 10134401024 883775264 9.737 100 80 159 1 924260368 22585456 17899648
02:12:42 13604 8192000000 9877510240 10212696064 922470384 10.120 100 79 159 1 999113056 59490288 17152384
02:14:00 7315 8192000000 9934074912 10280759296 970507008 10.592 100 87 174 0 1052246144 63660944 18078192
02:15:10 4384 8192000000 9982568992 10340450304 1008473952 10.961 100 79 158 0 1095481328 71137184 15870192
02:16:17 4548 8192000000 10001974048 10365673472 1030733664 11.176 100 83 167 0 1120645824 73023872 16888288
02:17:26 2456 8192000000 10034751216 10403037184 1057174336 11.429 100 92 183 0 1149692384 73823552 18694496
02:18:29 3029 8192000000 10051202144 10424459264 1067032448 11.524 100 80 160 2 1164145216 80832192 16280576
02:19:33 2715 8192000000 10000737440 10381565952 1073490304 11.585 100 61 119 2 1113148448 26089904 13568240
02:20:37 7082 8192000000 9989660224 10383482880 1080732928 11.654 100 30 58 1 1107576960 20612336 6231696
02:21:47 6746 8192000000 9996243584 10398715904 1086130416 11.706 100 36 71 1 1118710704 25337616 7242672
02:22:54 74142 8192000000 10005946688 10414751744 1089574256 11.739 100 28 56 1 1120637184 24542464 6520464
02:25:10 4860 8192000000 10033022192 10427117568 1095130144 11.791 100 50 99 1 1138264144 32230144 10903856
02:26:16 7570 8192000000 10018083392 10426437632 1094332288 11.784 99 48 97 0 1114798256 9415456 11050512
02:27:24 54492 8192000000 9973568736 10366808064 1050965296 11.370 97 38 73 2 1069008688 9528720 8514672
02:29:21 4870 8192000000 9959650880 10336780288 981365184 10.697 94 49 98 1 1018057184 26932560 9759440
02:30:29 6314 8192000000 9945288928 10336845824 1021672672 11.088 100 42 83 0 1049593632 18893520 9027440
02:31:39 8450 8192000000 9951996192 10344230912 1021941024 11.091 99 45 90 1 1046065936 13518464 10606448
02:32:48 18231 8192000000 9950344928 10344960000 1012165088 10.996 98 23 45 2 1040913584 23777872 4970624
02:34:12 7371 8192000000 9989888864 10386300928 1053151712 11.391 100 41 80 1 1074307280 12877904 8277664
02:35:19 7785 8192000000 9989799440 10400337920 1066983376 11.523 100 40 80 1 1100818928 25135376 8700176
02:36:27 55356 8192000000 10004361552 10407235584 1078547920 11.634 100 37 74 2 1111801840 25467488 7786432
02:38:33 14814 8192000000 10056304080 10456891392 1105392848 11.889 100 52 100 1 1160580592 44945840 10241904
02:39:52 23687 8192000000 10075404800 10483535872 1122406928 12.050 100 41 81 0 1191508384 60049552 9051904
02:41:20 16587 8192000000 10135449440 10546970624 1141537184 12.230 100 51 100 0 1241916096 88923840 11455072
02:42:46 17578 8192000000 10175043648 10588082176 1149131648 12.301 100 56 112 1 1282904192 121842192 11930352
02:44:09 10568 8192000000 10205004960 10630017024 1155628048 12.362 100 56 112 0 1312702592 144347568 12726976
02:46:10 4327 8192000000 10227276496 10630234112 1168854880 12.486 100 77 154 0 1330441008 144608688 16977440
02:47:20 7126 8192000000 10287169712 10694905856 1176701920 12.559 100 75 149 1 1385930896 192979440 16249536
02:48:31 4665 8192000000 10300115728 10709430272 1180101840 12.591 100 81 162 1 1403339344 205032784 18204720
02:49:40 2284 8192000000 10325516672 10731720704 1175573280 12.549 100 86 170 2 1431177600 236522880 19081440
02:50:46 3678 8192000000 10328987424 10738413568 1152104128 12.329 98 74 148 0 1421607664 251951856 17551680
02:51:53 1810 8192000000 10375167360 10778406912 1169928256 12.496 100 85 170 2 1469263696 279693216 19642224
02:52:59 4607 8192000000 10372039520 10777899008 1167858144 12.477 100 80 154 1 1464881408 279332208 17691056
02:54:08 4148 8192000000 10394358480 10806239232 1168497888 12.483 100 78 154 1 1495574208 308633440 18442880
02:55:15 11421 8192000000 10308711808 10746458112 1143775040 12.251 99 40 77 2 1422515680 268296544 10444096
02:56:33 15666 8192000000 10311721568 10741035008 1138581520 12.202 100 29 58 0 1408339776 261776688 7981568
02:57:50 21896 8192000000 10274323440 10729611264 1100968224 11.847 97 32 64 1 1378309104 268165552 9175328
02:59:15 12793 8192000000 10216539936 10668494848 1114861024 11.978 99 23 46 0 1312788080 191798704 6128352
03:00:31 14117 8192000000 10144700336 10596904960 1109514160 11.928 99 23 45 0 1232266128 117842912 4909056
03:01:54 42093 8192000000 10093914352 10559438848 1100394176 11.841 98 22 43 1 1213630432 108420064 4816192
03:03:42 102519 8192000000 10060547280 10480140288 1075511104 11.605 96 36 71 2 1120634144 37835856 7287184
03:06:27 59297 8192000000 10034393856 10458304512 1069602112 11.548 96 31 62 2 1117151168 41157568 6391488
03:08:30 101163 8192000000 10105067424 10530283520 1086739408 11.712 96 27 52 2 1144178384 52186368 5252608
03:11:13 17929 8192000000 10194459920 10618859520 1142390048 12.238 100 68 136 0 1265692832 108793328 14509456
03:12:39 214449 8192000000 10243344704 10706677760 1156259920 12.368 99 33 66 0 1340045584 176485952 7299712
03:17:14 41869 8192000000 10219076528 10724581376 1147050304 12.282 100 22 44 0 1344451200 192015008 5385888
03:18:56 77258 8192000000 10127581664 10623152128 1146458976 12.276 100 12 25 0 1205760704 56470800 2830928
03:21:32 71247 8192000000 10082679792 10533335040 1148421568 12.295 100 22 42 1 1191092512 38181616 4489328
03:24:31 40665 8192000000 10127120560 10560786432 1135839152 12.176 98 20 38 1 1200880976 60819184 4222640
03:26:14 53430 8192000000 10075658592 10524712960 1109209952 11.925 97 30 61 1 1141449088 26270688 5968448
03:28:08 45225 8192000000 10083439696 10539536384 1141809632 12.233 100 21 42 1 1177685968 31507712 4368624
03:29:58 80290 8192000000 10068194240 10516299776 1118979408 12.017 98 21 41 0 1162810384 39310336 4520640
03:32:19 30710 8192000000 10059661408 10532319232 1132053968 12.141 100 20 40 1 1173384304 36538000 4792336
03:33:55 70716 8192000000 10108590208 10553389056 1112536368 11.956 97 20 40 0 1179047488 62409424 4101696
03:36:06 30531 8192000000 10081671568 10524819456 1131803280 12.138 99 25 50 0 1177446128 40283344 5359504
03:37:40 58376 8192000000 10121419232 10586169344 1134583872 12.165 99 16 32 2 1209021008 70687776 3749360
03:39:39 69226 8192000000 10087023920 10553655296 1144491104 12.258 100 11 22 0 1207604240 61034304 2078832
03:41:53 19085 8192000000 10078394624 10506764288 1106183168 11.896 97 47 92 1 1152071584 36425808 9462608
03:43:17 83227 8192000000 10042199824 10509991936 1070752112 11.559 95 14 28 1 1141434768 67678272 3004384
03:45:48 61934 8192000000 10085825424 10531454976 1091741520 11.759 97 13 26 0 1156783680 62623648 2418512
03:47:51 52506 8192000000 10115922896 10563641344 1087523312 11.719 97 33 65 0 1155640096 61062880 7053904
03:51:22 50706 8192000000 10063581280 10499952640 1086838496 11.713 97 34 68 1 1132995856 39325824 6831536
03:53:18 59836 8192000000 10091330416 10525921280 1102975872 11.866 98 27 54 1 1181415104 73069104 5370128
03:55:19 107623 8192000000 10041047776 10483183616 1088978736 11.733 98 24 48 1 1130153424 35875520 5299168
03:58:10 41568 8192000000 10134821792 10598887424 1125650864 12.080 100 17 34 1 1246576176 117172848 3752464
03:59:53 23767 8192000000 10098741328 10624090112 1124651632 12.071 100 7 14 0 1203953712 77855856 1446224
04:01:16 82169 8192000000 10069277664 10577141760 1121090672 12.037 100 12 24 0 1198535456 74845280 2599504
04:03:41 62092 8192000000 10127979904 10564841472 1109706032 11.930 98 25 48 0 1202752576 87814000 5232544
04:05:44 64359 8192000000 10095376352 10549452800 1120813920 12.035 99 36 72 0 1162718528 34649200 7255408
04:07:48 83515 8192000000 10104060976 10576044032 1142051280 12.235 100 28 56 1 1214617344 67001888 5564176
04:10:15 143735 8192000000 10091136464 10606993408 1117719520 12.005 97 11 22 0 1198643440 78703520 2220400
04:13:40 38247 8192000000 10109624128 10569179136 1115925808 11.988 98 28 56 1 1159448112 38289504 5232800
04:15:25 108188 8192000000 10110587584 10575515648 1141963136 12.234 100 26 52 2 1228865584 80491056 6411392
04:18:32 61672 8192000000 10065653664 10547458048 1110785056 11.940 97 4 8 0 1157167344 45514272 868016
04:20:33 30756 8192000000 10087780000 10545172480 1138955520 12.206 100 11 22 1 1198529168 56820240 2753408
04:22:06 73725 8192000000 10168879712 10633863168 1140827712 12.223 99 14 28 0 1249129968 105357152 2945104
04:24:20 88856 8192000000 10114799360 10574426112 1104055360 11.876 96 19 38 0 1166959296 58746288 4157648
04:26:49 58879 8192000000 10086490784 10556727296 1087544624 11.719 95 25 50 0 1164521536 71344320 5632592
04:29:03 58483 8192000000 10093510048 10563158016 1136374400 12.181 100 31 62 0 1189067648 46517280 6175968
04:31:02 25108 8192000000 10091726528 10520723456 1121971888 12.046 99 40 80 1 1169126208 39258656 7895664
04:32:29 40757 8192000000 10074633904 10565529600 1081572672 11.662 96 36 72 0 1151927200 63290480 7064048
04:34:16 31042 8192000000 10133746032 10610974720 1106539184 11.900 99 33 66 0 1240778800 127242192 6997424
04:35:50 90977 8192000000 9226239904 9539883008 361017376 4.220 33 16 30 1 409211728 44879440 3314912

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:14, from 27-09-2021 04:40:23 to 27-09-2021 04:40:37.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 151 v_add_invoice_to_stock 335544517
except 21 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 27-09-2021 04:40:38 to 27-09-2021 04:40:40.

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          236923
MON$OLDEST_ACTIVE               236924
MON$OLDEST_SNAPSHOT             236924
MON$NEXT_TRANSACTION            236924
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                       2051424
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             806
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2618 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2618 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2618 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2618 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 ---
--- end of diff output ---

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

27.09.21 04:40:41. 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-27 04:40:44 No severe PSQL-related problems occured
27.09.21 04:40:45. End of report.