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

Hardware and OS info

Command: hostnamectl
Static hostname mscs-test-161.budzdorov.ru
Icon name computer-server
Chassis server
Machine ID aeee285d2cca40bf84c40a748f599e77
Boot ID 2a7b53d1c5ed44b9bb2712fffac5d8c6
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-11-11 18:28
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 4794.33
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 16536320 kB
MemAvailable 31459660 kB
Buffers 0 kB
Cached 15125600 kB
SwapCached 0 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-12-10 10:37:09
DTS_END                         2021-12-10 10:37:09.3700
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CANCEL_WRITE_OFF(41:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/53462
TRN_ID                          224707
ATT_ID                          596
EXC_UNIT                        2


Test workload details

SETTING_NAME SETTING_VALUE
WORKING_MODE SMALL_03
C_WARES_MAX_ID 400
C_CUSTOMER_DOC_MAX_ROWS 10
C_SUPPLIER_DOC_MAX_ROWS 50
C_CUSTOMER_DOC_MAX_QTY 15
C_SUPPLIER_DOC_MAX_QTY 50
C_NUMBER_OF_AGENTS 50

Indexes for heavy-loaded tables

TAB_NAME IDX_NAME IDX_KEY
XQD_1000_1200 XQD_1000_1200_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_ASC WORKER_ID,DOC_ID
XQD_1000_3300 XQD_1000_3300_WKR_DOC_DEC WORKER_ID,DOC_ID
XQD_1200_2000 XQD_1200_2000_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2000_3300 XQD_2000_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_2100_3300 XQD_2100_3300_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID
XQD_3300_3400 XQD_3300_3400_WA_SO_RO_WKR_SND WARE_ID,SND_OPTYPE_ID,RCV_OPTYPE_ID,WORKER_ID,SND_ID

Performance in TOTAL

ACTION AVG_TIMES_PER_MINUTE AVG_ELAPSED_MS SUCCESSFUL_TIMES_DONE
*** OVERALL *** for 120 minutes: 9069.23 22600 1088308
customer order: creation 163.41 380 19609
customer order: refuse 14.15 425 1698
order to supplier: creation 43.87 1615 5264
order to supplier: removal 3.53 108038 423
invoice (draft): creation 43.93 3862 5271
invoice (draft): removal 51.29 945 6155
invoice accept: apply 40.78 20209 4893
invoice accept: cancel 46.07 953 5528
customer reserve: creation 3833.09 194 459971
customer reserve: removal 4539.29 65 544715
realization accept: apply 81.75 225 9810
realization accept: cancel 85.81 141 10297
payment from customer: creation 55.97 131 6716
payment from customer: removal 5.80 134 696
payment to supplier: creation 46.54 157 5585
payment to supplier: removal 3.27 97 392
service: total inventory turnovers 3.83 3989 459
service: total monetary turnovers 5.99 529 719
service: aggregate perf. data 0.86 16259 103
service: refresh index statistics 0.03 293659 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:03, from 10-12-2021 10:38:20 to 10-12-2021 10:38:23.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 4331
TEST_TIME 2 3895
TEST_TIME 3 4954
TEST_TIME 4 4335
TEST_TIME 5 4321
TEST_TIME 6 4506
TEST_TIME 7 4907
TEST_TIME 8 4986
TEST_TIME 9 5159
TEST_TIME 10 5267
TEST_TIME 11 5342
TEST_TIME 12 5696
TEST_TIME 13 5534
TEST_TIME 14 5656
TEST_TIME 15 5623
TEST_TIME 16 5857
TEST_TIME 17 6383
TEST_TIME 18 6694
TEST_TIME 19 6955
TEST_TIME 20 7034
TEST_TIME 21 7487
TEST_TIME 22 8003
TEST_TIME 23 8121
TEST_TIME 24 8171
TEST_TIME 25 8202
TEST_TIME 26 8488
TEST_TIME 27 8658
TEST_TIME 28 9262
TEST_TIME 29 9938
TEST_TIME 30 10451
TEST_TIME 31 10321
TEST_TIME 32 10279
TEST_TIME 33 10258
TEST_TIME 34 10160
TEST_TIME 35 10028
TEST_TIME 36 9974
TEST_TIME 37 10060
TEST_TIME 38 9860
TEST_TIME 39 9794
TEST_TIME 40 9764
TEST_TIME 41 9892
TEST_TIME 42 9820
TEST_TIME 43 9827
TEST_TIME 44 9721
TEST_TIME 45 9603
TEST_TIME 46 9567
TEST_TIME 47 9424
TEST_TIME 48 9349
TEST_TIME 49 9367
TEST_TIME 50 9567
TEST_TIME 51 9580
TEST_TIME 52 9691
TEST_TIME 53 9697
TEST_TIME 54 9650
TEST_TIME 55 9644
TEST_TIME 56 9639
TEST_TIME 57 9657
TEST_TIME 58 9701
TEST_TIME 59 9850
TEST_TIME 60 9782
TEST_TIME 61 9840
TEST_TIME 62 9925
TEST_TIME 63 9815
TEST_TIME 64 9733
TEST_TIME 65 9706
TEST_TIME 66 9651
TEST_TIME 67 9617
TEST_TIME 68 9512
TEST_TIME 69 9489
TEST_TIME 70 9407
TEST_TIME 71 9430
TEST_TIME 72 9447
TEST_TIME 73 9480
TEST_TIME 74 9489
TEST_TIME 75 9477
TEST_TIME 76 9523
TEST_TIME 77 9564
TEST_TIME 78 9533
TEST_TIME 79 9654
TEST_TIME 80 9646
TEST_TIME 81 9623
TEST_TIME 82 9565
TEST_TIME 83 9625
TEST_TIME 84 9704
TEST_TIME 85 9678
TEST_TIME 86 9594
TEST_TIME 87 9577
TEST_TIME 88 9533
TEST_TIME 89 9519
TEST_TIME 90 9459
TEST_TIME 91 9430
TEST_TIME 92 9391
TEST_TIME 93 9305
TEST_TIME 94 9290
TEST_TIME 95 9276
TEST_TIME 96 9280
TEST_TIME 97 9279
TEST_TIME 98 9255
TEST_TIME 99 9289
TEST_TIME 100 9330
TEST_TIME 101 9305
TEST_TIME 102 9263
TEST_TIME 103 9309
TEST_TIME 104 9398
TEST_TIME 105 9389
TEST_TIME 106 9360
TEST_TIME 107 9456
TEST_TIME 108 9455
TEST_TIME 109 9430
TEST_TIME 110 9363
TEST_TIME 111 9324
TEST_TIME 112 9297
TEST_TIME 113 9279
TEST_TIME 114 9246
TEST_TIME 115 9217
TEST_TIME 116 9172
TEST_TIME 117 9172
TEST_TIME 118 9170
TEST_TIME 119 9140
TEST_TIME 120 9071

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 10-12-2021 10:38:24 to 10-12-2021 10:38:27.

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 1512495 1512495 0 0.00 0 19899 8 0 0
doc_list_biud 1512495 1512495 0 0.00 0 35776 30 0 0
sp_add_invoice_to_stock 4969 4893 76 1.52 21 208096 20210 0 76
sp_cancel_adding_invoice 5528 5528 0 0.00 6 75216 954 0 0
sp_cancel_client_order 1698 1698 0 0.00 5 21100 425 0 0
sp_cancel_customer_reserve 544715 544715 0 0.00 1 35780 65 0 0
sp_cancel_pay_from_customer 696 696 0 0.00 3 14312 134 0 0
sp_cancel_pay_to_supplier 392 392 0 0.00 3 2661 97 0 0
sp_cancel_supplier_invoice 6161 6155 6 0.09 2 35303 946 0 6
sp_cancel_supplier_order 423 423 0 0.00 13 1251239 108039 0 0
sp_cancel_write_off 10297 10297 0 0.00 1 11060 141 0 0
sp_client_order 19609 19609 0 0.00 4 20148 380 0 0
sp_customer_reserve 459971 459971 0 0.00 0 111021 194 0 0
sp_fill_shopping_cart 30143 30143 0 0.00 1 10757 46 0 0
sp_fill_shopping_cart_clo_res 459970 459970 0 0.00 0 12991 15 0 0
sp_get_clo_for_invoice 4892 4892 0 0.00 0 30921 385 0 0
sp_kill_cost_storno 16913 16913 0 0.00 0 19357 71 0 0
sp_kill_qstorno_ret_qs2qd 1106305 1106305 0 0.00 0 35753 36 0 0
sp_kill_qty_storno 583518 583518 0 0.00 0 35776 78 0 0
sp_lock_selected_doc 20276 20276 0 0.00 0 9672 8 0 0
sp_make_cost_storno 27003 27003 0 0.00 0 18977 71 0 0
sp_multiply_rows_for_pdistr 27003 27003 0 0.00 0 11367 40 0 0
sp_multiply_rows_for_qdistr 19609 19609 0 0.00 0 19900 192 0 0
sp_pay_from_customer 6716 6716 0 0.00 4 12794 132 0 0
sp_pay_to_supplier 5585 5585 0 0.00 5 25365 157 0 0
sp_payment_common 12301 12301 0 0.00 1 11251 30 0 0
sp_qd_handle_on_cancel_clo 1698 1698 0 0.00 0 21091 382 0 0
sp_qd_handle_on_invoice_upd_sts 10420 10420 0 0.00 0 28549 392 0 0
sp_qd_handle_on_reserve_upd_sts 9810 9810 0 0.00 0 18915 62 0 0
sp_reserve_write_off 9810 9810 0 0.00 7 18997 226 0 0
sp_supplier_invoice 5271 5271 0 0.00 6 86554 3863 0 0
sp_supplier_order 5264 5264 0 0.00 6 43142 1616 0 0
srv_aggregate_perf_data 103 103 0 0.00 2730 153336 16259 0 0
srv_fill_mon_cache_memory 66 66 0 0.00 1015 128391 41516 0 0
srv_find_qd_qs_mism 1041313 1041313 0 0.00 0 14888 6 0 0
srv_make_invnt_saldo 459 459 0 0.00 74 99854 3990 0 0
srv_make_money_saldo 719 719 0 0.00 0 10973 530 0 0
srv_recalc_idx_stat 4 4 0 0.00 203027 459476 293660 0 0
t$perf-abend:sp_add_invoice_to_stock 76 76 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 6 6 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4895 4895 0 0.00 0 17056 198 0 0
t$perf-norm:sp_cancel_adding_invoice 385 385 0 0.00 1 9019 122 0 0
t$perf-norm:sp_cancel_client_order 1698 1698 0 0.00 0 7546 23 0 0
t$perf-norm:sp_cancel_customer_reserve 650 650 0 0.00 0 5119 29 0 0
t$perf-norm:sp_cancel_pay_from_customer 696 696 0 0.00 0 763 5 0 0
t$perf-norm:sp_cancel_pay_to_supplier 392 392 0 0.00 0 609 6 0 0
t$perf-norm:sp_cancel_supplier_invoice 384 384 0 0.00 0 661 13 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 34406 1835 0 0
t$perf-norm:sp_cancel_write_off 946 946 0 0.00 0 9337 18 0 0
t$perf-norm:sp_client_order 19609 19609 0 0.00 0 8586 20 0 0
t$perf-norm:sp_customer_reserve 905 905 0 0.00 0 2403 10 0 0
t$perf-norm:sp_pay_from_customer 6716 6716 0 0.00 0 8663 13 0 0
t$perf-norm:sp_pay_to_supplier 5585 5585 0 0.00 0 6426 10 0 0
t$perf-norm:sp_reserve_write_off 9810 9810 0 0.00 0 12109 13 0 0
t$perf-norm:sp_supplier_invoice 5271 5271 0 0.00 0 4792 22 0 0
t$perf-norm:sp_supplier_order 5264 5264 0 0.00 0 8151 25 0 0
t$perf-norm:srv_aggregate_perf_data 103 103 0 0.00 0 145 7 0 0
t$perf-norm:srv_fill_mon_cache_memory 66 66 0 0.00 0 886 15 0 0
t$perf-norm:srv_make_invnt_saldo 459 459 0 0.00 0 6613 34 0 0
t$perf-norm:srv_make_money_saldo 719 719 0 0.00 0 2857 11 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 2 61 17 0 0
v_add_invoice_to_stock 9936 9784 152 1.52 0 5661 18 0 152
v_all_customers 35600 35600 0 0.00 0 19 1 0 0
v_all_suppliers 29250 29250 0 0.00 0 17 1 0 0
v_all_wares 39218 39218 0 0.00 0 41 1 0 0
v_cancel_adding_invoice 770 770 0 0.00 0 1961 11 0 0
v_cancel_client_order 3396 3396 0 0.00 0 3163 6 0 0
v_cancel_customer_prepayment 1392 1392 0 0.00 0 1531 5 0 0
v_cancel_customer_reserve 1300 1300 0 0.00 0 3650 7 0 0
v_cancel_payment_to_supplier 784 784 0 0.00 0 1070 6 0 0
v_cancel_supplier_invoice 780 768 12 1.53 0 750 17 0 12
v_cancel_supplier_order 846 846 0 0.00 0 2214 6 0 0
v_cancel_write_off 1892 1892 0 0.00 0 3735 19 0 0
v_max_id_clo_ord 5264 5264 0 0.00 0 1663 2 0 0
v_max_id_clo_res 905 905 0 0.00 0 2023 11 0 0
v_max_id_ord_sup 5270 5270 0 0.00 0 5300 5 0 0
v_max_non_paid_invoice 1494 1494 0 0.00 0 537 3 0 0
v_max_non_paid_realizn 3542 3542 0 0.00 0 1891 4 0 0
v_min_id_clo_ord 5264 5264 0 0.00 0 6654 10 0 0
v_min_id_clo_res 905 905 0 0.00 0 4904 13 0 0
v_min_id_ord_sup 5270 5270 0 0.00 0 6664 16 0 0
v_min_non_paid_invoice 5585 5585 0 0.00 0 11246 24 0 0
v_min_non_paid_realizn 6716 6716 0 0.00 0 8079 21 0 0
v_our_firm 9966 9966 0 0.00 0 16 1 0 0
v_reserve_write_off 19620 19620 0 0.00 0 3857 5 0 0
x_lock_depdocs_on_canc_invoice 11299 11299 0 0.00 0 11858 124 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 4159 340 0 0
x_make_qstorno_1000_1200 5264 5264 0 0.00 2 42815 1511 0 0
x_make_qstorno_1200_2000 5271 5271 0 0.00 3 86547 3703 0 0
x_make_qstorno_2100_3300 459971 459971 0 0.00 0 111004 176 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 10-12-2021 10:38:28 to 10-12-2021 10:38:32.

Memory consumption, metadata cache, attachments activity

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


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

measurement_dts measurement duration ms page cache memo used memo used, total memo allocated, total metadata cache metadata cache percent of total total attachments cnt active attachments cnt running statements cnt stalled statements cnt memo used by attachments memo used by transactions memo used by statements
08:07:13 2 8192000000 8798449840 8809664512 4478816 0.054 2 1 2 0 4578208 63616 35776
08:08:13 30957 8192000000 9715872384 10008289280 788430592 8.779 100 81 162 0 815624480 7464160 19729728
08:09:48 32314 8192000000 9763123584 10070949888 835969232 9.259 100 91 182 1 871785568 13775360 22040976
08:11:22 25723 8192000000 9811897392 10132508672 872934256 9.629 100 80 160 1 918613680 27512176 18167248
08:12:51 13640 8192000000 9872438192 10202906624 919528080 10.091 100 83 165 0 979888064 42488096 17871888
08:14:09 8816 8192000000 9931452944 10270011392 954856496 10.439 100 86 172 0 1041483024 68400432 18226096
08:15:20 7989 8192000000 9949011136 10305421312 988150096 10.763 100 73 146 0 1072271744 69073952 15047696
08:16:32 7882 8192000000 9998208656 10363387904 1020696432 11.079 100 70 139 2 1109861872 75550368 13615072
08:17:43 2786 8192000000 10029216048 10397880320 1047584800 11.338 100 76 152 1 1143627808 80810432 15232576
08:18:49 1813 8192000000 10036914592 10408452096 1064299216 11.498 100 96 190 0 1147087824 63300384 19488224
08:19:53 8630 8192000000 10000314864 10382106624 1071895088 11.570 100 71 140 3 1117278448 30395760 14987600
08:21:21 5749 8192000000 9996612320 10383798272 1079477488 11.642 100 43 85 3 1116259648 27894432 8887728
08:22:30 5712 8192000000 9996723312 10392064000 1083724128 11.683 100 37 75 1 1114864560 23836352 7304080
08:23:36 4457 8192000000 10008194848 10405634048 1087693824 11.721 100 31 63 2 1114005888 19741904 6570160
08:24:43 53515 8192000000 10002090208 10396430336 1091345328 11.755 100 54 107 3 1113309520 10178832 11785360
08:26:37 5754 8192000000 10007922464 10403979264 1083652576 11.682 99 33 65 0 1123369936 33089520 6627840
08:27:44 9214 8192000000 9992192816 10402295808 1078099968 11.629 99 29 58 2 1109537200 25425136 6012096
08:28:56 5473 8192000000 9996131440 10390315008 1082746592 11.674 100 33 62 2 1107885744 17961520 7177632
08:30:03 46375 8192000000 9957095888 10365820928 1036168160 11.228 97 26 52 1 1074196752 32171680 5856912
08:31:51 3713 8192000000 9903179312 10271268864 948185616 10.373 92 45 87 3 974798336 16199568 10413152
08:32:57 5877 8192000000 9920908592 10294611968 978251472 10.667 96 52 102 0 1000697680 11138016 11308192
08:34:05 63374 8192000000 9936276272 10327216128 1025205440 11.122 99 42 84 0 1050634144 15312544 10116160
08:36:27 5007 8192000000 9979752432 10366128128 1050566080 11.366 100 48 96 2 1081602288 21297376 9738832
08:37:40 18525 8192000000 9989585104 10372734976 1066016448 11.514 100 55 111 1 1100372976 22324512 12032016
08:39:08 33538 8192000000 10034959200 10430124032 1084414624 11.690 100 55 109 2 1132993360 36080768 12497968
08:40:59 64021 8192000000 10101241856 10496593920 1103746048 11.873 100 45 90 1 1193461616 79924672 9790896
08:43:18 10193 8192000000 10107457552 10512211968 1111456944 11.946 100 43 86 1 1225297184 104310208 9530032
08:44:34 17905 8192000000 10133352288 10540806144 1121127136 12.038 100 46 92 0 1240320752 108576128 10617488
08:46:13 8266 8192000000 10150758048 10559258624 1140835664 12.223 100 54 103 0 1253872864 101082976 11954224
08:47:25 8317 8192000000 10178185136 10604183552 1151250736 12.321 100 54 102 2 1284550032 121958464 11340832
08:48:38 2006 8192000000 10229435296 10634043392 1168869008 12.486 100 76 150 2 1341255568 156329792 16056768
08:49:47 2462 8192000000 10247222288 10653147136 1180390304 12.594 100 63 121 2 1350927664 157340192 13197168
08:50:57 2051 8192000000 10293135456 10703757312 1190483664 12.688 100 69 138 0 1395019024 189653280 14882080
08:52:05 1678 8192000000 10332382032 10734649344 1180524496 12.595 99 75 150 1 1431408896 234942416 15941984
08:53:13 3583 8192000000 10389033136 10801704960 1195158224 12.731 100 88 173 0 1493627696 279084896 19384576
08:54:22 2383 8192000000 10377486000 10799849472 1181834976 12.607 99 75 150 1 1470505296 271207584 17462736
08:55:30 1008 8192000000 10407207648 10808827904 1186194624 12.648 100 93 187 0 1505737520 298207808 21335088
08:56:40 4392 8192000000 10405340736 10807513088 1170033472 12.497 100 82 161 0 1501784784 313363520 18387792
08:57:52 7633 8192000000 10427798480 10831147008 1167966192 12.478 100 78 157 2 1515404752 329119456 18319104
08:59:03 4076 8192000000 10374865376 10819100672 1152428368 12.332 100 63 126 3 1469695760 302797056 14470336
09:00:15 14667 8192000000 10361126416 10782814208 1123663984 12.062 98 40 80 1 1449380128 315110224 10605920
09:01:36 15893 8192000000 10379171168 10803396608 1129334912 12.115 99 34 68 2 1468636512 330605760 8695840
09:02:56 10777 8192000000 10393773632 10841485312 1129183984 12.114 100 35 70 0 1511335104 373138096 9013024
09:04:09 8992 8192000000 10336519136 10790727680 1120819168 12.035 100 22 42 1 1463159680 336625808 5714704
09:05:24 11975 8192000000 10350480256 10807984128 1128001072 12.103 100 23 46 1 1456177664 321693664 6482928
09:06:39 19334 8192000000 10174945440 10638434304 1107758704 11.911 99 12 24 1 1277639056 166445936 3434416
09:08:01 14454 8192000000 10059995136 10475446272 1102036464 11.857 98 57 116 1 1131985088 17028736 12919888
09:09:18 23199 8192000000 10092134592 10543722496 1104546848 11.881 98 13 26 0 1185650736 78219712 2884176
09:10:44 82527 8192000000 10042555040 10471530496 1104900800 11.884 99 30 58 0 1136423520 25636480 5886240
09:13:08 88759 8192000000 10053597424 10530045952 1105725728 11.892 99 26 52 0 1176061824 64916240 5419856
09:15:39 61732 8192000000 10074869184 10504605696 1102463504 11.861 97 32 64 0 1149479888 40475696 6540688
09:17:50 17248 8192000000 10121696432 10543910912 1130726720 12.128 100 58 113 0 1207826560 65674352 11425488
09:19:13 62980 8192000000 10065340912 10518044672 1107738864 11.911 98 23 46 2 1162654224 50269280 4646080
09:21:16 68592 8192000000 10126279120 10566238208 1147063424 12.282 100 19 38 0 1207520976 56408736 4048816
09:23:26 27369 8192000000 10178613648 10639802368 1157978512 12.384 100 18 36 1 1265515408 103551552 3985344
09:24:53 112796 8192000000 10173843344 10668871680 1145670416 12.269 99 23 46 1 1294235312 143401520 5163376
09:28:27 59704 8192000000 10133933920 10607480832 1145862064 12.271 99 10 20 0 1216396048 68320912 2213072
09:30:29 53639 8192000000 10098969776 10544599040 1144235440 12.255 99 20 40 2 1179364672 30349776 4779456
09:32:25 18812 8192000000 10102849168 10539229184 1157392352 12.379 100 33 66 0 1198546000 34033392 7120256
09:33:54 55958 8192000000 10161183392 10629738496 1142514224 12.239 98 23 46 1 1233661840 86212576 4935040
09:35:50 75389 8192000000 10099213824 10563842048 1143264320 12.246 99 21 42 1 1253215392 105263232 4687840
09:38:09 11431 8192000000 10120093216 10548162560 1137420416 12.191 100 32 64 2 1208457232 63899280 7137536
09:39:26 63663 8192000000 10108775632 10574315520 1151676736 12.325 100 12 24 0 1209807440 55934192 2196512
09:41:34 75283 8192000000 10088752144 10561306624 1145108320 12.264 99 20 40 0 1181069168 32014032 3946816
09:43:58 64632 8192000000 10090608336 10560573440 1095286336 11.793 95 13 26 1 1131901760 33926000 2689424
09:46:46 58730 8192000000 10161881232 10608087040 1137527824 12.192 99 16 32 1 1254293168 113017600 3747744
09:49:01 64470 8192000000 10114129776 10597130240 1121664128 12.043 99 15 30 1 1213675296 88856880 3154288
09:51:10 45591 8192000000 10078738640 10530877440 1101119808 11.848 96 11 22 0 1144963728 41495456 2348464
09:53:08 53232 8192000000 10101707696 10581544960 1126664448 12.090 99 20 40 0 1235241168 103867712 4709008
09:55:49 33106 8192000000 10128440096 10565107712 1120157552 12.028 99 38 76 1 1191549472 63749696 7642224
09:57:41 50068 8192000000 10072702976 10540376064 1108254896 11.916 100 23 46 1 1164646400 51760928 4630576
10:00:04 14056 8192000000 10130325808 10583986176 1124823408 12.073 100 53 106 1 1228070480 92415264 10831808
10:01:22 128391 8192000000 10077845712 10533687296 1074803392 11.598 95 14 28 0 1124565296 47004544 2757360
10:04:52 50814 8192000000 10052261008 10482548736 1100694400 11.844 98 24 48 2 1149072336 42934864 5443072
10:06:49 60693 8192000000 10050804160 10513219584 1116612224 11.995 100 10 20 0 1158170912 39562176 1996512
10:08:52 30170 8192000000 10029116416 10470903808 1090772592 11.750 97 21 42 4 1117178112 22749216 3656304
10:10:33 58879 8192000000 10061839440 10519109632 1112579600 11.957 99 13 25 1 1147881968 32763360 2539008
10:12:35 54819 8192000000 10050138848 10519535616 1085858928 11.703 97 18 36 1 1134261984 44350880 4052176
10:14:38 40657 8192000000 10073596144 10503827456 1123497248 12.060 100 30 60 1 1153151280 23240080 6413952
10:16:23 77504 8192000000 10064013616 10528497664 1110655840 11.939 98 16 32 1 1179286336 65644768 2985728
10:18:40 65550 8192000000 10141466384 10575278080 1140812944 12.223 100 30 60 1 1208865152 61180352 6871856
10:20:46 90559 8192000000 10099466976 10574778368 1105945552 11.894 96 19 36 1 1180559712 71057152 3557008
10:23:17 67666 8192000000 10133643072 10634989568 1133468400 12.154 99 26 51 0 1223387584 84278912 5640272
10:25:30 112897 8192000000 10063383776 10498572288 1121601872 12.042 98 13 26 0 1172012160 47910480 2499808
10:28:29 49029 8192000000 10062866272 10525020160 1137168224 12.189 100 22 44 0 1169639488 27724080 4747184
10:30:24 47547 8192000000 10076955552 10558275584 1144787600 12.261 100 10 21 2 1183909744 37152208 1969936
10:32:12 57350 8192000000 10086369520 10542014464 1140494176 12.220 99 17 35 0 1172694208 28648592 3551440
10:34:22 101355 8192000000 10062383680 10520580096 1101536336 11.852 96 10 19 0 1136178064 32682240 1959488
10:37:04 14957 8192000000 9004938928 9241980928 167703872 2.006 15 4 7 0 195997664 27427408 866384

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 10-12-2021 10:38:34 to 10-12-2021 10:38:49.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 76 v_add_invoice_to_stock 335544517
except 6 v_cancel_supplier_invoice 335544517

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

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          225003
MON$OLDEST_ACTIVE               225004
MON$OLDEST_SNAPSHOT             225004
MON$NEXT_TRANSACTION            225004
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                       2047136
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             775
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2680 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2680 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2680 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2680 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 ---
:23: 
:24: mscs-test-161.budzdorov.ru    Fri Dec 10 10:37:26 2021
:25:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:26: 
:27: 
:28: mscs-test-161.budzdorov.ru    Fri Dec 10 10:37:32 2021
:29:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:30: 
--- end of diff output ---

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

10.12.21 10:38:53. 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-12-10 10:38:55 No severe PSQL-related problems occured
10.12.21 10:38:56. End of report.