Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 14.06.2021 13:27
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 16555464 kB
MemAvailable 31383536 kB
Buffers 0 kB
Cached 15068528 kB
SwapCached 32 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-06-14 13:27:00
DTS_END                         2021-06-14 13:27:00.8640
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/47812
TRN_ID                          233477
ATT_ID                          613
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: 9069.52 25419 1088342
customer order: creation 166.68 389 20001
customer order: refuse 14.68 305 1762
order to supplier: creation 43.97 1422 5276
order to supplier: removal 3.36 118937 403
invoice (draft): creation 44.53 3596 5343
invoice (draft): removal 52.66 985 6319
invoice accept: apply 39.33 18601 4719
invoice accept: cancel 47.19 904 5663
customer reserve: creation 3695.08 178 443410
customer reserve: removal 4669.10 67 560292
realization accept: apply 81.07 173 9728
realization accept: cancel 86.13 115 10335
payment from customer: creation 58.37 106 7004
payment from customer: removal 5.43 95 652
payment to supplier: creation 47.96 124 5755
payment to supplier: removal 3.80 83 456
service: total inventory turnovers 3.76 3316 451
service: total monetary turnovers 5.58 604 670
service: aggregate perf. data 0.83 16499 99
service: refresh index statistics 0.03 341887 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 14-06-2021 13:28:15 to 14-06-2021 13:28:17.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 7316
TEST_TIME 2 6596
TEST_TIME 3 6783
TEST_TIME 4 6553
TEST_TIME 5 6755
TEST_TIME 6 6751
TEST_TIME 7 6482
TEST_TIME 8 6486
TEST_TIME 9 6489
TEST_TIME 10 6494
TEST_TIME 11 6598
TEST_TIME 12 6769
TEST_TIME 13 7488
TEST_TIME 14 7922
TEST_TIME 15 8402
TEST_TIME 16 8724
TEST_TIME 17 8709
TEST_TIME 18 8757
TEST_TIME 19 9041
TEST_TIME 20 8887
TEST_TIME 21 9059
TEST_TIME 22 9254
TEST_TIME 23 9441
TEST_TIME 24 9481
TEST_TIME 25 9640
TEST_TIME 26 10312
TEST_TIME 27 10428
TEST_TIME 28 11462
TEST_TIME 29 11215
TEST_TIME 30 10960
TEST_TIME 31 10902
TEST_TIME 32 10706
TEST_TIME 33 10617
TEST_TIME 34 10486
TEST_TIME 35 10357
TEST_TIME 36 10323
TEST_TIME 37 10337
TEST_TIME 38 10233
TEST_TIME 39 10102
TEST_TIME 40 10094
TEST_TIME 41 9997
TEST_TIME 42 10058
TEST_TIME 43 9981
TEST_TIME 44 10028
TEST_TIME 45 10001
TEST_TIME 46 9888
TEST_TIME 47 10090
TEST_TIME 48 9989
TEST_TIME 49 10012
TEST_TIME 50 10003
TEST_TIME 51 9965
TEST_TIME 52 10131
TEST_TIME 53 10095
TEST_TIME 54 10086
TEST_TIME 55 10060
TEST_TIME 56 9907
TEST_TIME 57 9874
TEST_TIME 58 9786
TEST_TIME 59 9681
TEST_TIME 60 9664
TEST_TIME 61 9596
TEST_TIME 62 9578
TEST_TIME 63 9516
TEST_TIME 64 9557
TEST_TIME 65 9660
TEST_TIME 66 9603
TEST_TIME 67 9576
TEST_TIME 68 9639
TEST_TIME 69 9602
TEST_TIME 70 9665
TEST_TIME 71 9684
TEST_TIME 72 9745
TEST_TIME 73 9689
TEST_TIME 74 9740
TEST_TIME 75 9755
TEST_TIME 76 9642
TEST_TIME 77 9630
TEST_TIME 78 9563
TEST_TIME 79 9474
TEST_TIME 80 9427
TEST_TIME 81 9429
TEST_TIME 82 9445
TEST_TIME 83 9383
TEST_TIME 84 9347
TEST_TIME 85 9362
TEST_TIME 86 9399
TEST_TIME 87 9465
TEST_TIME 88 9496
TEST_TIME 89 9486
TEST_TIME 90 9545
TEST_TIME 91 9601
TEST_TIME 92 9571
TEST_TIME 93 9491
TEST_TIME 94 9445
TEST_TIME 95 9455
TEST_TIME 96 9413
TEST_TIME 97 9367
TEST_TIME 98 9341
TEST_TIME 99 9315
TEST_TIME 100 9281
TEST_TIME 101 9266
TEST_TIME 102 9339
TEST_TIME 103 9301
TEST_TIME 104 9302
TEST_TIME 105 9293
TEST_TIME 106 9328
TEST_TIME 107 9369
TEST_TIME 108 9394
TEST_TIME 109 9391
TEST_TIME 110 9393
TEST_TIME 111 9323
TEST_TIME 112 9259
TEST_TIME 113 9267
TEST_TIME 114 9208
TEST_TIME 115 9219
TEST_TIME 116 9188
TEST_TIME 117 9143
TEST_TIME 118 9144
TEST_TIME 119 9151
TEST_TIME 120 9076

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 14-06-2021 13:28:19 to 14-06-2021 13:28:22.

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 1498968 1498968 0 0.00 0 18534 7 0 0
doc_list_biud 1498968 1498968 0 0.00 0 93085 32 0 0
sp_add_invoice_to_stock 4813 4719 94 1.95 36 214888 18601 0 94
sp_cancel_adding_invoice 5663 5663 0 0.00 6 92337 904 0 0
sp_cancel_client_order 1762 1762 0 0.00 4 6841 305 0 0
sp_cancel_customer_reserve 560292 560292 0 0.00 1 93086 68 0 0
sp_cancel_pay_from_customer 652 652 0 0.00 3 6442 96 0 0
sp_cancel_pay_to_supplier 456 456 0 0.00 3 8165 84 0 0
sp_cancel_supplier_invoice 6324 6319 5 0.07 3 67914 986 0 5
sp_cancel_supplier_order 403 403 0 0.00 9 943290 118937 0 0
sp_cancel_write_off 10335 10335 0 0.00 1 8939 115 0 0
sp_client_order 20001 20001 0 0.00 4 20638 390 0 0
sp_customer_reserve 443410 443410 0 0.00 0 58665 179 0 0
sp_fill_shopping_cart 30600 30600 0 0.00 1 14963 35 0 0
sp_fill_shopping_cart_clo_res 443395 443395 0 0.00 0 24537 17 0 0
sp_get_clo_for_invoice 4704 4704 0 0.00 0 59715 384 0 0
sp_kill_cost_storno 17106 17106 0 0.00 0 8935 54 0 0
sp_kill_qstorno_ret_qs2qd 1137641 1137641 0 0.00 0 93082 37 0 0
sp_kill_qty_storno 599206 599206 0 0.00 0 93085 80 0 0
sp_lock_selected_doc 20179 20179 0 0.00 0 7574 4 0 0
sp_make_cost_storno 27191 27191 0 0.00 0 11361 54 0 0
sp_multiply_rows_for_pdistr 27191 27191 0 0.00 0 8406 31 0 0
sp_multiply_rows_for_qdistr 20001 20001 0 0.00 0 9709 213 0 0
sp_pay_from_customer 7004 7004 0 0.00 5 11056 107 0 0
sp_pay_to_supplier 5755 5755 0 0.00 5 13550 125 0 0
sp_payment_common 12759 12759 0 0.00 1 6031 18 0 0
sp_qd_handle_on_cancel_clo 1762 1762 0 0.00 0 6640 274 0 0
sp_qd_handle_on_invoice_upd_sts 10367 10367 0 0.00 0 27190 390 0 0
sp_qd_handle_on_reserve_upd_sts 9728 9728 0 0.00 0 15909 54 0 0
sp_reserve_write_off 9728 9728 0 0.00 6 24742 173 0 0
sp_supplier_invoice 5343 5343 0 0.00 7 87551 3597 0 0
sp_supplier_order 5276 5276 0 0.00 6 26072 1423 0 0
srv_aggregate_perf_data 99 99 0 0.00 2647 318074 16500 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 2419 122254 46046 0 0
srv_find_qd_qs_mism 1042169 1042169 0 0.00 0 13249 6 0 0
srv_make_invnt_saldo 451 451 0 0.00 54 98466 3317 0 0
srv_make_money_saldo 670 670 0 0.00 1 14033 604 0 0
srv_recalc_idx_stat 4 4 0 0.00 157571 510883 341887 0 0
t$perf-abend:sp_add_invoice_to_stock 94 94 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 5 5 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4719 4719 0 0.00 0 8381 173 0 0
t$perf-norm:sp_cancel_adding_invoice 412 412 0 0.00 1 6407 94 0 0
t$perf-norm:sp_cancel_client_order 1762 1762 0 0.00 0 6242 19 0 0
t$perf-norm:sp_cancel_customer_reserve 658 658 0 0.00 0 1193 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 652 652 0 0.00 0 836 3 0 0
t$perf-norm:sp_cancel_pay_to_supplier 456 456 0 0.00 0 6451 18 0 0
t$perf-norm:sp_cancel_supplier_invoice 418 418 0 0.00 0 502 10 0 0
t$perf-norm:sp_cancel_supplier_order 403 403 0 0.00 0 34745 1535 0 0
t$perf-norm:sp_cancel_write_off 986 986 0 0.00 0 2354 8 0 0
t$perf-norm:sp_client_order 20001 20001 0 0.00 0 12863 18 0 0
t$perf-norm:sp_customer_reserve 979 979 0 0.00 0 3854 13 0 0
t$perf-norm:sp_pay_from_customer 7004 7004 0 0.00 0 4528 8 0 0
t$perf-norm:sp_pay_to_supplier 5755 5755 0 0.00 0 6977 13 0 0
t$perf-norm:sp_reserve_write_off 9728 9728 0 0.00 0 9267 13 0 0
t$perf-norm:sp_supplier_invoice 5343 5343 0 0.00 0 14861 22 0 0
t$perf-norm:sp_supplier_order 5276 5276 0 0.00 0 5279 15 0 0
t$perf-norm:srv_aggregate_perf_data 99 99 0 0.00 0 198 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 78 3 0 0
t$perf-norm:srv_make_invnt_saldo 451 451 0 0.00 0 2344 12 0 0
t$perf-norm:srv_make_money_saldo 670 670 0 0.00 0 985 7 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 3 2 0 0
v_add_invoice_to_stock 9596 9408 188 1.95 0 4355 18 0 188
v_all_customers 36336 36336 0 0.00 0 13 1 0 0
v_all_suppliers 29564 29564 0 0.00 0 25 1 0 0
v_all_wares 40002 40002 0 0.00 0 62 1 0 0
v_cancel_adding_invoice 824 824 0 0.00 0 2596 12 0 0
v_cancel_client_order 3524 3524 0 0.00 0 3559 7 0 0
v_cancel_customer_prepayment 1304 1304 0 0.00 0 2367 11 0 0
v_cancel_customer_reserve 1316 1316 0 0.00 0 3321 7 0 0
v_cancel_payment_to_supplier 912 912 0 0.00 0 423 3 0 0
v_cancel_supplier_invoice 846 836 10 1.18 0 3514 20 0 10
v_cancel_supplier_order 806 806 0 0.00 0 2117 6 0 0
v_cancel_write_off 1972 1972 0 0.00 0 2366 15 0 0
v_max_id_clo_ord 5276 5276 0 0.00 0 387 1 0 0
v_max_id_clo_res 979 979 0 0.00 0 5149 22 0 0
v_max_id_ord_sup 5323 5323 0 0.00 0 5423 5 0 0
v_max_non_paid_invoice 1572 1572 0 0.00 0 746 2 0 0
v_max_non_paid_realizn 3864 3864 0 0.00 0 2325 3 0 0
v_min_id_clo_ord 5276 5276 0 0.00 0 3311 5 0 0
v_min_id_clo_res 979 979 0 0.00 0 8498 23 0 0
v_min_id_ord_sup 5323 5323 0 0.00 0 7735 7 0 0
v_min_non_paid_invoice 5755 5755 0 0.00 0 5510 14 0 0
v_min_non_paid_realizn 7004 7004 0 0.00 0 6028 12 0 0
v_our_firm 9946 9946 0 0.00 0 15 1 0 0
v_reserve_write_off 19456 19456 0 0.00 0 3560 5 0 0
x_lock_depdocs_on_canc_invoice 11564 11564 0 0.00 0 26874 102 0 0
x_lock_depdocs_on_canc_sup_ord 403 403 0 0.00 0 38972 464 0 0
x_make_qstorno_1000_1200 5276 5276 0 0.00 2 26015 1353 0 0
x_make_qstorno_1200_2000 5343 5343 0 0.00 3 87529 3467 0 0
x_make_qstorno_2100_3300 443410 443410 0 0.00 0 58662 159 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 14-06-2021 13:28:23 to 14-06-2021 13:28:27.

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
10:57:04 6 8192000000 8796668864 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
10:58:05 35428 8192000000 9741503264 10042089472 811439760 9.012 100 89 178 1 840381504 7935760 21005984
10:59:43 22092 8192000000 9779579008 10090950656 855579824 9.456 100 93 186 1 891480816 13725824 22175168
11:01:07 15730 8192000000 9821106864 10140061696 877071408 9.671 100 86 172 3 921208096 24608944 19527744
11:02:34 11637 8192000000 9866461360 10203574272 919223184 10.088 100 73 146 0 976983984 42010352 15750448
11:03:48 11862 8192000000 9940662464 10288054272 959213712 10.481 100 82 162 0 1037769744 61454704 17101328
11:05:01 4998 8192000000 9960603680 10315685888 996830944 10.848 100 79 157 2 1081989968 69492528 15666496
11:06:09 2968 8192000000 10024298544 10390855680 1024952560 11.120 100 81 161 1 1131228096 90056912 16218624
11:07:14 1935 8192000000 10020866736 10394644480 1051905264 11.379 100 83 164 1 1142016192 73913760 16197168
11:08:19 2070 8192000000 10041569312 10413993984 1061605088 11.472 100 80 159 0 1154204992 76898032 15701872
11:09:22 1027 8192000000 10039368704 10412675072 1069926640 11.551 100 87 179 1 1152052208 64029360 18096208
11:10:24 3701 8192000000 10019365792 10402029568 1074318736 11.593 100 63 125 0 1135711664 48406736 12986192
11:11:29 8766 8192000000 9987236400 10385932288 1077771488 11.626 100 37 72 2 1120101296 34925456 7404352
11:12:41 7041 8192000000 10009622736 10408378368 1085485392 11.700 100 33 64 1 1135869568 43617136 6767040
11:13:49 4432 8192000000 10023572304 10411077632 1089500112 11.738 100 78 156 2 1135246176 27863920 17882144
11:14:54 4032 8192000000 10026871280 10424602624 1096682352 11.806 100 47 94 0 1127632496 21229296 9720848
11:16:00 4104 8192000000 9989758624 10377764864 1074694224 11.597 98 34 66 1 1101006400 18348848 7963328
11:17:05 4218 8192000000 9991084848 10370404352 1072908480 11.580 98 47 94 0 1092076176 9306368 9861328
11:18:12 11922 8192000000 9977982368 10373648384 1039355968 11.258 96 28 56 2 1054657456 9046480 6255008
11:19:25 5230 8192000000 9946675488 10332884992 1020007376 11.072 98 44 89 0 1055650528 26100448 9542704
11:20:32 7573 8192000000 9973389328 10363834368 1029755936 11.166 100 44 86 4 1059438432 20749008 8933488
11:21:41 65975 8192000000 9949204912 10344775680 1025094992 11.121 98 36 71 0 1051343344 18438912 7809440
11:23:50 4333 8192000000 9965518512 10355015680 1055377632 11.412 100 41 85 1 1076689760 12253392 9058736
11:24:56 6894 8192000000 9986205312 10388271104 1064317184 11.498 100 41 82 1 1091890704 18703968 8869552
11:26:03 8270 8192000000 9994939088 10402566144 1073944768 11.590 100 41 82 2 1109499968 25974336 9580864
11:27:15 31797 8192000000 10006591904 10404347904 1085895744 11.704 100 44 88 0 1116704912 20795728 10013440
11:28:50 15588 8192000000 10076015088 10475798528 1113531024 11.966 100 56 111 1 1184086864 59710576 10845264
11:30:26 19875 8192000000 10113198416 10530476032 1132205280 12.142 100 44 88 1 1234700224 93248000 9246944
11:31:52 6975 8192000000 10148806432 10560757760 1146137584 12.273 100 50 97 3 1250665360 94188000 10339776
11:33:03 9823 8192000000 10178563264 10602725376 1157014912 12.375 100 58 113 0 1286615568 116765792 12834864
11:34:18 17356 8192000000 10215838560 10639867904 1162483456 12.427 100 47 92 0 1319336128 146505504 10347168
11:35:43 17565 8192000000 10218558800 10643070976 1163621648 12.437 100 41 82 2 1325280304 152434848 9223808
11:37:03 6840 8192000000 10255722944 10667323392 1159544736 12.399 99 66 132 1 1363109920 188243968 15321216
11:38:20 8208 8192000000 10304271168 10717356032 1167259024 12.471 100 78 153 2 1408875072 223103264 18512784
11:39:34 8069 8192000000 10319157936 10747228160 1152982160 12.337 99 65 129 0 1405089168 236428832 15678176
11:40:44 2417 8192000000 10310197952 10716180480 1153809856 12.345 99 93 183 2 1402572304 225559360 23203088
11:41:51 3764 8192000000 10297461328 10741342208 1164636048 12.447 100 76 149 0 1401607776 219582656 17389072
11:43:05 4399 8192000000 10264762112 10667958272 1148976016 12.300 100 78 157 1 1364993984 198456576 17561392
11:44:12 15840 8192000000 10251143312 10676043776 1145972528 12.272 100 36 72 2 1353355024 198646944 8735552
11:45:37 6718 8192000000 10268739136 10695491584 1139223968 12.208 100 53 106 1 1366956608 214705968 13026672
11:46:46 11048 8192000000 10249684624 10700722176 1108609984 11.919 98 29 58 0 1333325392 218015552 6699856
11:48:01 4024 8192000000 10339854896 10751401984 1144178992 12.255 100 62 124 2 1439873280 280624800 15069488
11:49:11 14733 8192000000 10331296112 10791739392 1141781040 12.232 99 36 72 1 1451445440 299896176 9768224
11:50:32 15755 8192000000 10330535504 10757558272 1143615792 12.250 99 29 58 1 1420996256 270007472 7372992
11:51:50 6942 8192000000 10345535840 10777128960 1128950192 12.111 99 37 74 1 1440088624 301342512 9795920
11:53:06 10458 8192000000 10327726272 10798010368 1129362832 12.115 100 25 50 0 1439034048 303141040 6530176
11:54:36 41039 8192000000 10201911024 10665422848 1113316416 11.964 100 17 34 2 1316888656 198990400 4581840
11:56:17 43624 8192000000 10062931152 10486456320 1097604272 11.815 98 22 43 1 1139649024 37897872 4146880
11:58:07 114704 8192000000 10090913360 10535313408 1129443536 12.116 100 27 54 3 1212939232 78050688 5445008
12:01:15 13798 8192000000 10090188544 10522988544 1128064784 12.103 99 40 78 0 1177385776 41612384 7708608
12:02:33 64233 8192000000 10085414400 10519904256 1111572592 11.947 98 41 81 2 1176911136 56739584 8598960
12:04:41 49527 8192000000 10086885360 10534588416 1143271456 12.246 100 16 32 1 1187649072 41109072 3268544
12:06:35 16640 8192000000 10120967488 10580197376 1149415888 12.304 100 12 24 0 1228041056 76002176 2622992
12:08:14 75121 8192000000 10165729168 10664067072 1143482128 12.248 99 18 36 0 1245793904 98056272 4255504
12:11:41 56769 8192000000 10134676544 10599038976 1152896768 12.337 100 13 26 0 1218457888 62703392 2857728
12:13:42 51071 8192000000 10130338208 10604781568 1142834992 12.242 99 31 62 1 1228953184 79364288 6753904
12:16:01 63364 8192000000 10113810720 10549338112 1127609120 12.099 98 23 46 1 1182716528 49869984 5237424
12:18:05 71002 8192000000 10134107776 10611826688 1143373808 12.247 99 13 26 0 1218284720 71911536 2999376
12:20:18 49054 8192000000 10111519072 10560983040 1113587600 11.966 97 40 80 1 1177339504 55565648 8186256
12:22:07 16467 8192000000 10116796608 10541187072 1139133152 12.207 100 47 94 0 1189447008 41361264 8952592
12:23:25 71808 8192000000 10055745776 10539872256 1129038016 12.112 100 22 44 0 1185939872 52230048 4671808
12:25:41 64966 8192000000 10072032336 10554327040 1116957488 11.998 99 4 8 0 1165153904 47324688 871728
12:27:50 39141 8192000000 10054617824 10509025280 1122383200 12.050 99 8 16 1 1151589120 27769952 1435968
12:29:30 71750 8192000000 10124094432 10571501568 1107515552 11.909 96 14 28 0 1158846272 48259888 3070832
12:31:44 50534 8192000000 10103091680 10567221248 1094803904 11.788 96 16 33 1 1201458576 102882256 3772416
12:33:36 35025 8192000000 10104261552 10535522304 1122404624 12.050 99 42 84 0 1181352544 50592064 8355856
12:35:13 89136 8192000000 10091037168 10547507200 1065885888 11.513 94 24 48 2 1104220416 32941296 5393232
12:37:57 91154 8192000000 10111832272 10559545344 1078197648 11.630 96 11 22 0 1107188064 27199264 1791152
12:40:28 61880 8192000000 10123513312 10569318400 1065262944 11.507 94 18 36 1 1118052016 49234608 3554464
12:42:33 104586 8192000000 10063447376 10497916928 1089147968 11.735 98 25 50 0 1159518544 64725936 5644640
12:45:18 75513 8192000000 10085848688 10565758976 1125526976 12.079 100 8 16 0 1191388528 64106880 1754672
12:47:34 63854 8192000000 10079343504 10560765952 1093563184 11.777 97 10 20 0 1183917184 87979872 2374128
12:49:39 71438 8192000000 10111170288 10548727808 1101891920 11.856 98 21 42 1 1146784848 40672944 4219984
12:51:51 80792 8192000000 10181912512 10673180672 1125239264 12.076 99 23 46 2 1256378208 125614272 5524672
12:54:13 50262 8192000000 10112016016 10577682432 1115616256 11.986 99 15 30 0 1225972016 106700896 3654864
12:56:11 41745 8192000000 10079882928 10508062720 1118493408 12.013 99 28 56 0 1154937280 30632064 5811808
12:57:56 65124 8192000000 10074322832 10569871360 1130612000 12.127 100 13 27 0 1170243056 37687680 1943376
13:00:02 99999 8192000000 10131938464 10585808896 1146621200 12.278 100 28 56 0 1220454848 68202944 5630704
13:02:58 50894 8192000000 10050990800 10506960896 1101015520 11.847 97 7 14 1 1136421456 34512016 893920
13:04:49 96884 8192000000 10100288400 10586308608 1129313040 12.115 99 9 18 0 1172716992 41536608 1867344
13:07:28 74873 8192000000 10128811920 10601816064 1123631664 12.061 98 18 36 1 1206400464 78760416 4008384
13:09:43 57543 8192000000 10085371760 10523480064 1137279504 12.190 100 25 50 1 1193710256 50573904 5856848
13:11:47 54593 8192000000 10084394736 10539937792 1122575152 12.051 99 18 36 1 1184112992 57864256 3673584
13:13:43 64198 8192000000 10105455728 10633891840 1126198736 12.086 99 15 30 0 1191185392 62104976 2881680
13:16:40 64667 8192000000 10113224576 10576330752 1120202768 12.029 98 13 26 0 1172150704 49509248 2438688
13:19:08 122254 8192000000 10087237408 10577055744 1118170656 12.010 98 17 34 1 1213108944 91200848 3737440
13:22:10 63746 8192000000 10060659072 10563391488 1111824320 11.950 98 5 10 0 1153291520 40474000 993200
13:24:20 90113 8192000000 10105089296 10574233600 1093365472 11.775 96 13 26 0 1155841216 59672416 2803328
13:26:51 13849 8192000000 9329664240 9686573056 454192560 5.253 41 5 10 0 493244304 38092176 959568

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 14-06-2021 13:28:29 to 14-06-2021 13:28:44.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 94 v_add_invoice_to_stock 335544517
except 5 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 14-06-2021 13:28:45 to 14-06-2021 13:28:47.

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          233476
MON$OLDEST_ACTIVE               233941
MON$OLDEST_SNAPSHOT             233941
MON$NEXT_TRANSACTION            233941
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                       2109824
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             794
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2516 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2516 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2516 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2516 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:92: 
:93: mscs-test-161.budzdorov.ru    Mon Jun 14 13:27:09 2021
:94:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:95: 
:96: 
:97: mscs-test-161.budzdorov.ru    Mon Jun 14 13:27:11 2021
:98:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:99: 
--- end of diff output ---

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

14.06.21 13:28:48. 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-06-14 13:28:51 No severe PSQL-related problems occured
14.06.21 13:28:52. End of report.