Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 01.10.2021 13:37
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 15762436 kB
MemAvailable 30741936 kB
Buffers 0 kB
Cached 15926868 kB
SwapCached 4696 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-10-01 13:37:05
DTS_END                         2021-10-01 13:37:05.9740
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/38104
TRN_ID                          224667
ATT_ID                          668
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: 9253.10 25610 1110372
customer order: creation 158.10 430 18972
customer order: refuse 13.31 354 1597
order to supplier: creation 42.11 1512 5053
order to supplier: removal 3.32 139093 398
invoice (draft): creation 43.39 3651 5207
invoice (draft): removal 55.86 970 6703
invoice accept: apply 38.52 19965 4622
invoice accept: cancel 50.98 924 6118
customer reserve: creation 3557.83 196 426939
customer reserve: removal 5002.23 73 600268
realization accept: apply 77.43 204 9292
realization accept: cancel 89.80 128 10776
payment from customer: creation 55.43 113 6651
payment from customer: removal 5.18 111 621
payment to supplier: creation 46.18 144 5541
payment to supplier: removal 3.27 97 392
service: total inventory turnovers 3.39 4250 407
service: total monetary turnovers 5.87 608 704
service: aggregate perf. data 0.88 14500 106
service: refresh index statistics 0.04 324879 5

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 01-10-2021 13:37:48 to 01-10-2021 13:37:51.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6637
TEST_TIME 2 4599
TEST_TIME 3 5236
TEST_TIME 4 4942
TEST_TIME 5 4850
TEST_TIME 6 4853
TEST_TIME 7 5037
TEST_TIME 8 4809
TEST_TIME 9 4714
TEST_TIME 10 5069
TEST_TIME 11 5511
TEST_TIME 12 5942
TEST_TIME 13 6081
TEST_TIME 14 6198
TEST_TIME 15 6355
TEST_TIME 16 6455
TEST_TIME 17 6615
TEST_TIME 18 6698
TEST_TIME 19 6911
TEST_TIME 20 7600
TEST_TIME 21 7880
TEST_TIME 22 8023
TEST_TIME 23 8123
TEST_TIME 24 8115
TEST_TIME 25 8682
TEST_TIME 26 9807
TEST_TIME 27 10418
TEST_TIME 28 10852
TEST_TIME 29 10770
TEST_TIME 30 10519
TEST_TIME 31 10436
TEST_TIME 32 10163
TEST_TIME 33 10113
TEST_TIME 34 9945
TEST_TIME 35 9879
TEST_TIME 36 9936
TEST_TIME 37 9871
TEST_TIME 38 9977
TEST_TIME 39 9806
TEST_TIME 40 9710
TEST_TIME 41 9702
TEST_TIME 42 9685
TEST_TIME 43 9611
TEST_TIME 44 9541
TEST_TIME 45 9502
TEST_TIME 46 9506
TEST_TIME 47 9466
TEST_TIME 48 9366
TEST_TIME 49 9472
TEST_TIME 50 9426
TEST_TIME 51 9400
TEST_TIME 52 9464
TEST_TIME 53 9365
TEST_TIME 54 9398
TEST_TIME 55 9634
TEST_TIME 56 9645
TEST_TIME 57 9662
TEST_TIME 58 9703
TEST_TIME 59 9657
TEST_TIME 60 9560
TEST_TIME 61 9458
TEST_TIME 62 9448
TEST_TIME 63 9377
TEST_TIME 64 9414
TEST_TIME 65 9338
TEST_TIME 66 9287
TEST_TIME 67 9259
TEST_TIME 68 9178
TEST_TIME 69 9165
TEST_TIME 70 9127
TEST_TIME 71 9090
TEST_TIME 72 9055
TEST_TIME 73 9238
TEST_TIME 74 9488
TEST_TIME 75 9504
TEST_TIME 76 9574
TEST_TIME 77 9532
TEST_TIME 78 9529
TEST_TIME 79 9482
TEST_TIME 80 9541
TEST_TIME 81 9476
TEST_TIME 82 9370
TEST_TIME 83 9412
TEST_TIME 84 9377
TEST_TIME 85 9319
TEST_TIME 86 9244
TEST_TIME 87 9200
TEST_TIME 88 9127
TEST_TIME 89 9077
TEST_TIME 90 9095
TEST_TIME 91 9105
TEST_TIME 92 9197
TEST_TIME 93 9173
TEST_TIME 94 9177
TEST_TIME 95 9180
TEST_TIME 96 9209
TEST_TIME 97 9249
TEST_TIME 98 9262
TEST_TIME 99 9360
TEST_TIME 100 9317
TEST_TIME 101 9362
TEST_TIME 102 9416
TEST_TIME 103 9343
TEST_TIME 104 9283
TEST_TIME 105 9235
TEST_TIME 106 9252
TEST_TIME 107 9224
TEST_TIME 108 9198
TEST_TIME 109 9179
TEST_TIME 110 9155
TEST_TIME 111 9156
TEST_TIME 112 9180
TEST_TIME 113 9144
TEST_TIME 114 9134
TEST_TIME 115 9191
TEST_TIME 116 9252
TEST_TIME 117 9254
TEST_TIME 118 9257
TEST_TIME 119 9326
TEST_TIME 120 9255

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 01-10-2021 13:37:52 to 01-10-2021 13:37:56.

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 1506740 1506740 0 0.00 0 19951 7 0 0
doc_list_biud 1506740 1506740 0 0.00 0 66313 36 0 0
sp_add_invoice_to_stock 4740 4622 118 2.48 36 284990 19966 0 118
sp_cancel_adding_invoice 6118 6118 0 0.00 4 83539 925 0 0
sp_cancel_client_order 1597 1597 0 0.00 4 9645 354 0 0
sp_cancel_customer_reserve 600268 600268 0 0.00 1 52211 73 0 0
sp_cancel_pay_from_customer 621 621 0 0.00 3 5275 111 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 5212 97 0 0
sp_cancel_supplier_invoice 6712 6703 9 0.13 2 49702 970 0 9
sp_cancel_supplier_order 398 398 0 0.00 6 1359803 139094 0 0
sp_cancel_write_off 10776 10776 0 0.00 1 66463 129 0 0
sp_client_order 18972 18972 0 0.00 4 31185 430 0 0
sp_customer_reserve 426939 426939 0 0.00 0 87813 197 0 0
sp_fill_shopping_cart 29223 29223 0 0.00 1 14384 43 0 0
sp_fill_shopping_cart_clo_res 426935 426935 0 0.00 0 18964 17 0 0
sp_get_clo_for_invoice 4616 4616 0 0.00 0 33060 408 0 0
sp_kill_cost_storno 17907 17907 0 0.00 0 19939 68 0 0
sp_kill_qstorno_ret_qs2qd 1218413 1218413 0 0.00 0 66310 39 0 0
sp_kill_qty_storno 639763 639763 0 0.00 0 66311 84 0 0
sp_lock_selected_doc 19204 19204 0 0.00 0 8071 5 0 0
sp_make_cost_storno 26095 26095 0 0.00 0 13639 61 0 0
sp_multiply_rows_for_pdistr 26095 26095 0 0.00 0 9274 34 0 0
sp_multiply_rows_for_qdistr 18972 18972 0 0.00 0 31176 209 0 0
sp_pay_from_customer 6651 6651 0 0.00 4 12950 114 0 0
sp_pay_to_supplier 5541 5541 0 0.00 5 17019 145 0 0
sp_payment_common 12192 12192 0 0.00 1 16987 21 0 0
sp_qd_handle_on_cancel_clo 1597 1597 0 0.00 0 9638 328 0 0
sp_qd_handle_on_invoice_upd_sts 10729 10729 0 0.00 0 34080 377 0 0
sp_qd_handle_on_reserve_upd_sts 9292 9292 0 0.00 0 17325 64 0 0
sp_reserve_write_off 9292 9292 0 0.00 7 17344 204 0 0
sp_supplier_invoice 5207 5207 0 0.00 7 71280 3651 0 0
sp_supplier_order 5053 5053 0 0.00 6 27824 1512 0 0
srv_aggregate_perf_data 106 106 0 0.00 2897 164902 14501 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 941 137268 42643 0 0
srv_find_qd_qs_mism 1066655 1066655 0 0.00 0 17942 6 0 0
srv_make_invnt_saldo 407 407 0 0.00 75 107960 4250 0 0
srv_make_money_saldo 704 704 0 0.00 1 12683 609 0 0
srv_recalc_idx_stat 5 5 0 0.00 193481 497245 324880 0 0
t$perf-abend:sp_add_invoice_to_stock 118 118 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 9 9 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4622 4622 0 0.00 0 20384 222 0 0
t$perf-norm:sp_cancel_adding_invoice 395 395 0 0.00 1 11843 167 0 0
t$perf-norm:sp_cancel_client_order 1597 1597 0 0.00 0 4305 17 0 0
t$perf-norm:sp_cancel_customer_reserve 637 637 0 0.00 0 4422 18 0 0
t$perf-norm:sp_cancel_pay_from_customer 621 621 0 0.00 0 554 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 1005 4 0 0
t$perf-norm:sp_cancel_supplier_invoice 359 359 0 0.00 0 992 16 0 0
t$perf-norm:sp_cancel_supplier_order 398 398 0 0.00 0 25311 2010 0 0
t$perf-norm:sp_cancel_write_off 902 902 0 0.00 0 800 6 0 0
t$perf-norm:sp_client_order 18972 18972 0 0.00 0 12131 25 0 0
t$perf-norm:sp_customer_reserve 940 940 0 0.00 0 2844 7 0 0
t$perf-norm:sp_pay_from_customer 6651 6651 0 0.00 0 7640 12 0 0
t$perf-norm:sp_pay_to_supplier 5541 5541 0 0.00 0 9793 14 0 0
t$perf-norm:sp_reserve_write_off 9292 9292 0 0.00 0 8345 14 0 0
t$perf-norm:sp_supplier_invoice 5207 5207 0 0.00 0 7990 22 0 0
t$perf-norm:sp_supplier_order 5053 5053 0 0.00 0 11394 22 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 462 14 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 109 3 0 0
t$perf-norm:srv_make_invnt_saldo 407 407 0 0.00 0 2846 18 0 0
t$perf-norm:srv_make_money_saldo 704 704 0 0.00 0 2502 9 0 0
t$perf-norm:srv_recalc_idx_stat 5 5 0 0.00 1 6 3 0 0
v_add_invoice_to_stock 9458 9222 236 2.49 0 8331 21 0 236
v_all_customers 34716 34716 0 0.00 0 17 1 0 0
v_all_suppliers 28688 28688 0 0.00 0 116 1 0 0
v_all_wares 37944 37944 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 790 790 0 0.00 0 3371 15 0 0
v_cancel_client_order 3194 3194 0 0.00 0 5847 6 0 0
v_cancel_customer_prepayment 1242 1242 0 0.00 0 4899 15 0 0
v_cancel_customer_reserve 1274 1274 0 0.00 0 6094 9 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 3151 8 0 0
v_cancel_supplier_invoice 736 718 18 2.44 0 5855 25 0 18
v_cancel_supplier_order 796 796 0 0.00 0 2845 10 0 0
v_cancel_write_off 1804 1804 0 0.00 0 4366 16 0 0
v_max_id_clo_ord 5053 5053 0 0.00 0 1152 2 0 0
v_max_id_clo_res 940 940 0 0.00 0 1958 16 0 0
v_max_id_ord_sup 5196 5196 0 0.00 0 4267 5 0 0
v_max_non_paid_invoice 1446 1446 0 0.00 0 282 2 0 0
v_max_non_paid_realizn 3503 3503 0 0.00 0 734 3 0 0
v_min_id_clo_ord 5053 5053 0 0.00 0 3497 8 0 0
v_min_id_clo_res 940 940 0 0.00 0 1614 14 0 0
v_min_id_ord_sup 5196 5196 0 0.00 0 9852 8 0 0
v_min_non_paid_invoice 5541 5541 0 0.00 0 11310 17 0 0
v_min_non_paid_realizn 6651 6651 0 0.00 0 7531 13 0 0
v_our_firm 9524 9524 0 0.00 0 12 1 0 0
v_reserve_write_off 18584 18584 0 0.00 0 6024 9 0 0
x_lock_depdocs_on_canc_invoice 12462 12462 0 0.00 0 12506 108 0 0
x_lock_depdocs_on_canc_sup_ord 398 398 0 0.00 0 21383 527 0 0
x_make_qstorno_1000_1200 5053 5053 0 0.00 2 27815 1422 0 0
x_make_qstorno_1200_2000 5207 5207 0 0.00 4 71273 3500 0 0
x_make_qstorno_2100_3300 426939 426939 0 0.00 0 87811 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 01-10-2021 13:37:57 to 01-10-2021 13:38:01.

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:09 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
11:08:12 42957 8192000000 9734682448 10033811456 799400288 8.890 100 93 185 0 830832224 8431728 23000208
11:09:59 26656 8192000000 9766577904 10076999680 841727072 9.317 100 93 186 0 876379616 12394752 22257792
11:11:37 15833 8192000000 9822752928 10147323904 886315472 9.762 100 83 166 0 930860048 25612896 18931680
11:13:06 15437 8192000000 9897800832 10237280256 936320944 10.257 100 88 176 0 1002536864 46635408 19580512
11:14:23 10605 8192000000 9945247840 10298638336 979583856 10.680 100 76 151 1 1047956336 52387424 15985056
11:15:37 4432 8192000000 9988528752 10344673280 1010684576 10.982 100 88 173 0 1096941072 68933152 17323344
11:16:45 2621 8192000000 9993679216 10363785216 1028901968 11.158 100 66 132 0 1107387392 65157456 13327968
11:17:50 1867 8192000000 10046673088 10416291840 1054226288 11.401 100 84 168 0 1156471616 84914800 17330528
11:18:53 2034 8192000000 10034071296 10404110336 1065114992 11.505 100 95 192 1 1150542272 65620224 19807056
11:19:57 24235 8192000000 10000877328 10383368192 1064481792 11.499 100 49 97 2 1105250784 30327824 10441168
11:21:22 6949 8192000000 9998619456 10389114880 1076976928 11.619 100 29 58 3 1112457456 28823232 6657296
11:22:30 4845 8192000000 10003683216 10397437952 1086103280 11.706 100 39 78 1 1115586688 21076288 8407120
11:23:38 20242 8192000000 10021574736 10436321280 1086877664 11.713 100 47 93 1 1125508336 28418112 10212560
11:25:03 9164 8192000000 10027296000 10423234560 1094541392 11.786 100 34 67 1 1138997152 36989904 7465856
11:26:14 5540 8192000000 10012824816 10421268480 1104798768 11.883 100 48 95 2 1137660704 21597456 11264480
11:27:20 65772 8192000000 10004911504 10406535168 1082994352 11.676 98 34 67 1 1101723984 10375536 8354096
11:29:28 4009 8192000000 9997032544 10388193280 1072457872 11.576 100 56 112 1 1108715280 24133280 12124128
11:30:36 7452 8192000000 9944889936 10328113152 988034720 10.762 94 32 64 0 1006821728 11351936 7435072
11:31:49 8433 8192000000 9956093872 10337280000 1000864240 10.887 98 37 75 3 1045924112 37138384 7921488
11:32:57 41138 8192000000 9928235808 10322903040 959910656 10.488 93 24 49 0 985007984 19848320 5249008
11:34:39 7497 8192000000 9961004560 10348302336 1043620656 11.299 100 46 92 2 1070573248 16458400 10494192
11:35:49 6162 8192000000 9966983104 10367221760 1039774864 11.263 99 28 54 2 1068124704 21885664 6464176
11:36:57 48163 8192000000 9989801440 10380562432 1066414240 11.518 100 40 77 1 1087484496 12710736 8359520
11:38:57 14755 8192000000 10052290192 10452475904 1098599984 11.824 100 58 115 1 1158088256 48204368 11283904
11:40:29 14470 8192000000 10073480736 10478563328 1113620336 11.967 100 44 87 0 1181681520 58592000 9469184
11:41:56 14891 8192000000 10115845984 10517200896 1130495632 12.126 100 54 105 1 1220072528 77858448 11718448
11:43:16 9665 8192000000 10157764352 10570366976 1141048096 12.225 100 52 103 1 1257449856 105554272 10847488
11:44:30 21432 8192000000 10167188848 10603372544 1147393040 12.285 100 37 74 0 1278717152 122852096 8472016
11:46:04 10981 8192000000 10232571392 10648248320 1161411552 12.416 100 47 91 2 1332653520 160227664 11014304
11:47:18 5214 8192000000 10288902144 10701426688 1162899392 12.430 99 67 134 1 1386750272 208736848 15114032
11:48:24 10065 8192000000 10328275360 10764505088 1178774768 12.579 100 67 134 2 1442789952 248550080 15465104
11:49:41 4653 8192000000 10353278160 10778697728 1192110576 12.703 100 72 143 1 1455102512 246634688 16357248
11:50:49 3188 8192000000 10383856592 10797821952 1179122944 12.582 99 75 151 1 1478985184 282310048 17552192
11:52:00 4127 8192000000 10395088800 10822541312 1189776624 12.681 100 70 137 0 1492637584 287623744 15237216
11:53:14 6992 8192000000 10436156128 10849910784 1194401312 12.724 100 73 144 1 1532115568 320833056 16881200
11:54:25 937 8192000000 10451246992 10861858816 1191723104 12.699 100 90 175 1 1546085952 333433424 20929424
11:55:32 2077 8192000000 10510988048 10930225152 1192753712 12.709 100 74 146 0 1611866080 400809728 18302640
11:56:41 3296 8192000000 10526149120 10945871872 1185536448 12.642 100 92 184 0 1616784832 410100080 21148304
11:57:51 3373 8192000000 10484439008 10918719488 1192808224 12.709 100 83 165 0 1586412992 374436432 19168336
11:59:00 12292 8192000000 10462787744 10904989696 1168518496 12.483 99 38 75 1 1557550832 378733168 10299168
12:00:16 22233 8192000000 10492577792 10925314048 1148476768 12.295 99 43 86 1 1583685104 423743776 11464560
12:01:41 5964 8192000000 10494079200 10930540544 1157628976 12.381 100 41 83 0 1594711040 426157088 10924976
12:02:48 25133 8192000000 10325829024 10887532544 1129258304 12.114 99 31 62 0 1435781264 298199840 8323120
12:04:20 22527 8192000000 10214194032 10739630080 1124890288 12.073 99 13 26 0 1360540064 232318720 3331056
12:06:01 21877 8192000000 10044028256 10458955776 1069421008 11.547 96 56 107 2 1097846960 15372656 13053296
12:07:29 71931 8192000000 10025997616 10458021888 1055971616 11.418 95 43 85 0 1081927792 17563936 8392240
12:09:49 58572 8192000000 10051939472 10503704576 1099457120 11.832 99 31 62 1 1152324288 46297520 6569648
12:11:59 36635 8192000000 10041683024 10460823552 1095532240 11.795 98 35 69 1 1129103200 26920192 6650768
12:13:38 29280 8192000000 10074130464 10498961408 1116208144 11.991 100 51 100 4 1185564560 58443712 10912704
12:15:27 80060 8192000000 10076216480 10509053952 1118464592 12.012 99 20 38 1 1186234800 62905616 4864592
12:18:08 14869 8192000000 10077991120 10525351936 1131226336 12.133 100 20 40 2 1182729456 46657424 4845696
12:19:24 50345 8192000000 10106539440 10557054976 1127636928 12.099 99 22 44 0 1197921728 65132000 5152800
12:21:15 87330 8192000000 10083047616 10533990400 1139614448 12.212 100 28 55 0 1234714672 88987904 6112320
12:23:44 48799 8192000000 10080804816 10585243648 1132151360 12.142 99 18 36 0 1200541888 64045520 4345008
12:25:35 84324 8192000000 10079978704 10590011392 1148404608 12.295 100 18 36 1 1220484208 67971872 4107728
12:28:02 51856 8192000000 10113168208 10566991872 1137567696 12.193 98 26 51 1 1195290992 51695440 6027856
12:29:54 56307 8192000000 10151220272 10585018368 1138136672 12.198 98 34 66 0 1240345776 95386928 6822176
12:31:51 69676 8192000000 10082260416 10543079424 1097631200 11.815 96 8 16 0 1130789936 31714992 1443744
12:34:02 60060 8192000000 10118166032 10592960512 1149246992 12.302 100 26 52 0 1215764192 61106512 5410688
12:36:05 40488 8192000000 10079518080 10519687168 1129196752 12.114 98 32 64 0 1158505216 22308928 6999536
12:37:47 86700 8192000000 10120044704 10602508288 1146833440 12.280 99 30 60 0 1244838352 91891536 6113376
12:40:30 20716 8192000000 10111409488 10555580416 1142774640 12.242 99 26 52 0 1213105840 65149888 5181312
12:41:58 75500 8192000000 10080221904 10530701312 1129798304 12.119 97 17 34 0 1164322944 31371824 3152816
12:44:14 54049 8192000000 10074990608 10545704960 1148651376 12.297 100 7 13 2 1178894192 28758208 1484608
12:46:09 36830 8192000000 10126786928 10569613312 1124713184 12.071 96 16 32 1 1176007488 47862640 3431664
12:47:46 29003 8192000000 10167310560 10598768640 1099110352 11.829 95 34 68 0 1207768224 101033904 7623968
12:49:25 78751 8192000000 10243395616 10781802496 1142313792 12.237 99 25 50 1 1346997648 198423488 6260368
12:51:52 103239 8192000000 10109235712 10615308288 1081031056 11.657 96 6 12 0 1177796800 95484480 1281264
12:54:35 63440 8192000000 10032466720 10493747200 1072899632 11.580 96 14 28 0 1111420352 35574640 2946080
12:56:45 18279 8192000000 10097558048 10513453056 1128200720 12.104 100 61 122 2 1180627152 40029600 12396832
12:58:04 137267 8192000000 10098747872 10550956032 1089366304 11.737 97 26 51 2 1177075744 82033072 5676368
13:01:39 31713 8192000000 10037784208 10473459712 1098314992 11.822 99 12 24 0 1125818416 25128576 2374848
13:03:26 55487 8192000000 10087585552 10536329216 1103423120 11.870 98 13 26 0 1164254224 58161968 2669136
13:05:43 55655 8192000000 10162632240 10613248000 1124531472 12.070 99 17 33 0 1229517856 101358144 3628240
13:07:49 77137 8192000000 10121744896 10561851392 1088459056 11.728 96 22 42 1 1186504832 93115664 4930112
13:10:16 36167 8192000000 10081612160 10525220864 1120756672 12.034 100 32 64 0 1160609408 33204256 6648480
13:11:56 54640 8192000000 10111442656 10565902336 1112399488 11.955 99 11 22 0 1197994240 83444608 2150144
13:13:52 63461 8192000000 10117787136 10594869248 1128504528 12.107 99 27 54 0 1193782752 60114096 5164128
13:15:57 41130 8192000000 10169400576 10662510592 1141739376 12.232 100 25 50 1 1257443600 110581376 5122848
13:17:41 97063 8192000000 10113156016 10629292032 1133293392 12.152 99 16 32 0 1180314016 44395552 2625072
13:20:18 67892 8192000000 10088530224 10533179392 1140739488 12.222 99 28 56 1 1174723616 27905680 6078448
13:24:24 90356 8192000000 10095983104 10560147456 1137600432 12.193 99 17 34 0 1184666592 43283232 3782928
13:27:58 37402 8192000000 10131563264 10586038272 1139352112 12.209 99 32 64 0 1231635872 85455120 6828640
13:29:47 86633 8192000000 10131442320 10566750208 1087774720 11.721 94 24 48 2 1153552080 59723264 6054096
13:32:30 49375 8192000000 10120898896 10631061504 1130258544 12.124 99 12 24 0 1220467056 87462304 2746208
13:34:32 59933 8192000000 10138009920 10644267008 1146708240 12.279 100 9 18 0 1235236192 86781312 1746640
13:36:40 35066 8192000000 8954507040 9138356224 117003984 1.408 11 1 2 0 117886080 833984 48112

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 01-10-2021 13:38:03 to 01-10-2021 13:38:18.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 118 v_add_invoice_to_stock 335544517
except 9 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 01-10-2021 13:38:18 to 01-10-2021 13:38: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          224971
MON$OLDEST_ACTIVE               224972
MON$OLDEST_SNAPSHOT             224972
MON$NEXT_TRANSACTION            224972
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                       2064992
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             776
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


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

01.10.21 13:38: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-10-01 13:38:24 No severe PSQL-related problems occured
01.10.21 13:38:25. End of report.