Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 17.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 16166184 kB
MemAvailable 31041088 kB
Buffers 0 kB
Cached 15559728 kB
SwapCached 2580 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-17 07:37:04
DTS_END                         2021-08-17 07:37:04.2760
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/34210
TRN_ID                          238822
ATT_ID                          632
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: 9046.76 22523 1085611
customer order: creation 171.98 400 20637
customer order: refuse 13.87 311 1664
order to supplier: creation 44.80 1322 5376
order to supplier: removal 3.42 124824 410
invoice (draft): creation 44.73 3534 5367
invoice (draft): removal 50.61 1034 6073
invoice accept: apply 40.86 19508 4903
invoice accept: cancel 45.48 868 5458
customer reserve: creation 3820.54 190 458465
customer reserve: removal 4522.41 75 542689
realization accept: apply 83.14 190 9977
realization accept: cancel 78.39 132 9407
payment from customer: creation 58.88 108 7065
payment from customer: removal 5.82 98 698
payment to supplier: creation 47.33 123 5679
payment to supplier: removal 3.14 75 377
service: total inventory turnovers 3.93 3318 471
service: total monetary turnovers 6.46 512 775
service: aggregate perf. data 0.97 13876 116
service: refresh index statistics 0.03 279962 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 17-08-2021 07:39:09 to 17-08-2021 07:39:11.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6799
TEST_TIME 2 5406
TEST_TIME 3 5272
TEST_TIME 4 4981
TEST_TIME 5 5268
TEST_TIME 6 5351
TEST_TIME 7 5022
TEST_TIME 8 5264
TEST_TIME 9 5395
TEST_TIME 10 5528
TEST_TIME 11 5779
TEST_TIME 12 6088
TEST_TIME 13 6506
TEST_TIME 14 6415
TEST_TIME 15 6641
TEST_TIME 16 6995
TEST_TIME 17 7188
TEST_TIME 18 7504
TEST_TIME 19 7933
TEST_TIME 20 8013
TEST_TIME 21 8089
TEST_TIME 22 8140
TEST_TIME 23 8515
TEST_TIME 24 8736
TEST_TIME 25 9131
TEST_TIME 26 9288
TEST_TIME 27 9244
TEST_TIME 28 10290
TEST_TIME 29 11115
TEST_TIME 30 11054
TEST_TIME 31 11060
TEST_TIME 32 10842
TEST_TIME 33 10863
TEST_TIME 34 10653
TEST_TIME 35 10376
TEST_TIME 36 10334
TEST_TIME 37 10165
TEST_TIME 38 10016
TEST_TIME 39 10207
TEST_TIME 40 10147
TEST_TIME 41 10066
TEST_TIME 42 9919
TEST_TIME 43 9903
TEST_TIME 44 9818
TEST_TIME 45 9776
TEST_TIME 46 9800
TEST_TIME 47 9736
TEST_TIME 48 9806
TEST_TIME 49 9721
TEST_TIME 50 9795
TEST_TIME 51 9735
TEST_TIME 52 9843
TEST_TIME 53 9837
TEST_TIME 54 9799
TEST_TIME 55 9833
TEST_TIME 56 9833
TEST_TIME 57 9841
TEST_TIME 58 9856
TEST_TIME 59 9841
TEST_TIME 60 9723
TEST_TIME 61 9693
TEST_TIME 62 9567
TEST_TIME 63 9599
TEST_TIME 64 9495
TEST_TIME 65 9488
TEST_TIME 66 9452
TEST_TIME 67 9393
TEST_TIME 68 9470
TEST_TIME 69 9470
TEST_TIME 70 9469
TEST_TIME 71 9458
TEST_TIME 72 9396
TEST_TIME 73 9447
TEST_TIME 74 9567
TEST_TIME 75 9587
TEST_TIME 76 9661
TEST_TIME 77 9654
TEST_TIME 78 9575
TEST_TIME 79 9503
TEST_TIME 80 9466
TEST_TIME 81 9411
TEST_TIME 82 9354
TEST_TIME 83 9286
TEST_TIME 84 9327
TEST_TIME 85 9320
TEST_TIME 86 9287
TEST_TIME 87 9264
TEST_TIME 88 9396
TEST_TIME 89 9351
TEST_TIME 90 9320
TEST_TIME 91 9362
TEST_TIME 92 9411
TEST_TIME 93 9397
TEST_TIME 94 9500
TEST_TIME 95 9579
TEST_TIME 96 9526
TEST_TIME 97 9444
TEST_TIME 98 9407
TEST_TIME 99 9388
TEST_TIME 100 9353
TEST_TIME 101 9294
TEST_TIME 102 9293
TEST_TIME 103 9258
TEST_TIME 104 9216
TEST_TIME 105 9196
TEST_TIME 106 9201
TEST_TIME 107 9197
TEST_TIME 108 9155
TEST_TIME 109 9198
TEST_TIME 110 9261
TEST_TIME 111 9250
TEST_TIME 112 9233
TEST_TIME 113 9279
TEST_TIME 114 9304
TEST_TIME 115 9333
TEST_TIME 116 9263
TEST_TIME 117 9212
TEST_TIME 118 9181
TEST_TIME 119 9121
TEST_TIME 120 9048

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 17-08-2021 07:39:13 to 17-08-2021 07:39:16.

