Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 25.08.2021 07: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 16003244 kB
MemAvailable 30977416 kB
Buffers 0 kB
Cached 15704128 kB
SwapCached 1324 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-08-25 07:37:03
DTS_END                         2021-08-25 07:37:03.5640
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> X_MAKE_QSTORNO_1200_2000(279:25) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/47718
TRN_ID                          234175
ATT_ID                          644
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: 9083.97 28192 1090076
customer order: creation 164.91 419 19789
customer order: refuse 14.49 401 1739
order to supplier: creation 44.99 1393 5399
order to supplier: removal 3.43 116836 411
invoice (draft): creation 46.18 3718 5542
invoice (draft): removal 52.38 957 6285
invoice accept: apply 40.67 20283 4881
invoice accept: cancel 46.90 888 5628
customer reserve: creation 3809.63 199 457156
customer reserve: removal 4571.58 69 548589
realization accept: apply 81.85 199 9822
realization accept: cancel 83.88 139 10066
payment from customer: creation 55.51 112 6661
payment from customer: removal 5.85 88 702
payment to supplier: creation 47.75 150 5730
payment to supplier: removal 3.30 76 396
service: total inventory turnovers 3.66 3880 439
service: total monetary turnovers 6.06 660 727
service: aggregate perf. data 0.92 15930 110
service: refresh index statistics 0.03 397445 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 25-08-2021 07:38:30 to 25-08-2021 07:38:32.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 9017
TEST_TIME 2 6979
TEST_TIME 3 6128
TEST_TIME 4 5317
TEST_TIME 5 4713
TEST_TIME 6 4910
TEST_TIME 7 5999
TEST_TIME 8 6161
TEST_TIME 9 6674
TEST_TIME 10 6931
TEST_TIME 11 7210
TEST_TIME 12 7171
TEST_TIME 13 7217
TEST_TIME 14 7271
TEST_TIME 15 7282
TEST_TIME 16 7579
TEST_TIME 17 7659
TEST_TIME 18 7979
TEST_TIME 19 7961
TEST_TIME 20 8144
TEST_TIME 21 8268
TEST_TIME 22 8137
TEST_TIME 23 8385
TEST_TIME 24 8516
TEST_TIME 25 8563
TEST_TIME 26 8565
TEST_TIME 27 9123
TEST_TIME 28 9245
TEST_TIME 29 10541
TEST_TIME 30 10386
TEST_TIME 31 10280
TEST_TIME 32 10433
TEST_TIME 33 10259
TEST_TIME 34 10398
TEST_TIME 35 10342
TEST_TIME 36 10460
TEST_TIME 37 10342
TEST_TIME 38 10285
TEST_TIME 39 10273
TEST_TIME 40 10207
TEST_TIME 41 9967
TEST_TIME 42 10011
TEST_TIME 43 9824
TEST_TIME 44 9851
TEST_TIME 45 9786
TEST_TIME 46 9682
TEST_TIME 47 9663
TEST_TIME 48 9644
TEST_TIME 49 9610
TEST_TIME 50 9613
TEST_TIME 51 9602
TEST_TIME 52 9677
TEST_TIME 53 9615
TEST_TIME 54 9667
TEST_TIME 55 9602
TEST_TIME 56 9650
TEST_TIME 57 9573
TEST_TIME 58 9609
TEST_TIME 59 9658
TEST_TIME 60 9708
TEST_TIME 61 9672
TEST_TIME 62 9668
TEST_TIME 63 9853
TEST_TIME 64 9870
TEST_TIME 65 9824
TEST_TIME 66 9844
TEST_TIME 67 9875
TEST_TIME 68 9745
TEST_TIME 69 9685
TEST_TIME 70 9633
TEST_TIME 71 9612
TEST_TIME 72 9731
TEST_TIME 73 9635
TEST_TIME 74 9530
TEST_TIME 75 9510
TEST_TIME 76 9485
TEST_TIME 77 9441
TEST_TIME 78 9483
TEST_TIME 79 9447
TEST_TIME 80 9434
TEST_TIME 81 9399
TEST_TIME 82 9441
TEST_TIME 83 9408
TEST_TIME 84 9637
TEST_TIME 85 9641
TEST_TIME 86 9582
TEST_TIME 87 9617
TEST_TIME 88 9646
TEST_TIME 89 9567
TEST_TIME 90 9511
TEST_TIME 91 9458
TEST_TIME 92 9442
TEST_TIME 93 9417
TEST_TIME 94 9351
TEST_TIME 95 9341
TEST_TIME 96 9326
TEST_TIME 97 9302
TEST_TIME 98 9315
TEST_TIME 99 9288
TEST_TIME 100 9294
TEST_TIME 101 9263
TEST_TIME 102 9317
TEST_TIME 103 9350
TEST_TIME 104 9355
TEST_TIME 105 9364
TEST_TIME 106 9375
TEST_TIME 107 9385
TEST_TIME 108 9379
TEST_TIME 109 9407
TEST_TIME 110 9429
TEST_TIME 111 9348
TEST_TIME 112 9274
TEST_TIME 113 9244
TEST_TIME 114 9212
TEST_TIME 115 9182
TEST_TIME 116 9179
TEST_TIME 117 9171
TEST_TIME 118 9183
TEST_TIME 119 9145
TEST_TIME 120 9090

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 25-08-2021 07:38:34 to 25-08-2021 07:38:37.

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 1512569 1512569 0 0.00 0 18503 7 0 0
doc_list_biud 1512566 1512566 0 0.00 0 46232 32 0 0
sp_add_invoice_to_stock 5015 4881 134 2.67 51 192849 20283 0 134
sp_cancel_adding_invoice 5628 5628 0 0.00 5 91471 888 0 0
sp_cancel_client_order 1739 1739 0 0.00 4 15554 402 0 0
sp_cancel_customer_reserve 548589 548589 0 0.00 1 24522 69 0 0
sp_cancel_pay_from_customer 702 702 0 0.00 3 6101 88 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 2628 76 0 0
sp_cancel_supplier_invoice 6294 6285 9 0.14 1 38656 957 0 9
sp_cancel_supplier_order 411 411 0 0.00 10 1108719 116836 0 0
sp_cancel_write_off 10066 10066 0 0.00 1 14511 139 0 0
sp_client_order 19789 19789 0 0.00 4 30557 419 0 0
sp_customer_reserve 457156 457156 0 0.00 0 105602 199 0 0
sp_fill_shopping_cart 30719 30719 0 0.00 1 19676 49 0 0
sp_fill_shopping_cart_clo_res 457143 457143 0 0.00 0 19725 16 0 0
sp_get_clo_for_invoice 4868 4868 0 0.00 0 19825 320 0 0
sp_kill_cost_storno 16792 16792 0 0.00 0 14508 64 0 0
sp_kill_qstorno_ret_qs2qd 1113940 1113940 0 0.00 0 29114 38 0 0
sp_kill_qty_storno 587406 587406 0 0.00 0 46232 81 0 0
sp_lock_selected_doc 20297 20297 0 0.00 0 8810 5 0 0
sp_make_cost_storno 27080 27080 0 0.00 0 18489 58 0 0
sp_multiply_rows_for_pdistr 27079 27079 0 0.00 0 9897 32 0 0
sp_multiply_rows_for_qdistr 19788 19788 0 0.00 0 17185 219 0 0
sp_pay_from_customer 6661 6661 0 0.00 5 11765 112 0 0
sp_pay_to_supplier 5730 5730 0 0.00 5 12124 151 0 0
sp_payment_common 12391 12391 0 0.00 1 9743 26 0 0
sp_qd_handle_on_cancel_clo 1739 1739 0 0.00 0 15548 363 0 0
sp_qd_handle_on_invoice_upd_sts 10494 10494 0 0.00 0 46231 372 0 0
sp_qd_handle_on_reserve_upd_sts 9822 9822 0 0.00 0 16739 58 0 0
sp_reserve_write_off 9822 9822 0 0.00 7 16757 199 0 0
sp_supplier_invoice 5542 5542 0 0.00 7 97119 3719 0 0
sp_supplier_order 5399 5399 0 0.00 5 27409 1394 0 0
srv_aggregate_perf_data 110 110 0 0.00 2363 345298 15931 0 0
srv_fill_mon_cache_memory 65 65 0 0.00 1442 154470 43699 0 0
srv_find_qd_qs_mism 1043249 1043249 0 0.00 0 16219 6 0 0
srv_make_invnt_saldo 439 439 0 0.00 64 113009 3880 0 0
srv_make_money_saldo 727 727 0 0.00 1 20607 661 0 0
srv_recalc_idx_stat 4 4 0 0.00 297350 542353 397446 0 0
t$perf-abend:sp_add_invoice_to_stock 134 134 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 4881 4881 0 0.00 0 19189 180 0 0
t$perf-norm:sp_cancel_adding_invoice 359 359 0 0.00 1 2331 120 0 0
t$perf-norm:sp_cancel_client_order 1739 1739 0 0.00 0 1455 12 0 0
t$perf-norm:sp_cancel_customer_reserve 640 640 0 0.00 0 1461 12 0 0
t$perf-norm:sp_cancel_pay_from_customer 702 702 0 0.00 0 6172 13 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 154 3 0 0
t$perf-norm:sp_cancel_supplier_invoice 398 398 0 0.00 0 954 24 0 0
t$perf-norm:sp_cancel_supplier_order 411 411 0 0.00 0 19883 1684 0 0
t$perf-norm:sp_cancel_write_off 965 965 0 0.00 0 1034 8 0 0
t$perf-norm:sp_client_order 19789 19789 0 0.00 0 6376 18 0 0
t$perf-norm:sp_customer_reserve 975 975 0 0.00 0 1234 6 0 0
t$perf-norm:sp_pay_from_customer 6661 6661 0 0.00 0 7164 11 0 0
t$perf-norm:sp_pay_to_supplier 5730 5730 0 0.00 0 6169 12 0 0
t$perf-norm:sp_reserve_write_off 9822 9822 0 0.00 0 6136 11 0 0
t$perf-norm:sp_supplier_invoice 5542 5542 0 0.00 0 3360 17 0 0
t$perf-norm:sp_supplier_order 5399 5399 0 0.00 0 7032 19 0 0
t$perf-norm:srv_aggregate_perf_data 110 110 0 0.00 0 255 10 0 0
t$perf-norm:srv_fill_mon_cache_memory 65 65 0 0.00 0 104 3 0 0
t$perf-norm:srv_make_invnt_saldo 439 439 0 0.00 0 5555 35 0 0
t$perf-norm:srv_make_money_saldo 727 727 0 0.00 0 1267 9 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 7 3 0 0
v_add_invoice_to_stock 9999 9732 267 2.67 0 6285 21 0 267
v_all_customers 35556 35556 0 0.00 0 20 1 0 0
v_all_suppliers 30252 30252 0 0.00 0 30 1 0 0
v_all_wares 39576 39576 0 0.00 0 25 1 0 0
v_cancel_adding_invoice 718 718 0 0.00 0 2111 12 0 0
v_cancel_client_order 3478 3478 0 0.00 0 2083 4 0 0
v_cancel_customer_prepayment 1404 1404 0 0.00 0 5559 11 0 0
v_cancel_customer_reserve 1280 1280 0 0.00 0 1886 7 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 2622 8 0 0
v_cancel_supplier_invoice 814 796 18 2.21 0 1858 18 0 18
v_cancel_supplier_order 822 822 0 0.00 0 1471 5 0 0
v_cancel_write_off 1930 1930 0 0.00 0 6143 23 0 0
v_max_id_clo_ord 5399 5399 0 0.00 0 741 2 0 0
v_max_id_clo_res 975 975 0 0.00 0 2719 14 0 0
v_max_id_ord_sup 5532 5532 0 0.00 0 13255 7 0 0
v_max_non_paid_invoice 1535 1535 0 0.00 0 377 2 0 0
v_max_non_paid_realizn 3740 3740 0 0.00 0 731 3 0 0
v_min_id_clo_ord 5399 5399 0 0.00 0 3378 9 0 0
v_min_id_clo_res 975 975 0 0.00 0 9476 18 0 0
v_min_id_ord_sup 5532 5532 0 0.00 0 15344 12 0 0
v_min_non_paid_invoice 5730 5730 0 0.00 0 9739 23 0 0
v_min_non_paid_realizn 6661 6661 0 0.00 0 9085 15 0 0
v_our_firm 9862 9862 0 0.00 0 22 1 0 0
v_reserve_write_off 19642 19642 0 0.00 0 6698 8 0 0
x_lock_depdocs_on_canc_invoice 11515 11515 0 0.00 0 17616 131 0 0
x_lock_depdocs_on_canc_sup_ord 411 411 0 0.00 0 3654 349 0 0
x_make_qstorno_1000_1200 5399 5399 0 0.00 2 27403 1302 0 0
x_make_qstorno_1200_2000 5542 5542 0 0.00 4 96960 3537 0 0
x_make_qstorno_2100_3300 457156 457156 0 0.00 0 105600 181 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 25-08-2021 07:38:38 to 25-08-2021 07:38:42.

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
05:07:07 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:08 22731 8192000000 9713711488 10008793088 791833936 8.813 100 90 180 0 822147600 8337504 21976160
05:09:37 28867 8192000000 9763281184 10068144128 830591616 9.205 100 91 182 1 864253888 11797328 21864944
05:11:10 16086 8192000000 9790381088 10106728448 861324384 9.513 100 80 160 1 902694960 22686192 18684384
05:12:32 16453 8192000000 9845758912 10174472192 902725040 9.925 100 84 166 1 947952160 26924480 18302640
05:13:50 9973 8192000000 9922489680 10266628096 953396000 10.424 100 79 155 1 1027797456 58374016 16027440
05:15:02 5379 8192000000 9962692528 10312863744 985171296 10.735 100 83 166 0 1081783808 79455104 17157408
05:16:10 3600 8192000000 9993424960 10353274880 1011507216 10.990 100 81 157 2 1102215856 75358848 15349792
05:17:15 6029 8192000000 10001183472 10365902848 1027190976 11.141 100 77 154 0 1112612704 70480816 14940912
05:18:24 1567 8192000000 10035668576 10401865728 1054716160 11.406 100 93 188 0 1147176944 72976384 19484400
05:19:28 2090 8192000000 10055468384 10428575744 1063860912 11.493 100 87 174 0 1165286992 83374768 18051312
05:20:32 8207 8192000000 10035716000 10433695744 1068996480 11.542 100 60 120 0 1155387776 73879776 12511520
05:21:43 5615 8192000000 9982843024 10389528576 1077440688 11.623 100 27 53 2 1104649456 21716160 5492608
05:22:51 10198 8192000000 10000775584 10400854016 1088553600 11.729 100 28 56 0 1123943072 29636384 5753088
05:24:03 4432 8192000000 10010620608 10407362560 1090181728 11.744 100 45 90 0 1127983136 28234992 9566416
05:25:10 26658 8192000000 10012985648 10400358400 1095695280 11.797 100 62 127 1 1119841216 10426800 13719136
05:26:37 9318 8192000000 10012439360 10414260224 1080476480 11.652 99 40 79 2 1124084400 35534704 8073216
05:27:52 5605 8192000000 10002846704 10403926016 1086902128 11.713 100 37 71 0 1117869872 23140880 7826864
05:28:58 8733 8192000000 9984228672 10379632640 1049571264 11.357 99 34 70 0 1074249120 16942880 7734976
05:30:09 54355 8192000000 9941881472 10339385344 987122336 10.753 95 29 59 0 1020981408 27981952 5877120
05:32:08 10059 8192000000 9920489824 10308747264 970842592 10.595 94 39 77 1 1003083888 23024240 9217056
05:33:18 6857 8192000000 9947653984 10341740544 1037436432 11.240 100 32 64 2 1061443456 16959040 7047984
05:34:25 13454 8192000000 9962898384 10363637760 1044558928 11.308 99 28 53 6 1066928480 15920464 6449088
05:35:41 31693 8192000000 9969292048 10378231808 1054287616 11.402 100 21 42 2 1077361328 18328864 4744848
05:37:23 15674 8192000000 9995188000 10391748608 1070943856 11.561 100 48 96 2 1104951200 23923216 10084128
05:38:42 10822 8192000000 10043710512 10447675392 1088100320 11.725 100 51 102 0 1154184592 55421648 10662624
05:40:09 49818 8192000000 10084269360 10488340480 1108289872 11.916 100 42 84 0 1197524544 79849664 9385008
05:42:04 47360 8192000000 10107326352 10514345984 1113588192 11.966 100 31 62 2 1225374944 104710320 7076432
05:43:59 28073 8192000000 10130307312 10547224576 1123408976 12.059 100 40 80 3 1265774304 133561088 8804240
05:45:36 1440 8192000000 10162744016 10565201920 1126329936 12.087 100 81 162 3 1265746960 121701856 17715168
05:46:42 1687 8192000000 10150463408 10560745472 1129434800 12.116 100 75 148 3 1257508592 111038272 17035520
05:47:50 1935 8192000000 10161750816 10563715072 1145354080 12.266 100 67 134 1 1262071168 102158800 14558288
05:48:57 3934 8192000000 10168063552 10572410880 1154955856 12.356 100 68 136 0 1274517440 104683008 14878576
05:50:07 7142 8192000000 10204020048 10603405312 1157836096 12.383 100 83 166 0 1302326272 126824288 17665888
05:51:18 5231 8192000000 10247287728 10662289408 1152166000 12.330 100 61 121 0 1349345824 183324976 13854848
05:52:37 14806 8192000000 10236928832 10661531648 1154796624 12.354 100 69 138 1 1355142528 184582288 15763616
05:54:01 2747 8192000000 10269302848 10670571520 1152931200 12.337 100 81 162 1 1369389920 198567280 17891440
05:55:08 1674 8192000000 10298091984 10696806400 1162588848 12.428 99 92 181 2 1392998080 210063872 20345360
05:56:14 10632 8192000000 10311680496 10727137280 1142082848 12.235 99 50 100 3 1391907200 237454896 12369456
05:57:29 4445 8192000000 10320267152 10727030784 1160271376 12.406 100 69 138 1 1414319936 237680864 16367696
05:58:38 8203 8192000000 10322893280 10744004608 1148656528 12.297 100 38 76 1 1426136608 267791920 9688160
05:59:56 18577 8192000000 10399243936 10828316672 1160024544 12.403 100 43 86 1 1497369056 326287904 11056608
06:01:38 16345 8192000000 10403012656 10841387008 1166948752 12.468 100 40 80 0 1513638240 336681888 10007600
06:03:16 7485 8192000000 10448108928 10891300864 1142387504 12.238 99 38 76 1 1535042848 382498832 10156512
06:04:40 23161 8192000000 10324337456 10809753600 1125326832 12.077 100 20 40 0 1465492144 334311904 5853408
06:06:09 19421 8192000000 10219660448 10717593600 1129034640 12.112 100 22 44 1 1352994016 218174368 5785008
06:07:31 23674 8192000000 10095872000 10528948224 1126422512 12.088 99 33 66 1 1173036848 39256928 7357408
06:09:01 37412 8192000000 10088438016 10598014976 1111416608 11.946 98 12 24 1 1162434960 48518560 2499792
06:10:39 60667 8192000000 10096237968 10590081024 1107558752 11.909 98 25 51 3 1205735968 93086688 5090528
06:12:47 41901 8192000000 10094512464 10554638336 1120037504 12.027 99 19 38 0 1191938080 67520144 4380432
06:14:37 24612 8192000000 10071248512 10494046208 1133693408 12.156 100 21 43 3 1156587216 18626944 4266864
06:16:02 66204 8192000000 10094814992 10576027648 1134569440 12.164 99 14 28 1 1198173472 61059104 2544928
06:18:09 90436 8192000000 10088582368 10533289984 1115919024 11.988 97 36 72 0 1167609728 43536480 8154224
06:20:42 38954 8192000000 10095256576 10532044800 1134069312 12.160 99 36 71 1 1181831360 40692048 7070000
06:22:24 26734 8192000000 10131652464 10576785408 1156425552 12.370 100 30 59 2 1229609232 67095216 6088464
06:23:53 44077 8192000000 10203334016 10651209728 1174399072 12.538 100 50 99 1 1302999264 117878848 10721344
06:25:44 71181 8192000000 10142272400 10586030080 1119308672 12.020 96 17 34 0 1231765184 108447824 4008688
06:27:57 62359 8192000000 10169238352 10621059072 1132303584 12.143 98 27 54 1 1251323888 112639152 6381152
06:30:04 54042 8192000000 10113821952 10598694912 1115614192 11.986 97 16 32 0 1175708128 56522784 3571152
06:32:04 82531 8192000000 10063488528 10561757184 1068924672 11.542 94 12 25 1 1141346736 69892480 2529584
06:34:34 63914 8192000000 10100330368 10570817536 1103966096 11.875 98 24 48 0 1179537744 70458944 5112704
06:36:45 70943 8192000000 10095401104 10571132928 1088513744 11.729 96 23 46 0 1180201728 86813696 4874288
06:38:56 35000 8192000000 10162003600 10607386624 1106577184 11.900 98 36 72 1 1243105568 128808480 7719904
06:40:38 71184 8192000000 10042945280 10502987776 1084669808 11.692 97 14 28 0 1141082864 53408560 3004496
06:43:02 25878 8192000000 10088963232 10506620928 1081281200 11.660 97 42 84 1 1132129920 42150000 8698720
06:44:28 108296 8192000000 10056242992 10504433664 1114439824 11.974 100 22 44 3 1172353232 52953744 4959664
06:47:29 36827 8192000000 10056097072 10478632960 1101183184 11.849 98 41 82 0 1152379520 42855232 8341104
06:49:45 53109 8192000000 10067410224 10599583744 1115794080 11.987 99 8 16 0 1168797664 51504336 1499248
06:51:45 76615 8192000000 10077704144 10552094720 1108472928 11.918 98 12 24 0 1150828368 39781040 2574400
06:54:11 59292 8192000000 10085513632 10534731776 1090739552 11.750 97 12 23 1 1157353168 63957248 2656368
06:56:11 76426 8192000000 10125330976 10565042176 1107442432 11.908 98 18 35 2 1189227120 77750768 4033920
06:58:28 48723 8192000000 10192432576 10629144576 1141102608 12.226 100 25 50 1 1275294672 128415504 5776560
07:00:17 43544 8192000000 10173281104 10615672832 1126619424 12.089 99 35 70 0 1270708960 135569296 8520240
07:02:09 59808 8192000000 10079859616 10546831360 1124367248 12.068 99 4 8 0 1176670752 51743584 559920
07:04:09 10801 8192000000 10123692400 10549137408 1140630432 12.221 100 52 104 0 1209084416 58219616 10234368
07:05:22 62711 8192000000 10065741808 10513948672 1132148000 12.142 99 20 40 0 1195431776 58811136 4472640
07:07:25 65918 8192000000 10100210816 10539720704 1135533744 12.173 99 39 78 1 1180029728 36436672 8059312
07:09:33 69494 8192000000 10111517280 10592526336 1144298928 12.256 99 20 40 1 1247659584 98525840 4834816
07:11:46 61733 8192000000 10126732432 10632089600 1156602016 12.371 100 15 29 1 1233425792 73232992 3590784
07:13:54 96791 8192000000 10108106976 10570739712 1122105488 12.047 97 10 20 1 1153676400 29949712 1621200
07:16:33 58378 8192000000 10126023600 10581397504 1153561232 12.343 100 20 39 1 1190121664 32516192 4044240
07:18:35 49598 8192000000 10145914576 10598608896 1174380832 12.538 100 36 72 0 1245426528 63922688 7123008
07:20:25 87290 8192000000 10119107968 10589712384 1113260816 11.963 96 16 31 0 1162842336 46758112 2823408
07:23:04 86264 8192000000 10111589728 10584793088 1095667584 11.797 95 28 55 6 1153778000 52656672 5453744
07:25:35 154470 8192000000 10043906688 10536464384 1039068416 11.256 91 16 32 0 1107406592 65205136 3133040
07:30:05 37399 8192000000 10102598656 10552631296 1112445840 11.956 98 43 86 1 1180291280 59173632 8671808
07:31:46 56750 8192000000 10066168736 10536456192 1097388816 11.813 98 8 16 0 1162369392 63197568 1783008
07:33:42 59782 8192000000 10080259536 10564947968 1118212192 12.010 99 12 25 0 1208081632 87223920 2645520
07:36:44 28034 8192000000 9061843360 9328324608 205886928 2.451 18 5 8 1 227670464 20581376 1202160

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 25-08-2021 07:38:44 to 25-08-2021 07:38:59.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 134 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 25-08-2021 07:39:00 to 25-08-2021 07:39:02.

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          234550
MON$OLDEST_ACTIVE               234551
MON$OLDEST_SNAPSHOT             234551
MON$NEXT_TRANSACTION            234551
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                       2063072
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             803
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2571 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2571 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2571 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2571 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 ---
:266: 
:267: mscs-test-161.budzdorov.ru    Wed Aug 25 07:37:15 2021
:268:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:269: 
:270: 
:271: mscs-test-161.budzdorov.ru    Wed Aug 25 07:37:20 2021
:272:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:273: 
--- end of diff output ---

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

25.08.21 07:39:03. 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-08-25 07:39:05 No severe PSQL-related problems occured
25.08.21 07:39:07. End of report.