Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 09.09.2021 07:51
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 16078280 kB
MemAvailable 30902764 kB
Buffers 0 kB
Cached 15646436 kB
SwapCached 460 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-09-09 07:49:12
DTS_END                         2021-09-09 07:49:12.2000
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_SUPPLIER_INVOICE(39:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/59418
TRN_ID                          238431
ATT_ID                          623
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: 9194.79 26765 1103375
customer order: creation 168.77 377 20252
customer order: refuse 14.45 325 1734
order to supplier: creation 46.47 1419 5576
order to supplier: removal 3.33 109715 400
invoice (draft): creation 46.62 3483 5594
invoice (draft): removal 52.93 928 6351
invoice accept: apply 41.74 18457 5009
invoice accept: cancel 47.70 820 5724
customer reserve: creation 3875.48 182 465057
customer reserve: removal 4593.20 62 551184
realization accept: apply 84.73 174 10167
realization accept: cancel 90.39 119 10847
payment from customer: creation 58.06 144 6967
payment from customer: removal 6.10 82 732
payment to supplier: creation 49.55 134 5946
payment to supplier: removal 3.75 67 450
service: total inventory turnovers 4.17 3132 500
service: total monetary turnovers 6.39 428 767
service: aggregate perf. data 0.95 15246 114
service: refresh index statistics 0.03 380014 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 09-09-2021 07:51:40 to 09-09-2021 07:51:43.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 5750
TEST_TIME 2 4374
TEST_TIME 3 3776
TEST_TIME 4 4614
TEST_TIME 5 4993
TEST_TIME 6 5510
TEST_TIME 7 5378
TEST_TIME 8 5697
TEST_TIME 9 5414
TEST_TIME 10 5606
TEST_TIME 11 5955
TEST_TIME 12 6188
TEST_TIME 13 6368
TEST_TIME 14 6797
TEST_TIME 15 7095
TEST_TIME 16 7518
TEST_TIME 17 7736
TEST_TIME 18 8467
TEST_TIME 19 8992
TEST_TIME 20 8984
TEST_TIME 21 9299
TEST_TIME 22 9442
TEST_TIME 23 9563
TEST_TIME 24 10194
TEST_TIME 25 10432
TEST_TIME 26 11442
TEST_TIME 27 11100
TEST_TIME 28 11036
TEST_TIME 29 10911
TEST_TIME 30 10735
TEST_TIME 31 10688
TEST_TIME 32 10667
TEST_TIME 33 10504
TEST_TIME 34 10547
TEST_TIME 35 10481
TEST_TIME 36 10498
TEST_TIME 37 10427
TEST_TIME 38 10309
TEST_TIME 39 10253
TEST_TIME 40 10122
TEST_TIME 41 10158
TEST_TIME 42 10028
TEST_TIME 43 9968
TEST_TIME 44 9971
TEST_TIME 45 10077
TEST_TIME 46 10031
TEST_TIME 47 9967
TEST_TIME 48 9977
TEST_TIME 49 10036
TEST_TIME 50 10047
TEST_TIME 51 10158
TEST_TIME 52 10102
TEST_TIME 53 10109
TEST_TIME 54 10128
TEST_TIME 55 10101
TEST_TIME 56 9926
TEST_TIME 57 9843
TEST_TIME 58 9770
TEST_TIME 59 9779
TEST_TIME 60 9690
TEST_TIME 61 9739
TEST_TIME 62 9720
TEST_TIME 63 9691
TEST_TIME 64 9624
TEST_TIME 65 9640
TEST_TIME 66 9640
TEST_TIME 67 9655
TEST_TIME 68 9589
TEST_TIME 69 9677
TEST_TIME 70 9787
TEST_TIME 71 9779
TEST_TIME 72 9773
TEST_TIME 73 9859
TEST_TIME 74 9942
TEST_TIME 75 9965
TEST_TIME 76 9897
TEST_TIME 77 9807
TEST_TIME 78 9708
TEST_TIME 79 9642
TEST_TIME 80 9628
TEST_TIME 81 9617
TEST_TIME 82 9608
TEST_TIME 83 9587
TEST_TIME 84 9596
TEST_TIME 85 9560
TEST_TIME 86 9531
TEST_TIME 87 9488
TEST_TIME 88 9528
TEST_TIME 89 9514
TEST_TIME 90 9584
TEST_TIME 91 9564
TEST_TIME 92 9559
TEST_TIME 93 9565
TEST_TIME 94 9572
TEST_TIME 95 9482
TEST_TIME 96 9455
TEST_TIME 97 9389
TEST_TIME 98 9380
TEST_TIME 99 9371
TEST_TIME 100 9367
TEST_TIME 101 9341
TEST_TIME 102 9288
TEST_TIME 103 9234
TEST_TIME 104 9292
TEST_TIME 105 9313
TEST_TIME 106 9322
TEST_TIME 107 9302
TEST_TIME 108 9277
TEST_TIME 109 9340
TEST_TIME 110 9354
TEST_TIME 111 9380
TEST_TIME 112 9333
TEST_TIME 113 9342
TEST_TIME 114 9412
TEST_TIME 115 9422
TEST_TIME 116 9415
TEST_TIME 117 9338
TEST_TIME 118 9299
TEST_TIME 119 9276
TEST_TIME 120 9203

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 09-09-2021 07:51:44 to 09-09-2021 07:51:47.

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 1532597 1532597 0 0.00 0 19055 7 0 0
doc_list_biud 1532595 1532595 0 0.00 0 77110 29 0 0
sp_add_invoice_to_stock 5098 5009 89 1.74 38 193193 18458 0 89
sp_cancel_adding_invoice 5724 5724 0 0.00 5 101398 820 0 0
sp_cancel_client_order 1734 1734 0 0.00 5 27619 325 0 0
sp_cancel_customer_reserve 551184 551184 0 0.00 1 23886 63 0 0
sp_cancel_pay_from_customer 732 732 0 0.00 3 9885 82 0 0
sp_cancel_pay_to_supplier 450 450 0 0.00 3 1875 67 0 0
sp_cancel_supplier_invoice 6358 6351 7 0.11 2 77131 929 0 7
sp_cancel_supplier_order 400 400 0 0.00 11 1140929 109715 0 0
sp_cancel_write_off 10847 10847 0 0.00 1 10267 120 0 0
sp_client_order 20252 20252 0 0.00 4 24197 378 0 0
sp_customer_reserve 465057 465057 0 0.00 0 37667 182 0 0
sp_fill_shopping_cart 31408 31408 0 0.00 1 22935 46 0 0
sp_fill_shopping_cart_clo_res 465052 465052 0 0.00 0 20104 16 0 0
sp_get_clo_for_invoice 4999 4999 0 0.00 0 38311 365 0 0
sp_kill_cost_storno 17753 17753 0 0.00 0 10086 56 0 0
sp_kill_qstorno_ret_qs2qd 1119966 1119966 0 0.00 0 77107 34 0 0
sp_kill_qty_storno 591406 591406 0 0.00 0 77110 74 0 0
sp_lock_selected_doc 21014 21014 0 0.00 0 9488 5 0 0
sp_make_cost_storno 28079 28079 0 0.00 0 16719 56 0 0
sp_multiply_rows_for_pdistr 28079 28079 0 0.00 0 18721 35 0 0
sp_multiply_rows_for_qdistr 20252 20252 0 0.00 0 20144 187 0 0
sp_pay_from_customer 6967 6967 0 0.00 4 23041 145 0 0
sp_pay_to_supplier 5946 5946 0 0.00 5 17869 134 0 0
sp_payment_common 12913 12913 0 0.00 1 18297 34 0 0
sp_qd_handle_on_cancel_clo 1734 1734 0 0.00 0 27607 301 0 0
sp_qd_handle_on_invoice_upd_sts 10723 10723 0 0.00 0 38674 347 0 0
sp_qd_handle_on_reserve_upd_sts 10167 10167 0 0.00 0 13698 52 0 0
sp_reserve_write_off 10167 10167 0 0.00 6 16700 175 0 0
sp_supplier_invoice 5594 5594 0 0.00 8 103522 3483 0 0
sp_supplier_order 5576 5576 0 0.00 5 76442 1420 0 0
srv_aggregate_perf_data 114 114 0 0.00 2763 236677 15247 0 0
srv_fill_mon_cache_memory 64 64 0 0.00 1165 117383 45078 0 0
srv_find_qd_qs_mism 1054962 1054962 0 0.00 0 19054 6 0 0
srv_make_invnt_saldo 500 500 0 0.00 30 102937 3133 0 0
srv_make_money_saldo 767 767 0 0.00 1 18669 428 0 0
srv_recalc_idx_stat 4 4 0 0.00 230870 451629 380014 0 0
t$perf-abend:sp_add_invoice_to_stock 89 89 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 7 7 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 5009 5009 0 0.00 0 16768 179 0 0
t$perf-norm:sp_cancel_adding_invoice 464 464 0 0.00 0 11132 114 0 0
t$perf-norm:sp_cancel_client_order 1734 1734 0 0.00 0 7473 20 0 0
t$perf-norm:sp_cancel_customer_reserve 666 666 0 0.00 0 437 6 0 0
t$perf-norm:sp_cancel_pay_from_customer 732 732 0 0.00 0 2751 8 0 0
t$perf-norm:sp_cancel_pay_to_supplier 450 450 0 0.00 0 4970 14 0 0
t$perf-norm:sp_cancel_supplier_invoice 433 433 0 0.00 0 2754 19 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 37000 1349 0 0
t$perf-norm:sp_cancel_write_off 974 974 0 0.00 0 3260 14 0 0
t$perf-norm:sp_client_order 20252 20252 0 0.00 0 8003 17 0 0
t$perf-norm:sp_customer_reserve 1020 1020 0 0.00 0 3055 14 0 0
t$perf-norm:sp_pay_from_customer 6967 6967 0 0.00 0 5400 10 0 0
t$perf-norm:sp_pay_to_supplier 5946 5946 0 0.00 0 7863 12 0 0
t$perf-norm:sp_reserve_write_off 10167 10167 0 0.00 0 6532 10 0 0
t$perf-norm:sp_supplier_invoice 5594 5594 0 0.00 0 8206 22 0 0
t$perf-norm:sp_supplier_order 5576 5576 0 0.00 0 7540 17 0 0
t$perf-norm:srv_aggregate_perf_data 114 114 0 0.00 0 134 2 0 0
t$perf-norm:srv_fill_mon_cache_memory 64 64 0 0.00 0 37 2 0 0
t$perf-norm:srv_make_invnt_saldo 500 500 0 0.00 0 1671 15 0 0
t$perf-norm:srv_make_money_saldo 767 767 0 0.00 0 715 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 79 23 0 0
v_add_invoice_to_stock 10166 9988 178 1.75 0 6390 13 0 178
v_all_customers 36612 36612 0 0.00 0 25 1 0 0
v_all_suppliers 30924 30924 0 0.00 0 24 1 0 0
v_all_wares 40500 40500 0 0.00 0 151 1 0 0
v_cancel_adding_invoice 928 928 0 0.00 0 6343 19 0 0
v_cancel_client_order 3468 3468 0 0.00 0 6400 5 0 0
v_cancel_customer_prepayment 1464 1464 0 0.00 0 5290 11 0 0
v_cancel_customer_reserve 1332 1332 0 0.00 0 2167 6 0 0
v_cancel_payment_to_supplier 900 900 0 0.00 0 1854 9 0 0
v_cancel_supplier_invoice 880 866 14 1.59 0 373 10 0 14
v_cancel_supplier_order 800 800 0 0.00 0 6396 14 0 0
v_cancel_write_off 1948 1948 0 0.00 0 944 13 0 0
v_max_id_clo_ord 5571 5571 0 0.00 0 2193 2 0 0
v_max_id_clo_res 1020 1020 0 0.00 0 4416 22 0 0
v_max_id_ord_sup 5586 5586 0 0.00 0 4302 5 0 0
v_max_non_paid_invoice 1641 1641 0 0.00 0 446 3 0 0
v_max_non_paid_realizn 3758 3758 0 0.00 0 2650 5 0 0
v_min_id_clo_ord 5571 5571 0 0.00 0 4833 5 0 0
v_min_id_clo_res 1020 1020 0 0.00 0 7200 19 0 0
v_min_id_ord_sup 5586 5586 0 0.00 0 16265 13 0 0
v_min_non_paid_invoice 5946 5946 0 0.00 0 17198 21 0 0
v_min_non_paid_realizn 6967 6967 0 0.00 0 17946 30 0 0
v_our_firm 10306 10306 0 0.00 0 18 1 0 0
v_reserve_write_off 20334 20334 0 0.00 0 5655 5 0 0
x_lock_depdocs_on_canc_invoice 11642 11642 0 0.00 0 12471 105 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 9142 324 0 0
x_make_qstorno_1000_1200 5576 5576 0 0.00 2 76418 1344 0 0
x_make_qstorno_1200_2000 5594 5594 0 0.00 5 103457 3307 0 0
x_make_qstorno_2100_3300 465057 465057 0 0.00 0 34477 164 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 09-09-2021 07:51:48 to 09-09-2021 07:51:52.

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:19:16 2 8192000000 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
05:20:17 19780 8192000000 9723320656 10019897344 802824464 8.925 100 83 166 0 830112992 7296864 19991664
05:21:49 15393 8192000000 9760561680 10066325504 837309072 9.273 100 90 180 0 872104400 13393456 21401872
05:23:10 8202 8192000000 9789075024 10101374976 857429840 9.474 100 89 178 0 904373056 26186192 20757024
05:24:21 8222 8192000000 9848211680 10176843776 901145056 9.910 100 80 160 1 960476288 42220608 17110624
05:25:36 6538 8192000000 9886864976 10228305920 936981184 10.263 100 76 152 1 998392880 45487632 15924064
05:26:45 6964 8192000000 9946324816 10298355712 973091824 10.617 100 75 150 1 1063072240 74661024 15319392
05:27:54 3181 8192000000 9987267920 10339241984 1007695664 10.953 100 84 167 0 1098612384 74348576 16568144
05:28:58 5456 8192000000 9994659792 10364530688 1029522928 11.164 100 74 148 1 1110521424 65799216 15199280
05:30:06 1621 8192000000 10037344784 10407358464 1060843744 11.465 100 92 186 4 1152748768 73225232 18679792
05:31:08 18650 8192000000 9980732784 10373840896 1069459728 11.547 100 33 64 1 1144404320 68211424 6733168
05:32:28 5307 8192000000 10000599008 10397003776 1082692912 11.673 100 58 112 1 1119796368 24136096 12967360
05:33:34 4679 8192000000 9983877040 10380726272 1082603120 11.672 100 40 80 0 1107856144 15866480 9386544
05:34:39 78011 8192000000 10002413696 10406510592 1088953712 11.733 100 37 74 0 1111916096 15036976 7925408
05:36:58 4600 8192000000 10002306576 10409168896 1095761952 11.797 100 39 78 0 1123000144 18837568 8400624
05:38:04 12789 8192000000 10009427216 10418966528 1093495488 11.776 100 31 59 0 1121514112 22363168 5655456
05:39:17 14080 8192000000 9989823344 10382368768 1039839568 11.263 96 28 56 0 1069189072 23036256 6313248
05:40:32 31745 8192000000 9976680496 10378891264 1050672496 11.367 98 22 45 1 1085682192 30948096 4061600
05:42:04 9747 8192000000 9967814208 10361499648 998959312 10.868 96 35 69 1 1044262256 38233136 7069808
05:43:18 7666 8192000000 9954254672 10341855232 1013377664 11.008 99 48 94 0 1053875344 28793632 11704048
05:44:30 66904 8192000000 9948304144 10345259008 1022280464 11.094 98 36 72 1 1060061344 29999920 7780960
05:46:39 3411 8192000000 9981900624 10379321344 1058950336 11.446 100 66 135 0 1098553472 24472528 15130608
05:47:43 10980 8192000000 9973748496 10371358720 1065675152 11.511 100 21 41 2 1093149264 23155040 4319072
05:48:55 14174 8192000000 9991084048 10392776704 1071156688 11.563 100 21 42 0 1096010848 20227056 4627104
05:50:22 117382 8192000000 10059777008 10479886336 1092340608 11.765 100 43 86 1 1161142976 59339696 9462672
05:53:22 13306 8192000000 10099681792 10518933504 1112703328 11.958 100 39 78 0 1207139008 86179712 8255968
05:54:38 20933 8192000000 10162275776 10593705984 1124272000 12.067 100 49 97 1 1278736912 144393488 10071424
05:56:05 15045 8192000000 10180893872 10593665024 1144547184 12.258 100 49 96 1 1292756096 137230656 10978256
05:57:25 37229 8192000000 10216661440 10637377536 1155538288 12.361 100 40 79 1 1319941616 155477760 8925568
05:59:07 4938 8192000000 10283053696 10692214784 1171181776 12.508 100 63 125 0 1386826016 201366096 14278144
06:00:19 1164 8192000000 10282336672 10680606720 1170608864 12.503 99 88 176 0 1383474352 192692288 20173200
06:01:26 5171 8192000000 10347811744 10764906496 1182424960 12.613 100 76 151 1 1446140144 246437792 17277392
06:02:48 2315 8192000000 10352589904 10755088384 1155862096 12.364 98 84 163 2 1443863552 268844848 19156608
06:03:54 2507 8192000000 10380919808 10779586560 1166461920 12.464 99 93 181 1 1471990080 286076400 19451760
06:05:01 1626 8192000000 10445000336 10847612928 1162419184 12.426 99 89 177 0 1534558848 352032528 20107136
06:06:06 3706 8192000000 10414489440 10832695296 1146262544 12.274 99 72 143 0 1499015024 335846832 16905648
06:07:17 9365 8192000000 10387279408 10828550144 1146628992 12.278 99 46 92 1 1497488576 339034640 11824944
06:08:37 10841 8192000000 10366396784 10815750144 1164192400 12.443 100 36 71 0 1475391168 302048336 9150432
06:09:51 8298 8192000000 10376282160 10821656576 1136895936 12.186 99 45 90 1 1480573488 332347440 11330112
06:11:00 14713 8192000000 10350475824 10783117312 1131358144 12.134 99 37 73 0 1446041680 305824240 8859296
06:12:20 15370 8192000000 10349621520 10799955968 1113955088 11.970 98 33 66 1 1455067104 332629344 8482672
06:13:38 24494 8192000000 10279703888 10732077056 1128296144 12.105 100 23 46 1 1388505792 254391936 5817712
06:15:37 17323 8192000000 10056530864 10479083520 1122934560 12.055 99 45 87 3 1148848768 15244928 10669280
06:16:54 51461 8192000000 10120733760 10549882880 1126539984 12.089 100 49 98 0 1208989152 71189808 11259360
06:18:49 67771 8192000000 10108098480 10558373888 1138847632 12.205 100 26 52 1 1189441920 45062048 5532240
06:21:16 62599 8192000000 10101348464 10556497920 1107801392 11.912 98 22 44 1 1202900320 90212176 4886752
06:23:25 45043 8192000000 10091351216 10592092160 1115558064 11.985 99 20 40 0 1200554176 80626448 4369664
06:25:15 32867 8192000000 10060457408 10506493952 1117433520 12.003 99 11 23 0 1166602064 46739680 2428864
06:26:48 29742 8192000000 10062143616 10558459904 1124264064 12.067 100 9 18 0 1184958240 58780528 1913648
06:29:18 42130 8192000000 10089920768 10540531712 1127019264 12.093 99 16 33 1 1176192336 46148912 3024160
06:31:01 43778 8192000000 10075683712 10533642240 1132030224 12.140 99 21 42 0 1189277664 52546240 4701200
06:32:52 53559 8192000000 10104022656 10582937600 1139350832 12.209 100 19 38 2 1183811424 40026576 4434016
06:35:00 30182 8192000000 10109928656 10559459328 1150061360 12.310 100 21 42 0 1200697088 46210496 4425232
06:36:31 48451 8192000000 10110087056 10557849600 1111069504 11.943 96 24 48 0 1179173792 63085296 5018992
06:38:24 76846 8192000000 10118951712 10577764352 1119084464 12.018 97 17 34 0 1165317824 42902192 3331168
06:40:44 41996 8192000000 10085571744 10534432768 1149544768 12.305 100 20 40 0 1204062960 50400080 4118112
06:42:28 32731 8192000000 10132182800 10577170432 1153413776 12.342 100 35 70 0 1208360736 48029824 6917136
06:44:04 29463 8192000000 10105837024 10545909760 1104473888 11.880 97 31 62 2 1170174336 59422032 6278416
06:45:40 79518 8192000000 10111457664 10547879936 1108229936 11.916 97 37 75 2 1204872656 88052592 8590128
06:48:00 73183 8192000000 10121594016 10618212352 1128283728 12.105 99 23 46 0 1246681120 113320832 5076560
06:50:18 62464 8192000000 10068584992 10513420288 1107371312 11.908 98 11 22 0 1165705120 55969184 2364624
06:52:24 67676 8192000000 10063040496 10506133504 1066595408 11.520 95 16 32 0 1106379952 36798432 2986112
06:54:32 36341 8192000000 10068490416 10512633856 1087443136 11.718 96 23 47 1 1155844960 63709760 4692064
06:56:18 56270 8192000000 10083809168 10513391616 1067724016 11.530 95 33 64 1 1121669632 47416256 6529360
06:58:24 58305 8192000000 10076870272 10538074112 1094442752 11.785 99 15 30 1 1215585968 117537728 3605488
07:00:24 92555 8192000000 10131759616 10576719872 1103013824 11.866 98 28 56 1 1147918496 39630336 5274336
07:02:58 28790 8192000000 10109407632 10569736192 1135841392 12.176 100 28 57 1 1193520944 51866496 5813056
07:04:30 90537 8192000000 10031185104 10492346368 1042345344 11.287 92 13 25 0 1085558640 40657264 2556032
07:07:26 53809 8192000000 10061439536 10512089088 1114324128 11.973 99 4 8 0 1154853168 39931280 597760
07:09:23 48029 8192000000 10109826784 10608173056 1129048816 12.112 100 8 16 0 1224431424 93736288 1646320
07:11:20 60408 8192000000 10082458480 10566152192 1120309472 12.030 99 12 24 0 1163637344 40952384 2375488
07:13:27 91198 8192000000 10083933952 10521837568 1094239072 11.783 96 16 32 1 1120741328 23267056 3235200
07:15:58 45892 8192000000 10116075856 10553118720 1123989328 12.065 99 28 56 0 1194535488 64807552 5738608
07:17:54 51183 8192000000 10137424560 10592714752 1147540320 12.286 100 22 44 1 1229409520 77591152 4278048
07:19:50 41231 8192000000 10083932256 10513866752 1111062768 11.942 97 25 50 2 1143613232 27564192 4986272
07:21:32 73520 8192000000 10110080672 10554736640 1096664320 11.806 96 33 66 0 1172750544 69490720 6595504
07:23:47 96202 8192000000 10058384480 10505355264 1131548864 12.136 100 12 24 0 1167770832 33507632 2714336
07:26:24 26525 8192000000 10114781120 10546593792 1146438256 12.276 100 54 108 1 1206756144 49364544 10953344
07:27:51 65427 8192000000 10090304656 10575241216 1141520256 12.230 100 12 24 0 1199220064 54842352 2857456
07:29:56 64605 8192000000 10081956800 10553962496 1136689968 12.184 99 14 28 0 1189027680 49690160 2647552
07:32:05 52013 8192000000 10148822544 10604515328 1152527296 12.333 100 12 24 0 1235673488 80662576 2483616
07:34:09 65012 8192000000 10066297328 10513035264 1112669152 11.958 97 20 40 1 1142896448 26511232 3716064
07:36:14 41767 8192000000 10073175344 10520793088 1115910864 11.988 98 17 33 2 1155229248 36257280 3061104
07:37:57 38540 8192000000 10126488912 10579763200 1120034016 12.027 99 55 110 0 1208696048 77157008 11505024
07:39:56 64662 8192000000 10033569696 10475806720 1058586256 11.443 95 17 34 0 1118105680 55980592 3538832
07:42:08 20461 8192000000 10085329072 10506821632 1090876112 11.751 99 42 84 2 1154840032 55485696 8478224
07:43:32 107312 8192000000 10074999344 10565890048 1098307472 11.822 99 10 20 0 1152766416 52535088 1923856
07:46:25 117170 8192000000 10021898928 10483511296 1082703056 11.673 97 17 34 1 1106390432 20299680 3387696

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 09-09-2021 07:51:54 to 09-09-2021 07:52:09.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 89 v_add_invoice_to_stock 335544517
except 7 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:02, from 09-09-2021 07:52:10 to 09-09-2021 07:52:12.

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          237848
MON$OLDEST_ACTIVE               238537
MON$OLDEST_SNAPSHOT             238537
MON$NEXT_TRANSACTION            238537
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                       2046496
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             814
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2586 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2586 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2586 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2586 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 ---
:6: 
:7: mscs-test-161.budzdorov.ru    Thu Sep  9 07:49:12 2021
:8:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:9: 
:10: 
:11: mscs-test-161.budzdorov.ru    Thu Sep  9 07:49:21 2021
:12:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:13: 
:14: 
:15: mscs-test-161.budzdorov.ru    Thu Sep  9 07:49:35 2021
:16:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:17: 
--- end of diff output ---

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

09.09.21 07:52:13. 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-09-09 07:52:15 No severe PSQL-related problems occured
09.09.21 07:52:16. End of report.