Performance in DETAILS

UNIT CNT_ALL CNT_OK CNT_ERR ERR_PRC OK_MIN_MS OK_MAX_MS OK_AVG_MS CNT_LK_CONFL CNT_USER_EXC
doc_list_aiud 1510290 1510290 0 0.00 0 22780 7 0 0
doc_list_biud 1510289 1510289 0 0.00 0 48254 34 0 0
sp_add_invoice_to_stock 5072 4903 169 3.33 40 195429 19508 0 169
sp_cancel_adding_invoice 5458 5458 0 0.00 4 54906 868 0 0
sp_cancel_client_order 1664 1664 0 0.00 4 7017 312 0 0
sp_cancel_customer_reserve 542689 542689 0 0.00 1 32829 75 0 0
sp_cancel_pay_from_customer 698 698 0 0.00 2 4718 99 0 0
sp_cancel_pay_to_supplier 377 377 0 0.00 3 4231 76 0 0
sp_cancel_supplier_invoice 6088 6073 15 0.24 2 48263 1034 0 15
sp_cancel_supplier_order 410 410 0 0.00 9 1193986 124824 0 0
sp_cancel_write_off 9407 9407 0 0.00 1 23927 132 0 0
sp_client_order 20637 20637 0 0.00 4 19734 400 0 0
sp_customer_reserve 458465 458465 0 0.00 0 91479 190 0 0
sp_fill_shopping_cart 31377 31377 0 0.00 1 16855 41 0 0
sp_fill_shopping_cart_clo_res 458462 458462 0 0.00 0 15620 17 0 0
sp_get_clo_for_invoice 4899 4899 0 0.00 0 17545 359 0 0
sp_kill_cost_storno 15940 15940 0 0.00 0 10485 60 0 0
sp_kill_qstorno_ret_qs2qd 1101268 1101268 0 0.00 0 48250 41 0 0
sp_kill_qty_storno 580576 580576 0 0.00 0 48252 86 0 0
sp_lock_selected_doc 20474 20474 0 0.00 0 7836 5 0 0
sp_make_cost_storno 27620 27620 0 0.00 0 22752 61 0 0
sp_multiply_rows_for_pdistr 27619 27619 0 0.00 0 9372 30 0 0
sp_multiply_rows_for_qdistr 20637 20637 0 0.00 0 16376 228 0 0
sp_pay_from_customer 7065 7065 0 0.00 4 10715 109 0 0
sp_pay_to_supplier 5679 5679 0 0.00 4 9277 124 0 0
sp_payment_common 12744 12744 0 0.00 1 7522 23 0 0
sp_qd_handle_on_cancel_clo 1664 1664 0 0.00 0 7011 288 0 0
sp_qd_handle_on_invoice_upd_sts 10357 10357 0 0.00 0 34261 367 0 0
sp_qd_handle_on_reserve_upd_sts 9976 9976 0 0.00 0 17593 63 0 0
sp_reserve_write_off 9977 9977 0 0.00 7 17630 190 0 0
sp_supplier_invoice 5367 5367 0 0.00 5 78987 3534 0 0
sp_supplier_order 5376 5376 0 0.00 5 26710 1322 0 0
srv_aggregate_perf_data 116 116 0 0.00 2897 95004 13876 0 0
srv_fill_mon_cache_memory 61 61 0 0.00 956 145572 47905 0 0
srv_find_qd_qs_mism 1038524 1038524 0 0.00 0 9354 7 0 0
srv_make_invnt_saldo 471 471 0 0.00 65 103450 3318 0 0
srv_make_money_saldo 775 775 0 0.00 1 15622 512 0 0
srv_recalc_idx_stat 4 4 0 0.00 200571 428012 279963 0 0
t$perf-abend:sp_add_invoice_to_stock 169 169 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 15 15 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4904 4904 0 0.00 0 8519 159 0 0
t$perf-norm:sp_cancel_adding_invoice 415 415 0 0.00 0 2325 84 0 0
t$perf-norm:sp_cancel_client_order 1664 1664 0 0.00 0 2954 11 0 0
t$perf-norm:sp_cancel_customer_reserve 647 647 0 0.00 0 3833 11 0 0
t$perf-norm:sp_cancel_pay_from_customer 698 698 0 0.00 0 1429 4 0 0
t$perf-norm:sp_cancel_pay_to_supplier 377 377 0 0.00 0 2208 11 0 0
t$perf-norm:sp_cancel_supplier_invoice 409 409 0 0.00 0 615 11 0 0
t$perf-norm:sp_cancel_supplier_order 410 410 0 0.00 0 14621 1465 0 0
t$perf-norm:sp_cancel_write_off 979 979 0 0.00 0 831 3 0 0
t$perf-norm:sp_client_order 20637 20637 0 0.00 0 11830 18 0 0
t$perf-norm:sp_customer_reserve 995 995 0 0.00 0 665 5 0 0
t$perf-norm:sp_pay_from_customer 7065 7065 0 0.00 0 12161 13 0 0
t$perf-norm:sp_pay_to_supplier 5679 5679 0 0.00 0 5020 9 0 0
t$perf-norm:sp_reserve_write_off 9977 9977 0 0.00 0 23097 10 0 0
t$perf-norm:sp_supplier_invoice 5367 5367 0 0.00 0 32196 25 0 0
t$perf-norm:sp_supplier_order 5376 5376 0 0.00 0 4246 13 0 0
t$perf-norm:srv_aggregate_perf_data 116 116 0 0.00 0 87 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 61 61 0 0.00 0 112 4 0 0
t$perf-norm:srv_make_invnt_saldo 471 471 0 0.00 0 21437 57 0 0
t$perf-norm:srv_make_money_saldo 775 775 0 0.00 0 826 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 4 3 0 0
v_add_invoice_to_stock 10136 9798 338 3.33 0 4689 15 0 338
v_all_customers 37578 37578 0 0.00 0 16 1 0 0
v_all_suppliers 29752 29752 0 0.00 0 16 1 0 0
v_all_wares 41274 41274 0 0.00 0 21 1 0 0
v_cancel_adding_invoice 830 830 0 0.00 0 1537 8 0 0
v_cancel_client_order 3328 3328 0 0.00 0 3923 4 0 0
v_cancel_customer_prepayment 1396 1396 0 0.00 0 2770 11 0 0
v_cancel_customer_reserve 1294 1294 0 0.00 0 281 3 0 0
v_cancel_payment_to_supplier 754 754 0 0.00 0 4224 10 0 0
v_cancel_supplier_invoice 848 818 30 3.53 0 1211 13 0 30
v_cancel_supplier_order 820 820 0 0.00 0 335 5 0 0
v_cancel_write_off 1958 1958 0 0.00 0 8743 19 0 0
v_max_id_clo_ord 5376 5376 0 0.00 0 2494 2 0 0
v_max_id_clo_res 995 995 0 0.00 0 3327 16 0 0
v_max_id_ord_sup 5364 5364 0 0.00 0 6042 7 0 0
v_max_non_paid_invoice 1543 1543 0 0.00 0 212 2 0 0
v_max_non_paid_realizn 3752 3752 0 0.00 0 3990 6 0 0
v_min_id_clo_ord 5376 5376 0 0.00 0 1771 5 0 0
v_min_id_clo_res 995 995 0 0.00 0 14088 18 0 0
v_min_id_ord_sup 5364 5364 0 0.00 0 10519 5 0 0
v_min_non_paid_invoice 5679 5679 0 0.00 0 4331 14 0 0
v_min_non_paid_realizn 7065 7065 0 0.00 0 6722 17 0 0
v_our_firm 10322 10322 0 0.00 0 13 1 0 0
v_reserve_write_off 19952 19952 0 0.00 0 4223 4 0 0
x_lock_depdocs_on_canc_invoice 11122 11122 0 0.00 0 12379 110 0 0
x_lock_depdocs_on_canc_sup_ord 410 410 0 0.00 0 12556 405 0 0
x_make_qstorno_1000_1200 5376 5376 0 0.00 2 26700 1246 0 0
x_make_qstorno_1200_2000 5367 5367 0 0.00 2 77314 3372 0 0
x_make_qstorno_2100_3300 458465 458465 0 0.00 0 91469 171 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 17-08-2021 07:39:17 to 17-08-2021 07:39:21.

