Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 07.11.2021 10:38
Common Performance Final Results
  1. Hardware and OS info
  2. Test configuration
  3. Test Finish details
  4. Test workload details
  5. Indices DDL for heavy-loaded table(s)
  1. Performance, TOTAL score:    as table     as chart
  2. Performance per MINUTE, during test_time phase:    as table     as chart
  3. Performance, DETAILS per units
  4. Memory consumption, metadata cache, attachments activity    as table    as chart
  5. Monitoring data: STATEMENTS activity, as chart
  6. Exceptions during test run
  1. mon$database and 'show version' results
  2. Database statistics was not gathered. Change config parameter 'run_db_statistics' to 1.
  3. Database validation was not performed. Change config parameter 'run_db_validation' to 1.
  4. New in firebird.log while test was run
  5. Final processing of ISQL logs

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 58e1ddde69c44dad93d327777e5becec
Operating System CentOS Linux 7 (Core)
CPE OS Name cpe:/o:centos:centos:7
Kernel Linux 3.10.0-1127.10.1.el7.x86_64
Architecture x86-64
Command: who -b
bootup_info system boot 2021-05-09 08:45
Command: dmidecode -t system|grep -i -e 'manufacturer\|product\|hypervisor'
Manufacturer HP
Product Name ProLiant DL380 Gen9
Command: dmesg | grep DMI
[ 0.000000] DMI HP ProLiant DL380 Gen9/ProLiant DL380 Gen9, BIOS P89 05/21/2018
Command: lscpu | grep -i -v flags
Architecture x86_64
CPU op-mode(s) 32-bit, 64-bit
Byte Order Little Endian
CPU(s) 24
On-line CPU(s) list 0-23
Thread(s) per core 2
Core(s) per socket 6
Socket(s) 2
NUMA node(s) 2
Vendor ID GenuineIntel
CPU family 6
Model 63
Model name Intel(R) Xeon(R) CPU E5-2620 v3 @ 2.40GHz
Stepping 2
CPU MHz 1200.000
CPU max MHz 2400.0000
CPU min MHz 1200.0000
BogoMIPS 4793.92
Virtualization VT-x
L1d cache 32K
L1i cache 32K
L2 cache 256K
L3 cache 15360K
NUMA node0 CPU(s) 0-5,12-17
NUMA node1 CPU(s) 6-11,18-23
Command: cat /proc/meminfo | grep -i -e 'memtotal\|memfree\|memavail\|buffers\|cached\|swapcached'
MemTotal 32643792 kB
MemFree 15807620 kB
MemAvailable 30794976 kB
Buffers 0 kB
Cached 15882968 kB
SwapCached 2444 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-11-07 10:37:07
DTS_END                         2021-11-07 10:37:07.7290
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_RESERVE_WRITE_OFF(34:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/48904
TRN_ID                          236945
ATT_ID                          650
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: 8986.86 22852 1078423
customer order: creation 178.26 432 21391
customer order: refuse 15.10 328 1812
order to supplier: creation 46.68 1476 5601
order to supplier: removal 3.34 116371 401
invoice (draft): creation 47.41 3729 5689
invoice (draft): removal 49.91 1134 5989
invoice accept: apply 42.95 16902 5154
invoice accept: cancel 44.56 1037 5347
customer reserve: creation 3928.33 166 471399
customer reserve: removal 4329.48 69 519538
realization accept: apply 86.69 203 10403
realization accept: cancel 79.84 135 9581
payment from customer: creation 61.30 118 7356
payment from customer: removal 6.33 88 759
payment to supplier: creation 51.25 117 6150
payment to supplier: removal 4.13 72 495
service: total inventory turnovers 3.98 3517 478
service: total monetary turnovers 6.47 498 776
service: aggregate perf. data 0.83 17475 100
service: refresh index statistics 0.03 293175 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 07-11-2021 10:38:24 to 07-11-2021 10:38:26.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4041
TEST_TIME 2 5542
TEST_TIME 3 5431
TEST_TIME 4 5275
TEST_TIME 5 5416
TEST_TIME 6 5048
TEST_TIME 7 4930
TEST_TIME 8 5204
TEST_TIME 9 5838
TEST_TIME 10 6129
TEST_TIME 11 6544
TEST_TIME 12 6713
TEST_TIME 13 7130
TEST_TIME 14 7368
TEST_TIME 15 7314
TEST_TIME 16 7406
TEST_TIME 17 7824
TEST_TIME 18 7837
TEST_TIME 19 8265
TEST_TIME 20 8389
TEST_TIME 21 8738
TEST_TIME 22 8746
TEST_TIME 23 8626
TEST_TIME 24 8680
TEST_TIME 25 8926
TEST_TIME 26 10024
TEST_TIME 27 11305
TEST_TIME 28 11026
TEST_TIME 29 10815
TEST_TIME 30 11127
TEST_TIME 31 10968
TEST_TIME 32 10822
TEST_TIME 33 10718
TEST_TIME 34 10553
TEST_TIME 35 10482
TEST_TIME 36 10690
TEST_TIME 37 10702
TEST_TIME 38 10681
TEST_TIME 39 10568
TEST_TIME 40 10585
TEST_TIME 41 10512
TEST_TIME 42 10454
TEST_TIME 43 10351
TEST_TIME 44 10335
TEST_TIME 45 10342
TEST_TIME 46 10120
TEST_TIME 47 10150
TEST_TIME 48 10054
TEST_TIME 49 9976
TEST_TIME 50 9923
TEST_TIME 51 9844
TEST_TIME 52 9876
TEST_TIME 53 9978
TEST_TIME 54 9907
TEST_TIME 55 9851
TEST_TIME 56 9776
TEST_TIME 57 9666
TEST_TIME 58 9601
TEST_TIME 59 9595
TEST_TIME 60 9535
TEST_TIME 61 9490
TEST_TIME 62 9496
TEST_TIME 63 9496
TEST_TIME 64 9485
TEST_TIME 65 9490
TEST_TIME 66 9452
TEST_TIME 67 9552
TEST_TIME 68 9543
TEST_TIME 69 9590
TEST_TIME 70 9623
TEST_TIME 71 9652
TEST_TIME 72 9657
TEST_TIME 73 9702
TEST_TIME 74 9693
TEST_TIME 75 9600
TEST_TIME 76 9518
TEST_TIME 77 9446
TEST_TIME 78 9442
TEST_TIME 79 9375
TEST_TIME 80 9352
TEST_TIME 81 9318
TEST_TIME 82 9297
TEST_TIME 83 9271
TEST_TIME 84 9258
TEST_TIME 85 9280
TEST_TIME 86 9291
TEST_TIME 87 9321
TEST_TIME 88 9370
TEST_TIME 89 9356
TEST_TIME 90 9380
TEST_TIME 91 9452
TEST_TIME 92 9438
TEST_TIME 93 9352
TEST_TIME 94 9313
TEST_TIME 95 9284
TEST_TIME 96 9231
TEST_TIME 97 9212
TEST_TIME 98 9162
TEST_TIME 99 9147
TEST_TIME 100 9201
TEST_TIME 101 9196
TEST_TIME 102 9183
TEST_TIME 103 9172
TEST_TIME 104 9189
TEST_TIME 105 9263
TEST_TIME 106 9245
TEST_TIME 107 9276
TEST_TIME 108 9267
TEST_TIME 109 9291
TEST_TIME 110 9286
TEST_TIME 111 9234
TEST_TIME 112 9198
TEST_TIME 113 9173
TEST_TIME 114 9134
TEST_TIME 115 9093
TEST_TIME 116 9064
TEST_TIME 117 9058
TEST_TIME 118 9028
TEST_TIME 119 9030
TEST_TIME 120 8996

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 07-11-2021 10:38:28 to 07-11-2021 10:38:31.

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 1513552 1513552 0 0.00 0 19570 6 0 0
doc_list_biud 1513552 1513552 0 0.00 0 47542 31 0 0
sp_add_invoice_to_stock 5254 5154 100 1.90 37 193963 16902 0 100
sp_cancel_adding_invoice 5347 5347 0 0.00 5 77181 1038 0 0
sp_cancel_client_order 1812 1812 0 0.00 4 13777 328 0 0
sp_cancel_customer_reserve 519538 519538 0 0.00 1 25021 70 0 0
sp_cancel_pay_from_customer 759 759 0 0.00 3 9638 89 0 0
sp_cancel_pay_to_supplier 495 495 0 0.00 3 2461 72 0 0
sp_cancel_supplier_invoice 6000 5989 11 0.18 2 20390 1134 0 11
sp_cancel_supplier_order 401 401 0 0.00 18 1252862 116372 0 0
sp_cancel_write_off 9581 9581 0 0.00 1 16801 135 0 0
sp_client_order 21391 21391 0 0.00 4 26311 432 0 0
sp_customer_reserve 471399 471399 0 0.00 0 77346 167 0 0
sp_fill_shopping_cart 32680 32680 0 0.00 1 21700 45 0 0
sp_fill_shopping_cart_clo_res 471389 471389 0 0.00 0 16892 16 0 0
sp_get_clo_for_invoice 5140 5140 0 0.00 0 38060 369 0 0
sp_kill_cost_storno 16182 16182 0 0.00 0 7249 67 0 0
sp_kill_qstorno_ret_qs2qd 1055047 1055047 0 0.00 0 25016 39 0 0
sp_kill_qty_storno 558209 558209 0 0.00 0 47540 84 0 0
sp_lock_selected_doc 21677 21677 0 0.00 0 12715 5 0 0
sp_make_cost_storno 29047 29047 0 0.00 0 16414 51 0 0
sp_multiply_rows_for_pdistr 29047 29047 0 0.00 0 8406 30 0 0
sp_multiply_rows_for_qdistr 21391 21391 0 0.00 0 10901 203 0 0
sp_pay_from_customer 7356 7356 0 0.00 4 21275 119 0 0
sp_pay_to_supplier 6150 6150 0 0.00 5 22391 118 0 0
sp_payment_common 13506 13506 0 0.00 1 6320 17 0 0
sp_qd_handle_on_cancel_clo 1812 1812 0 0.00 0 13771 292 0 0
sp_qd_handle_on_invoice_upd_sts 10485 10485 0 0.00 0 47540 397 0 0
sp_qd_handle_on_reserve_upd_sts 10403 10403 0 0.00 0 19522 69 0 0
sp_reserve_write_off 10403 10403 0 0.00 7 23307 203 0 0
sp_supplier_invoice 5689 5689 0 0.00 7 68620 3729 0 0
sp_supplier_order 5601 5601 0 0.00 6 45942 1476 0 0
srv_aggregate_perf_data 100 100 0 0.00 2546 296330 17476 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1581 130251 44267 0 0
srv_find_qd_qs_mism 1028506 1028506 0 0.00 0 19569 5 0 0
srv_make_invnt_saldo 478 478 0 0.00 63 100386 3517 0 0
srv_make_money_saldo 776 776 0 0.00 1 14825 499 0 0
srv_recalc_idx_stat 4 4 0 0.00 182655 375318 293175 0 0
t$perf-abend:sp_add_invoice_to_stock 100 100 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5156 5156 0 0.00 0 10933 168 0 0
t$perf-norm:sp_cancel_adding_invoice 459 459 0 0.00 0 4852 102 0 0
t$perf-norm:sp_cancel_client_order 1812 1812 0 0.00 0 8724 23 0 0
t$perf-norm:sp_cancel_customer_reserve 745 745 0 0.00 0 3909 17 0 0
t$perf-norm:sp_cancel_pay_from_customer 759 759 0 0.00 0 4292 17 0 0
t$perf-norm:sp_cancel_pay_to_supplier 495 495 0 0.00 0 955 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 378 378 0 0.00 0 2842 18 0 0
t$perf-norm:sp_cancel_supplier_order 401 401 0 0.00 0 19101 1522 0 0
t$perf-norm:sp_cancel_write_off 1088 1088 0 0.00 0 842 4 0 0
t$perf-norm:sp_client_order 21391 21391 0 0.00 0 10084 20 0 0
t$perf-norm:sp_customer_reserve 1014 1014 0 0.00 0 3148 6 0 0
t$perf-norm:sp_pay_from_customer 7356 7356 0 0.00 0 7510 10 0 0
t$perf-norm:sp_pay_to_supplier 6150 6150 0 0.00 0 4808 12 0 0
t$perf-norm:sp_reserve_write_off 10404 10404 0 0.00 0 9064 14 0 0
t$perf-norm:sp_supplier_invoice 5689 5689 0 0.00 0 6040 21 0 0
t$perf-norm:sp_supplier_order 5601 5601 0 0.00 0 3687 17 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 101 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 105 5 0 0
t$perf-norm:srv_make_invnt_saldo 478 478 0 0.00 0 1790 15 0 0
t$perf-norm:srv_make_money_saldo 776 776 0 0.00 0 3389 18 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 4 0 0
v_add_invoice_to_stock 10476 10276 200 1.90 0 20063 24 0 200
v_all_customers 38828 38828 0 0.00 0 118 1 0 0
v_all_suppliers 31636 31636 0 0.00 0 26 1 0 0
v_all_wares 42782 42782 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 918 918 0 0.00 0 519 7 0 0
v_cancel_client_order 3624 3624 0 0.00 0 6719 10 0 0
v_cancel_customer_prepayment 1518 1518 0 0.00 0 2574 8 0 0
v_cancel_customer_reserve 1490 1490 0 0.00 0 18548 16 0 0
v_cancel_payment_to_supplier 990 990 0 0.00 0 2452 10 0 0
v_cancel_supplier_invoice 778 756 22 2.82 0 2611 14 0 22
v_cancel_supplier_order 802 802 0 0.00 0 2626 7 0 0
v_cancel_write_off 2176 2176 0 0.00 0 16141 38 0 0
v_max_id_clo_ord 5601 5601 0 0.00 0 4560 2 0 0
v_max_id_clo_res 1013 1013 0 0.00 0 2677 21 0 0
v_max_id_ord_sup 5688 5688 0 0.00 0 3565 4 0 0
v_max_non_paid_invoice 1621 1621 0 0.00 0 219 2 0 0
v_max_non_paid_realizn 3917 3917 0 0.00 0 914 3 0 0
v_min_id_clo_ord 5601 5601 0 0.00 0 2668 5 0 0
v_min_id_clo_res 1013 1013 0 0.00 0 1825 4 0 0
v_min_id_ord_sup 5688 5688 0 0.00 0 5755 6 0 0
v_min_non_paid_invoice 6150 6150 0 0.00 0 4754 10 0 0
v_min_non_paid_realizn 7356 7356 0 0.00 0 6317 12 0 0
v_our_firm 10832 10832 0 0.00 0 14 1 0 0
v_reserve_write_off 20804 20804 0 0.00 0 17759 12 0 0
x_lock_depdocs_on_canc_invoice 10958 10958 0 0.00 0 10376 117 0 0
x_lock_depdocs_on_canc_sup_ord 401 401 0 0.00 0 3252 299 0 0
x_make_qstorno_1000_1200 5601 5601 0 0.00 2 45829 1396 0 0
x_make_qstorno_1200_2000 5689 5689 0 0.00 3 68224 3557 0 0
x_make_qstorno_2100_3300 471399 471399 0 0.00 0 77344 148 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 07-11-2021 10:38:32 to 07-11-2021 10:38:36.

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:11 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:12 26495 8192000000 9723319024 10020700160 795723296 8.853 100 85 170 1 823770272 7597232 20449744
08:09:46 23901 8192000000 9771817136 10079551488 845473824 9.355 100 90 180 1 880180096 13174000 21532272
08:11:16 16767 8192000000 9811478000 10135343104 877451696 9.674 100 85 168 2 918664800 21929264 19283840
08:12:39 17725 8192000000 9880664896 10218401792 924831952 10.144 100 75 151 1 987245936 46233520 16180464
08:13:59 8581 8192000000 9921100368 10269302784 967958512 10.567 100 79 158 0 1034402864 49978368 16465984
08:15:11 4788 8192000000 9974520224 10331045888 1000650672 10.885 100 79 158 0 1080630464 64092608 15887184
08:16:17 3241 8192000000 9997633024 10362736640 1022827360 11.099 100 83 165 1 1112006336 72795664 16383312
08:17:22 1624 8192000000 10016817488 10384203776 1054255040 11.401 100 90 180 1 1130872272 58157904 18459328
08:18:26 1749 8192000000 10052859296 10423775232 1064311376 11.498 100 91 181 1 1163422160 80891504 18219280
08:19:28 21599 8192000000 10040305968 10417352704 1075316064 11.603 100 67 135 1 1149029088 60296160 13416864
08:20:52 6393 8192000000 9989459360 10393980928 1079165888 11.640 100 37 73 1 1107018960 19250544 8602528
08:22:00 5743 8192000000 10009114768 10414366720 1082756976 11.674 100 26 50 1 1129399712 41535104 5107632
08:23:07 35686 8192000000 10013325040 10397720576 1087315696 11.717 100 70 140 1 1117713312 14143504 16254112
08:24:43 3911 8192000000 10011896848 10405081088 1088121968 11.725 100 43 86 0 1120301824 21499248 10680608
08:25:47 8145 8192000000 10011431360 10427592704 1094865120 11.789 100 28 56 0 1129590480 29151776 5573584
08:26:57 6896 8192000000 10008672960 10410606592 1097669136 11.816 100 32 63 2 1119717216 14461680 7586400
08:28:05 15626 8192000000 10017257360 10419572736 1080264880 11.650 99 60 122 0 1130984288 37226192 13493216
08:29:24 7540 8192000000 9968385168 10361577472 1009072272 10.966 95 51 102 4 1045915024 25027280 11815472
08:30:37 8626 8192000000 9923560688 10291494912 974630656 10.632 94 42 83 2 1019974544 34660912 10682976
08:31:46 55692 8192000000 9933679152 10301972480 996793184 10.847 97 42 84 1 1027610240 20193168 10623888
08:33:43 10817 8192000000 9941325776 10337812480 1036319264 11.229 100 29 58 0 1064433696 22118960 5995472
08:34:59 8130 8192000000 9971343968 10361802752 1044969200 11.312 100 44 84 1 1077516272 23506976 9040096
08:36:08 61260 8192000000 9978042976 10381697024 1056208016 11.420 100 29 57 0 1075581328 13167072 6206240
08:38:21 7957 8192000000 10016617984 10404810752 1083753760 11.683 100 63 125 1 1120836976 24320000 12763216
08:39:33 6368 8192000000 10092639664 10484260864 1108223120 11.916 100 56 112 0 1193192384 72679616 12289648
08:40:42 24592 8192000000 10097861696 10501971968 1123690464 12.062 100 42 83 1 1213352736 80361424 9300848
08:42:10 23895 8192000000 10138927920 10559262720 1133809040 12.157 100 53 103 0 1254972768 110264016 10899712
08:43:38 30162 8192000000 10198304192 10602479616 1138241664 12.199 100 58 115 0 1289259952 138294112 12724176
08:45:16 12247 8192000000 10226860352 10631921664 1151837648 12.327 100 62 124 0 1331078240 164786736 14453856
08:46:33 12361 8192000000 10266674336 10672652288 1169917920 12.496 100 65 124 1 1365550880 181495088 14137872
08:47:52 1578 8192000000 10318282192 10721517568 1184408800 12.631 100 86 173 0 1418850272 214537888 19903584
08:49:00 4493 8192000000 10331808736 10743648256 1182793248 12.616 100 71 140 0 1433523728 235300288 15430192
08:50:08 3094 8192000000 10385189680 10800246784 1198326976 12.761 100 85 171 3 1485639008 267443296 19868736
08:51:15 3119 8192000000 10358212944 10789371904 1185078784 12.638 99 74 145 0 1472245280 270172400 16994096
08:52:23 3263 8192000000 10391414816 10807259136 1188393216 12.668 100 69 137 0 1493717360 289472016 15852128
08:53:31 3848 8192000000 10401974640 10817675264 1186349488 12.649 100 79 158 0 1503937744 299321552 18266704
08:54:41 3545 8192000000 10380106288 10801025024 1158709040 12.391 99 83 165 0 1471776976 293030784 20037152
08:55:48 1596 8192000000 10454520384 10852495360 1172287952 12.518 100 91 182 1 1546466304 352695104 21483248
08:56:52 15799 8192000000 10428487216 10868985856 1170434112 12.501 99 45 90 0 1549111648 366805744 11871792
08:58:15 14843 8192000000 10409811648 10853871616 1142979808 12.244 99 46 91 0 1507961376 353329808 11651760
08:59:44 21057 8192000000 10492556752 10932756480 1172329120 12.519 100 32 64 0 1574625280 393893568 8402592
09:01:20 19872 8192000000 10490379680 10974105600 1149116464 12.301 100 29 58 1 1586958768 429747840 8094464
09:02:52 33843 8192000000 10383577264 10867765248 1115760096 11.987 98 29 58 0 1542871344 418528928 8582320
09:04:59 65003 8192000000 10096867088 10537623552 1128404576 12.106 100 16 32 0 1181224688 49337120 3482992
09:07:53 87134 8192000000 10049044752 10509430784 1076067344 11.610 95 5 10 0 1109877440 33128720 681376
09:10:20 78767 8192000000 10094529552 10528604160 1075638912 11.606 96 29 58 0 1131937632 50316080 5982640
09:12:43 99675 8192000000 10089285536 10554040320 1122053872 12.046 100 16 32 4 1207330800 81828576 3448352
09:15:27 45832 8192000000 10073362896 10519240704 1107431984 11.908 98 15 29 1 1143734512 32958752 3343776
09:17:14 44128 8192000000 10053074640 10506952704 1098342640 11.822 97 17 34 1 1144809424 42904768 3562016
09:19:03 31715 8192000000 10058601296 10494976000 1111019792 11.942 98 25 48 2 1142109168 26207760 4881616
09:20:43 30593 8192000000 10113617504 10543489024 1117208832 12.001 98 23 45 1 1173372976 50893104 5271040
09:22:15 130250 8192000000 10087266240 10560557056 1137275904 12.190 100 30 59 3 1225640224 81275088 7089232
09:25:32 50612 8192000000 10100327024 10517139456 1135196336 12.170 99 22 44 0 1164905424 24898768 4810320
09:27:24 55878 8192000000 10131249152 10569064448 1117091056 12.000 97 57 114 0 1202725344 73822896 11811392
09:29:35 41048 8192000000 10112090800 10614198272 1146331616 12.275 100 14 28 0 1240946336 91403520 3211200
09:31:56 54132 8192000000 10080849712 10535596032 1135928640 12.177 99 17 35 0 1180734576 41218736 3587200
09:34:37 74022 8192000000 10110116592 10559725568 1150391328 12.313 100 28 56 0 1220130880 63673472 6066080
09:36:59 74466 8192000000 10064099392 10552004608 1111971520 11.951 97 10 20 0 1157415584 42701680 2742384
09:39:15 41134 8192000000 10044380688 10494447616 1096529968 11.805 97 6 12 0 1125320528 27799040 991520
09:40:57 79968 8192000000 10099812880 10574446592 1121647664 12.043 99 12 24 0 1187647296 63426576 2573056
09:43:41 23480 8192000000 10125643552 10572963840 1112713264 11.958 98 28 56 0 1220695168 101434960 6546944
09:45:09 51895 8192000000 10087110192 10549239808 1053192320 11.391 93 14 28 0 1146230000 89531680 3506000
09:47:24 28321 8192000000 10072204384 10489458688 1115978688 11.989 100 37 72 0 1154952464 31694608 7279168
09:48:53 35549 8192000000 10098386064 10538553344 1083687920 11.683 96 33 66 3 1139070368 48762480 6619968
09:50:40 46261 8192000000 10075272384 10504253440 1086769040 11.712 96 31 62 0 1124381552 30742976 6869536
09:52:31 116308 8192000000 10056838512 10481983488 1104603536 11.881 98 40 78 0 1154753280 41901024 8248720
09:55:28 44852 8192000000 10035191376 10500239360 1101135472 11.848 98 10 20 0 1135051280 31775456 2140352
09:57:13 33562 8192000000 10035772640 10492338176 1120427456 12.031 100 11 20 2 1147935568 25474976 2033136
09:58:46 57314 8192000000 10072616992 10519801856 1094225824 11.783 97 17 34 1 1139390112 41404880 3759408
10:01:09 39309 8192000000 10090390128 10522935296 1082826032 11.674 97 26 52 1 1170837472 82819520 5191920
10:02:59 48108 8192000000 10085551920 10508247040 1073714288 11.588 96 19 38 0 1153861264 75834880 4312096
10:04:56 88185 8192000000 10094356672 10555047936 1101204640 11.849 98 19 38 0 1171748288 66824368 3719280
10:07:24 70235 8192000000 10122821072 10626695168 1120266768 12.030 99 13 24 1 1184692560 62257664 2168128
10:09:35 74127 8192000000 10078332144 10518081536 1140521616 12.220 100 42 81 0 1193187680 43494880 9171184
10:11:55 63479 8192000000 10063624176 10535673856 1143257840 12.246 100 5 10 0 1165413600 21234544 921216
10:14:03 62455 8192000000 10071773664 10530885632 1129389632 12.116 98 14 28 0 1152750272 20637248 2723392
10:16:23 26213 8192000000 10142708064 10593255424 1160489296 12.408 100 36 72 0 1249267328 81528592 7249440
10:17:52 81553 8192000000 10089361776 10535149568 1143065280 12.244 99 8 16 0 1179724576 35196720 1462576
10:20:25 52204 8192000000 10178041120 10622205952 1166813504 12.467 100 23 46 1 1266840720 95077328 4949888
10:22:22 94630 8192000000 10097902576 10547326976 1093031296 11.771 94 14 28 1 1130982624 35177200 2774128
10:25:03 37720 8192000000 10109834384 10568388608 1144281408 12.256 99 32 62 1 1210232112 59441280 6509424
10:26:46 73328 8192000000 10089130240 10595368960 1117395104 12.002 97 4 8 0 1176903552 58808192 700256
10:29:04 48595 8192000000 10051280304 10504957952 1103528656 11.871 97 15 30 0 1141419392 34943072 2947664
10:30:57 37783 8192000000 10041023792 10498129920 1108582368 11.919 98 10 19 0 1133608096 23210752 1814976
10:32:42 74107 8192000000 10066470512 10532175872 1098853728 11.827 97 7 14 0 1127180496 27079024 1247744
10:35:06 46840 8192000000 10104969408 10550939648 1120868928 12.035 99 23 43 2 1191628224 66478704 4280592
10:36:53 27651 8192000000 9203501760 9531142144 342591936 4.014 31 8 14 1 391875520 47468256 1815328

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 07-11-2021 10:38:38 to 07-11-2021 10:38:53.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 100 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 07-11-2021 10:38:54 to 07-11-2021 10:38:56.

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          237242
MON$OLDEST_ACTIVE               237243
MON$OLDEST_SNAPSHOT             237243
MON$NEXT_TRANSACTION            237243
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                       2058656
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.2653 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2653 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2653 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2653 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'

07.11.21 10:38:57. 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-11-07 10:38:59 No severe PSQL-related problems occured
07.11.21 10:39:00. End of report.