Memory consumption, metadata cache, attachments activity

Fields:
  page cache memo used            = page cache total size, bytes:
  memo used, total                = total of mon$memory_usage.mon$memory_used for database level (mon$stat_group = 0);
  memo allocated, total           = the same of mon$memory_usage.mon$memory_allocated;
  metadata cache memo used        = metadata cache, bytes;
  metadata cache percent of total = ratio: metadata cache / (metadata cache + page cache);
  total attachments cnt           = total number of attachments, regardless of state;
  active attachments cnt          = number of attachments with mon$state = 1;
  running statements cnt          = number of statements that are operating with data from page cache (mon$state = 1);
  stalled statements cnt          = number of statements that are waiting for client request for fetching ( mon$state = 2);
  memo used by attachments        = total of mon$memory_usage.mon$memory_used for attachment level (mon$stat_group = 1);
  memo used by transactions       = the same of transaction level (mon$stat_group = 2);
  memo used by statements         = the same of statement level (mon$stat_group = 3);


Page cache type: shared, buffers: 1000000 for all connections, with total size: 8192000000                                                                                                                                                                     

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
05:07:08 2 8192000000 8796668944 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:08:09 38154 8192000000 9727717328 10024448000 798921264 8.885 100 88 176 0 828711264 8013632 21776368
05:09:58 25012 8192000000 9766948176 10075557888 844510048 9.345 100 87 174 0 878710480 13282928 20917504
05:11:29 12492 8192000000 9812609568 10131828736 874082640 9.641 100 80 160 0 917528128 25566784 17878704
05:12:49 13074 8192000000 9878468912 10213429248 922934656 10.125 100 78 157 1 983090048 43234864 16920528
05:14:06 8081 8192000000 9936132864 10282143744 970492208 10.592 100 81 161 3 1050707984 64089312 16126464
05:15:16 3970 8192000000 9966541104 10326900736 1006512128 10.942 100 73 146 0 1081864400 60090624 15261648
05:16:21 2509 8192000000 10008127280 10372005888 1028637776 11.155 100 86 169 1 1121604032 75558512 17407744
05:17:25 1739 8192000000 10032813328 10400927744 1052223744 11.382 100 88 176 1 1139848704 69209056 18415904
05:18:28 1090 8192000000 10039310064 10413113344 1071701152 11.568 100 96 196 2 1155383552 63673104 20009296
05:19:29 4176 8192000000 10036320352 10430734336 1074129488 11.591 100 44 87 0 1132552320 50143088 8279744
05:20:38 10133 8192000000 9996973648 10396037120 1085017648 11.695 100 45 91 2 1120275248 25595856 9661744
05:21:49 10654 8192000000 10004958240 10410098688 1086713984 11.711 100 25 49 1 1121078880 29278192 5086704
05:23:00 60541 8192000000 10000074800 10408484864 1096698768 11.806 100 28 56 0 1112024528 9499744 5826016
05:25:01 6679 8192000000 10012717312 10418135040 1102034928 11.857 100 43 86 1 1125185968 13255968 9895072
05:26:11 17065 8192000000 10005806720 10418135040 1067717824 11.530 97 21 42 0 1096222976 24454944 4050208
05:27:32 7899 8192000000 9983333536 10388172800 1040620944 11.271 98 27 54 1 1089567472 43213136 5733392
05:28:41 29064 8192000000 9945083344 10345467904 984301632 10.726 94 27 54 0 1010826592 21004624 5520336
05:30:11 6308 8192000000 9945062320 10326790144 969897168 10.586 94 51 103 0 1000011488 19273920 10840400
05:31:19 4888 8192000000 9940873072 10318479360 1005680848 10.934 99 50 98 2 1042636592 26392320 10563424
05:32:25 61552 8192000000 9950181600 10339205120 1031009648 11.178 99 44 87 5 1062443056 22185888 9247520
05:34:31 5630 8192000000 9976944448 10365693952 1057943088 11.437 100 61 120 2 1084397712 13383760 13070864
05:35:41 7685 8192000000 9985797296 10384846848 1062785600 11.483 100 31 62 3 1092958272 23152368 7020304
05:36:49 8189 8192000000 9995695088 10403258368 1074692560 11.597 100 38 74 1 1103790240 20825200 8272480
05:37:59 61666 8192000000 10032143824 10438299648 1102799392 11.864 100 36 72 3 1130259072 19668736 7790944
05:40:07 39869 8192000000 10065134096 10484506624 1119495888 12.022 100 35 71 4 1184742992 57998800 7248304
05:41:50 25721 8192000000 10116914688 10530189312 1132230992 12.142 100 41 82 1 1217996304 77186032 8579280
05:43:24 14615 8192000000 10149329856 10557464576 1143818048 12.251 100 56 109 0 1240181824 84195312 12168464
05:44:45 28393 8192000000 10169834464 10600583168 1154771776 12.354 100 37 72 4 1284185152 121128736 8284640
05:46:21 12932 8192000000 10219769456 10646532096 1156183216 12.367 100 50 100 1 1331855600 163757600 11914784
05:47:50 5462 8192000000 10252467824 10662866944 1163881696 12.440 100 72 142 1 1359747824 179803040 16063088
05:49:03 3915 8192000000 10286944992 10692562944 1166175456 12.461 100 81 162 0 1380226912 195404336 18647120
05:50:10 1573 8192000000 10282775920 10684690432 1145291648 12.265 99 74 145 2 1381326512 219098576 16936288
05:51:18 1661 8192000000 10267319104 10662313984 1135295552 12.171 98 89 178 0 1362533632 206673984 20564096
05:52:27 4564 8192000000 10325596560 10739621888 1158439488 12.389 99 71 139 0 1430253616 256063456 15750672
05:53:37 2392 8192000000 10362459152 10765049856 1159765504 12.401 100 81 162 1 1463013936 284157920 19090512
05:54:45 953 8192000000 10413936944 10814861312 1163081424 12.432 100 90 184 2 1512411520 327079136 22250960
05:55:52 2273 8192000000 10430971792 10837909504 1175199344 12.545 100 75 150 0 1527236432 334305680 17731408
05:57:01 10648 8192000000 10404145488 10851418112 1179648592 12.587 100 45 90 0 1508953824 317277968 12027264
05:58:15 6199 8192000000 10439226304 10867998720 1152032880 12.329 100 38 74 2 1540223552 378183552 10007120
05:59:27 9887 8192000000 10480253376 10915860480 1161344400 12.416 100 42 84 0 1575503040 402771232 11387408
06:00:51 14762 8192000000 10443228480 10886299648 1160289104 12.406 100 41 82 0 1554929296 384199728 10440464
06:02:13 9043 8192000000 10413380304 10881245184 1168677760 12.484 100 41 82 0 1557064944 377841344 10545840
06:03:33 29304 8192000000 10461065216 10936623104 1160696160 12.410 100 31 62 1 1540381168 371239936 8445072
06:05:03 22847 8192000000 10400056208 10885971968 1111719968 11.949 98 21 41 0 1495803872 378008704 6075200
06:07:31 49574 8192000000 10072408256 10546683904 1129549680 12.117 99 16 31 0 1169917648 37232784 3135184
06:10:06 38655 8192000000 10114460880 10573754368 1135701376 12.175 100 35 71 0 1201755856 58800384 7254096
06:11:49 97556 8192000000 10074571776 10523054080 1124879920 12.073 99 28 56 0 1179655392 48581760 6193712
06:14:48 33575 8192000000 10130981424 10587095040 1142336816 12.238 100 36 73 0 1210445120 60305888 7802416
06:16:28 45904 8192000000 10102984176 10613755904 1147345264 12.285 100 12 24 0 1216465808 66621808 2498736
06:18:18 61316 8192000000 10104229136 10568093696 1149897456 12.309 100 11 22 0 1196801440 44246080 2657904
06:20:21 57392 8192000000 10101901904 10571755520 1144501776 12.258 100 21 42 3 1216853520 67049376 5302368
06:22:21 66641 8192000000 10095017216 10594848768 1146010352 12.272 100 15 30 1 1215237504 65967984 3259168
06:24:38 43831 8192000000 10120075504 10579177472 1140318368 12.219 99 14 28 0 1206421904 63036688 3066848
06:26:25 63254 8192000000 10129255168 10595418112 1108269648 11.916 96 19 38 0 1183716848 71100512 4346688
06:28:30 60486 8192000000 10106329744 10599751680 1148608608 12.296 100 26 52 2 1216859168 63070960 5179600
06:30:31 26256 8192000000 10094696576 10540650496 1146069168 12.273 100 29 58 1 1187613584 36040416 5504000
06:31:58 27188 8192000000 10125729616 10584453120 1149348368 12.303 100 45 88 2 1225929456 67563168 9017920
06:33:30 35734 8192000000 10068981872 10508607488 1090731568 11.750 96 28 56 2 1138318448 42138816 5448064
06:35:06 62109 8192000000 10078471040 10512035840 1068308592 11.536 94 19 38 2 1110784832 38690928 3785312
06:37:11 145570 8192000000 10078156960 10534703104 1132591008 12.146 100 36 72 0 1172885552 33081888 7212656
06:40:40 69510 8192000000 10084599552 10574147584 1113203216 11.963 97 5 10 0 1167183584 53105392 874976
06:43:22 48451 8192000000 10085096000 10532429824 1095884880 11.799 96 14 28 0 1145546832 47035696 2626256
06:45:21 92092 8192000000 10087541312 10553139200 1088215552 11.726 95 17 34 0 1137849456 45919760 3714144
06:48:34 74443 8192000000 10114456592 10555289600 1112209952 11.953 98 24 48 0 1170751376 52799088 5742336
06:50:49 97282 8192000000 10070956400 10599182336 1114432896 11.974 99 15 28 2 1207777952 90691520 2653536
06:53:31 98925 8192000000 10077612304 10523439104 1120079904 12.028 98 15 30 1 1153707152 30325680 3301568
06:56:14 85217 8192000000 10053281728 10515402752 1123764976 12.063 99 24 48 0 1163334544 34464736 5104832
06:58:41 48488 8192000000 10086511776 10545238016 1135261808 12.171 100 7 14 0 1190751248 53632528 1856912
07:01:06 54791 8192000000 10081779600 10592919552 1134570736 12.164 100 14 28 0 1209881056 71854800 3455520
07:03:07 57731 8192000000 10127047440 10588667904 1122754240 12.053 98 33 66 0 1213729200 83958832 7016128
07:05:10 58948 8192000000 10079189840 10572341248 1125488096 12.079 99 19 38 0 1239280096 109539376 4252624
07:07:10 59693 8192000000 10149247792 10602360832 1126505904 12.088 98 30 60 0 1212125248 79628832 5990512
07:09:15 45646 8192000000 10090003328 10518048768 1078320752 11.631 94 24 49 2 1112587216 29813744 4452720
07:11:07 44518 8192000000 10125609536 10607353856 1143773376 12.251 100 35 70 0 1240504096 90014480 6716240
07:12:59 141122 8192000000 10084956800 10562121728 1118192720 12.010 97 16 32 0 1181552032 59860128 3499184
07:16:21 52969 8192000000 10064997024 10541355008 1131353888 12.134 99 12 24 0 1170028464 36127152 2547424
07:19:24 63410 8192000000 10059520112 10565439488 1134624432 12.165 99 6 12 0 1177646656 41778704 1243520
07:21:28 48138 8192000000 10083882544 10540761088 1121778352 12.044 98 18 36 0 1163137344 36894320 4464672
07:23:27 75909 8192000000 10114501056 10574770176 1062221312 11.478 93 37 73 1 1126023248 55630352 8171584
07:25:46 43498 8192000000 10145668496 10627072000 1137414048 12.191 100 29 58 1 1258945264 115301152 6230064
07:27:34 83589 8192000000 10098740592 10567995392 1137793120 12.195 100 26 52 1 1184574176 41426880 5354176
07:30:00 46485 8192000000 10129473056 10576326656 1100043504 11.838 97 41 82 0 1204369872 96283840 8042528
07:31:48 124040 8192000000 10054596208 10540716032 1105092656 11.886 98 7 14 1 1145244272 38859792 1291824
07:34:52 83518 8192000000 10055461648 10524237824 1132045952 12.141 100 9 18 0 1170581216 36653744 1881520

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 17-08-2021 07:39:23 to 17-08-2021 07:39:38.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 169 v_add_invoice_to_stock 335544517
except 15 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 17-08-2021 07:39:39 to 17-08-2021 07:39:41.

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          236820
MON$OLDEST_ACTIVE               239009
MON$OLDEST_SNAPSHOT             239009
MON$NEXT_TRANSACTION            239009
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                       2053984
MON$STAT_ID                     1
MON$BACKUP_STATE                0
MON$CRYPT_PAGE                  0
MON$OWNER                       SYSDBA                                                                                                                                                                                                                                                      
MON$SEC_DATABASE                Default
MON$CRYPT_STATE                 0
MON$GUID                        {58978C10-B735-40F5-8B7D-F7A1956E782B}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             806
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2570 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2570 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2570 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2570 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 ---
:138: 
:139: mscs-test-161.budzdorov.ru    Tue Aug 17 07:37:15 2021
:140:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:141: 
:142: 
:143: mscs-test-161.budzdorov.ru    Tue Aug 17 07:37:30 2021
:144:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:145: 
:146: 
:147: mscs-test-161.budzdorov.ru    Tue Aug 17 07:37:30 2021
:148:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:149: 
:150: 
:151: mscs-test-161.budzdorov.ru    Tue Aug 17 07:37:30 2021
:152:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:153: 
:154: 
:155: mscs-test-161.budzdorov.ru    Tue Aug 17 07:37:30 2021
:156:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:157: 
:158: 
:159: mscs-test-161.budzdorov.ru    Tue Aug 17 07:37:30 2021
:160:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:161: 
:162: 
:163: mscs-test-161.budzdorov.ru    Tue Aug 17 07:37:30 2021
:164:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:165: 
--- end of diff output ---

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

17.08.21 07:39:42. 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-17 07:39:44 No severe PSQL-related problems occured
17.08.21 07:39:45. End of report.