Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 20.09.2021 10:39
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 15828848 kB
MemAvailable 30843052 kB
Buffers 0 kB
Cached 15895876 kB
SwapCached 696 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-20 10:37:09
DTS_END                         2021-09-20 10:37:09.7340
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SP_CLIENT_ORDER(57:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/34922
TRN_ID                          240238
ATT_ID                          645
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: 9232.26 26677 1107871
customer order: creation 169.63 455 20355
customer order: refuse 14.80 318 1776
order to supplier: creation 45.36 1382 5443
order to supplier: removal 3.53 115136 423
invoice (draft): creation 46.21 3345 5545
invoice (draft): removal 53.16 915 6379
invoice accept: apply 41.58 20057 4989
invoice accept: cancel 47.78 890 5733
customer reserve: creation 3803.08 199 456370
customer reserve: removal 4713.22 69 565586
realization accept: apply 84.25 201 10110
realization accept: cancel 80.73 131 9688
payment from customer: creation 59.62 131 7154
payment from customer: removal 5.62 95 674
payment to supplier: creation 49.21 133 5905
payment to supplier: removal 3.50 60 420
service: total inventory turnovers 4.03 3322 484
service: total monetary turnovers 6.11 504 733
service: aggregate perf. data 0.83 18251 100
service: refresh index statistics 0.03 367947 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 20-09-2021 10:39:32 to 20-09-2021 10:39:34.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 6925
TEST_TIME 2 4299
TEST_TIME 3 4275
TEST_TIME 4 4837
TEST_TIME 5 5532
TEST_TIME 6 5376
TEST_TIME 7 6175
TEST_TIME 8 5653
TEST_TIME 9 5766
TEST_TIME 10 5977
TEST_TIME 11 6821
TEST_TIME 12 7035
TEST_TIME 13 7119
TEST_TIME 14 7406
TEST_TIME 15 7333
TEST_TIME 16 7329
TEST_TIME 17 7349
TEST_TIME 18 7812
TEST_TIME 19 7874
TEST_TIME 20 8403
TEST_TIME 21 8438
TEST_TIME 22 8844
TEST_TIME 23 9180
TEST_TIME 24 9092
TEST_TIME 25 9542
TEST_TIME 26 10120
TEST_TIME 27 11378
TEST_TIME 28 11231
TEST_TIME 29 10943
TEST_TIME 30 10795
TEST_TIME 31 10823
TEST_TIME 32 10667
TEST_TIME 33 10531
TEST_TIME 34 10431
TEST_TIME 35 10489
TEST_TIME 36 10371
TEST_TIME 37 10285
TEST_TIME 38 10307
TEST_TIME 39 10137
TEST_TIME 40 10071
TEST_TIME 41 10063
TEST_TIME 42 9954
TEST_TIME 43 9854
TEST_TIME 44 10044
TEST_TIME 45 10140
TEST_TIME 46 10229
TEST_TIME 47 10180
TEST_TIME 48 10143
TEST_TIME 49 10096
TEST_TIME 50 10288
TEST_TIME 51 10255
TEST_TIME 52 10272
TEST_TIME 53 10264
TEST_TIME 54 10198
TEST_TIME 55 10280
TEST_TIME 56 10284
TEST_TIME 57 10141
TEST_TIME 58 10007
TEST_TIME 59 9966
TEST_TIME 60 9981
TEST_TIME 61 9909
TEST_TIME 62 9831
TEST_TIME 63 9764
TEST_TIME 64 9638
TEST_TIME 65 9623
TEST_TIME 66 9600
TEST_TIME 67 9672
TEST_TIME 68 9756
TEST_TIME 69 9763
TEST_TIME 70 9718
TEST_TIME 71 9755
TEST_TIME 72 9789
TEST_TIME 73 9805
TEST_TIME 74 9750
TEST_TIME 75 9788
TEST_TIME 76 9814
TEST_TIME 77 9822
TEST_TIME 78 9858
TEST_TIME 79 9760
TEST_TIME 80 9706
TEST_TIME 81 9676
TEST_TIME 82 9627
TEST_TIME 83 9569
TEST_TIME 84 9518
TEST_TIME 85 9424
TEST_TIME 86 9416
TEST_TIME 87 9433
TEST_TIME 88 9440
TEST_TIME 89 9464
TEST_TIME 90 9504
TEST_TIME 91 9525
TEST_TIME 92 9527
TEST_TIME 93 9532
TEST_TIME 94 9534
TEST_TIME 95 9517
TEST_TIME 96 9545
TEST_TIME 97 9528
TEST_TIME 98 9502
TEST_TIME 99 9502
TEST_TIME 100 9428
TEST_TIME 101 9387
TEST_TIME 102 9335
TEST_TIME 103 9331
TEST_TIME 104 9289
TEST_TIME 105 9267
TEST_TIME 106 9294
TEST_TIME 107 9251
TEST_TIME 108 9268
TEST_TIME 109 9271
TEST_TIME 110 9322
TEST_TIME 111 9409
TEST_TIME 112 9430
TEST_TIME 113 9388
TEST_TIME 114 9485
TEST_TIME 115 9473
TEST_TIME 116 9442
TEST_TIME 117 9372
TEST_TIME 118 9358
TEST_TIME 119 9320
TEST_TIME 120 9243

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:04, from 20-09-2021 10:39:35 to 20-09-2021 10:39:39.

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 1531481 1531481 0 0.00 0 14551 8 0 0
doc_list_biud 1531481 1531481 0 0.00 0 57942 32 0 0
sp_add_invoice_to_stock 5109 4989 120 2.34 43 179252 20057 0 120
sp_cancel_adding_invoice 5733 5733 0 0.00 3 67370 890 0 0
sp_cancel_client_order 1776 1776 0 0.00 4 8060 318 0 0
sp_cancel_customer_reserve 565586 565586 0 0.00 1 57943 69 0 0
sp_cancel_pay_from_customer 674 674 0 0.00 3 4107 95 0 0
sp_cancel_pay_to_supplier 420 420 0 0.00 3 2686 60 0 0
sp_cancel_supplier_invoice 6390 6379 11 0.17 1 33895 916 0 11
sp_cancel_supplier_order 423 423 0 0.00 11 1122319 115136 0 0
sp_cancel_write_off 9688 9688 0 0.00 1 12168 132 0 0
sp_client_order 20355 20355 0 0.00 4 19034 456 0 0
sp_customer_reserve 456370 456370 0 0.00 0 113057 200 0 0
sp_fill_shopping_cart 31339 31339 0 0.00 1 20781 47 0 0
sp_fill_shopping_cart_clo_res 456354 456354 0 0.00 0 34602 17 0 0
sp_get_clo_for_invoice 4973 4973 0 0.00 0 41012 403 0 0
sp_kill_cost_storno 16515 16515 0 0.00 0 11010 59 0 0
sp_kill_qstorno_ret_qs2qd 1147662 1147662 0 0.00 0 57931 37 0 0
sp_kill_qty_storno 604668 604668 0 0.00 0 57940 80 0 0
sp_lock_selected_doc 20934 20934 0 0.00 0 6086 3 0 0
sp_make_cost_storno 28142 28142 0 0.00 0 14549 61 0 0
sp_multiply_rows_for_pdistr 28142 28142 0 0.00 0 10344 37 0 0
sp_multiply_rows_for_qdistr 20355 20355 0 0.00 0 17576 248 0 0
sp_pay_from_customer 7154 7154 0 0.00 4 16025 131 0 0
sp_pay_to_supplier 5905 5905 0 0.00 5 15698 134 0 0
sp_payment_common 13059 13059 0 0.00 1 10665 29 0 0
sp_qd_handle_on_cancel_clo 1776 1776 0 0.00 0 8053 291 0 0
sp_qd_handle_on_invoice_upd_sts 10706 10706 0 0.00 0 26087 357 0 0
sp_qd_handle_on_reserve_upd_sts 10110 10110 0 0.00 0 18470 61 0 0
sp_reserve_write_off 10110 10110 0 0.00 7 18524 202 0 0
sp_supplier_invoice 5545 5545 0 0.00 7 85017 3346 0 0
sp_supplier_order 5443 5443 0 0.00 5 31272 1383 0 0
srv_aggregate_perf_data 100 100 0 0.00 2884 258011 18251 0 0
srv_fill_mon_cache_memory 60 60 0 0.00 1619 150272 51158 0 0
srv_find_qd_qs_mism 1061000 1061000 0 0.00 0 11698 7 0 0
srv_make_invnt_saldo 484 484 0 0.00 62 102820 3323 0 0
srv_make_money_saldo 733 733 0 0.00 1 18808 505 0 0
srv_recalc_idx_stat 4 4 0 0.00 126150 499590 367947 0 0
t$perf-abend:sp_add_invoice_to_stock 120 120 0 0.00 0 0 0 0 0
t$perf-abend:sp_cancel_supplier_invoice 11 11 0 0.00 0 0 0 0 0
t$perf-norm:sp_add_invoice_to_stock 4989 4989 0 0.00 0 20221 180 0 0
t$perf-norm:sp_cancel_adding_invoice 438 438 0 0.00 0 2528 91 0 0
t$perf-norm:sp_cancel_client_order 1776 1776 0 0.00 0 6457 19 0 0
t$perf-norm:sp_cancel_customer_reserve 694 694 0 0.00 0 896 14 0 0
t$perf-norm:sp_cancel_pay_from_customer 674 674 0 0.00 0 2628 6 0 0
t$perf-norm:sp_cancel_pay_to_supplier 420 420 0 0.00 0 2133 13 0 0
t$perf-norm:sp_cancel_supplier_invoice 417 417 0 0.00 0 2043 21 0 0
t$perf-norm:sp_cancel_supplier_order 423 423 0 0.00 0 24336 1578 0 0
t$perf-norm:sp_cancel_write_off 1011 1011 0 0.00 0 2255 10 0 0
t$perf-norm:sp_client_order 20355 20355 0 0.00 0 8769 18 0 0
t$perf-norm:sp_customer_reserve 1026 1026 0 0.00 0 1796 6 0 0
t$perf-norm:sp_pay_from_customer 7154 7154 0 0.00 0 7061 8 0 0
t$perf-norm:sp_pay_to_supplier 5905 5905 0 0.00 0 6141 11 0 0
t$perf-norm:sp_reserve_write_off 10110 10110 0 0.00 0 8080 11 0 0
t$perf-norm:sp_supplier_invoice 5545 5545 0 0.00 0 13101 23 0 0
t$perf-norm:sp_supplier_order 5443 5443 0 0.00 0 5036 14 0 0
t$perf-norm:srv_aggregate_perf_data 100 100 0 0.00 0 552 6 0 0
t$perf-norm:srv_fill_mon_cache_memory 60 60 0 0.00 0 1457 34 0 0
t$perf-norm:srv_make_invnt_saldo 484 484 0 0.00 0 1447 10 0 0
t$perf-norm:srv_make_money_saldo 733 733 0 0.00 0 1853 12 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 40 11 0 0
v_add_invoice_to_stock 10186 9946 240 2.35 0 5498 17 0 240
v_all_customers 37144 37144 0 0.00 0 19 1 0 0
v_all_suppliers 30734 30734 0 0.00 0 367 1 0 0
v_all_wares 40708 40708 0 0.00 0 17 1 0 0
v_cancel_adding_invoice 876 876 0 0.00 0 2097 11 0 0
v_cancel_client_order 3552 3552 0 0.00 0 2911 6 0 0
v_cancel_customer_prepayment 1348 1348 0 0.00 0 4083 13 0 0
v_cancel_customer_reserve 1388 1388 0 0.00 0 3905 11 0 0
v_cancel_payment_to_supplier 840 840 0 0.00 0 2677 8 0 0
v_cancel_supplier_invoice 856 834 22 2.57 0 3778 20 0 22
v_cancel_supplier_order 846 846 0 0.00 0 357 5 0 0
v_cancel_write_off 2022 2022 0 0.00 0 2788 17 0 0
v_max_id_clo_ord 5442 5442 0 0.00 0 249 1 0 0
v_max_id_clo_res 1026 1026 0 0.00 0 4143 15 0 0
v_max_id_ord_sup 5542 5542 0 0.00 0 4045 5 0 0
v_max_non_paid_invoice 1522 1522 0 0.00 0 539 2 0 0
v_max_non_paid_realizn 3776 3776 0 0.00 0 3720 4 0 0
v_min_id_clo_ord 5442 5442 0 0.00 0 15464 18 0 0
v_min_id_clo_res 1026 1026 0 0.00 0 2928 7 0 0
v_min_id_ord_sup 5542 5542 0 0.00 0 10455 18 0 0
v_min_non_paid_invoice 5905 5905 0 0.00 0 8456 19 0 0
v_min_non_paid_realizn 7154 7154 0 0.00 0 9666 25 0 0
v_our_firm 10320 10320 0 0.00 0 22 1 0 0
v_reserve_write_off 20216 20216 0 0.00 0 8975 7 0 0
x_lock_depdocs_on_canc_invoice 11695 11695 0 0.00 0 15554 115 0 0
x_lock_depdocs_on_canc_sup_ord 423 423 0 0.00 0 14689 411 0 0
x_make_qstorno_1000_1200 5443 5443 0 0.00 2 28364 1293 0 0
x_make_qstorno_1200_2000 5545 5545 0 0.00 3 74081 3174 0 0
x_make_qstorno_2100_3300 456370 456370 0 0.00 0 113056 180 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 20-09-2021 10:39:40 to 20-09-2021 10:39:44.

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 8796669024 8807174144 3189120 0.038 1 1 2 0 3286736 61840 35776
08:08:14 39309 8192000000 9733503504 10032029696 801481872 8.911 100 84 168 0 829916752 7787232 20647648
08:10:10 10360 8192000000 9762904416 10067255296 832642752 9.226 100 94 188 0 870545056 15281984 22620320
08:11:26 21372 8192000000 9811894112 10132307968 866177568 9.562 100 76 152 0 918147216 34912816 17056832
08:12:57 7999 8192000000 9878335744 10219487232 925524448 10.151 100 76 152 1 993124256 51392080 16207728
08:14:08 6037 8192000000 9935488384 10281676800 968217328 10.569 100 77 154 1 1048015808 64146640 15651840
08:15:17 5109 8192000000 9964128608 10321055744 1005029568 10.927 100 83 163 2 1081996816 60997008 15970240
08:16:26 2629 8192000000 10021131824 10385612800 1030390112 11.172 100 85 170 1 1134848672 87858176 16600384
08:17:30 1112 8192000000 10038405696 10406547456 1061083072 11.467 100 96 190 1 1147267136 66012848 20171216
08:18:32 2505 8192000000 10006270720 10378334208 1064208336 11.497 100 80 162 0 1125773664 44086784 17478544
08:19:35 5640 8192000000 9992372576 10372927488 1070112672 11.553 100 57 117 1 1102428976 19718480 12597824
08:20:42 7608 8192000000 9977460432 10386591744 1075347712 11.603 100 32 63 1 1111749920 29588448 6813760
08:21:52 5935 8192000000 9999217712 10402557952 1083406288 11.680 100 26 51 1 1108132832 19497024 5229520
08:23:00 18617 8192000000 10014781104 10398285824 1088428816 11.728 100 67 134 2 1117039824 13885952 14725056
08:24:20 3386 8192000000 10001236080 10397888512 1090631456 11.749 100 41 82 1 1114807280 14753536 9422288
08:25:24 5897 8192000000 10012471344 10414026752 1089411776 11.737 100 38 75 1 1121258704 24180240 7666688
08:26:31 7489 8192000000 10023648304 10427891712 1090449760 11.747 100 38 77 1 1117736832 18983872 8303200
08:27:40 37480 8192000000 9986699712 10394566656 1049218032 11.353 97 38 71 2 1081144000 24078384 7847584
08:29:24 5169 8192000000 9950100176 10325385216 1012866512 11.003 97 57 116 2 1044235168 18108560 13260096
08:30:30 8396 8192000000 9919380528 10306236416 967819008 10.565 95 43 86 0 996300768 17911552 10570208
08:31:39 5920 8192000000 9955777152 10339430400 1020330656 11.075 99 38 75 0 1047352944 18932256 8090032
08:32:48 37421 8192000000 9936196240 10344153088 1014576896 11.020 98 36 72 0 1039255200 16932096 7746208
08:34:28 3179 8192000000 9970333392 10358083584 1054871280 11.407 100 44 85 3 1083525936 19299968 9354688
08:35:31 10591 8192000000 9972632320 10378887168 1062863632 11.484 100 36 68 2 1095012896 23930880 8218384
08:36:43 10353 8192000000 9990006896 10396704768 1070122224 11.553 100 32 64 1 1099715248 22445696 7147328
08:37:58 128784 8192000000 10033614368 10443722752 1089307232 11.736 100 40 80 1 1126731200 29041648 8382320
08:41:10 39982 8192000000 10082455024 10493243392 1109950848 11.932 100 39 78 3 1186965344 68669600 8344896
08:42:56 2485 8192000000 10130065424 10525798400 1127498128 12.098 100 73 145 2 1234486576 91306032 15682416
08:44:04 18028 8192000000 10141635632 10570514432 1135391760 12.172 100 46 92 2 1259292688 114171584 9729344
08:45:35 19150 8192000000 10160272432 10571632640 1148894960 12.299 100 49 98 2 1272214704 111488640 11831104
08:47:05 12457 8192000000 10243130016 10660900864 1166593296 12.465 100 48 95 0 1344595952 167407904 10594752
08:48:20 2306 8192000000 10228311664 10631938048 1171325952 12.509 100 65 127 2 1330789952 145608352 13855648
08:49:26 3402 8192000000 10266432528 10676752384 1173725440 12.532 100 71 142 0 1370711280 182046768 14939072
08:50:33 5552 8192000000 10241794720 10652938240 1155004192 12.356 99 79 153 1 1344052448 172571248 16477008
08:51:43 4352 8192000000 10320051024 10727079936 1178679264 12.578 100 74 148 0 1426023968 230725088 16619616
08:52:53 4329 8192000000 10359986096 10759159808 1171472336 12.511 100 90 178 0 1451228944 259749968 20006640
08:54:01 4162 8192000000 10418986432 10823049216 1165659792 12.456 100 80 160 0 1520562784 335516288 19386704
08:55:13 3835 8192000000 10471722256 10874380288 1159954864 12.403 100 80 160 0 1568817920 389830720 19032336
08:56:24 1618 8192000000 10431635344 10828857344 1137140720 12.189 99 85 165 1 1522933536 366065280 19727536
08:57:33 3490 8192000000 10458122848 10860105728 1153090272 12.338 100 81 162 2 1556028256 384288880 18649104
08:58:42 8441 8192000000 10446285040 10881900544 1144997728 12.263 100 43 85 1 1571079152 414671792 11409632
08:59:54 12241 8192000000 10410423296 10831851520 1144001392 12.253 100 37 73 0 1508920784 356090848 8828544
09:01:08 14014 8192000000 10412576640 10845777920 1132945104 12.149 100 25 50 1 1521167488 381280912 6941472
09:02:25 17440 8192000000 10368944544 10830991360 1126873392 12.092 100 25 50 1 1513059088 379490592 6695104
09:04:02 71244 8192000000 10044218592 10511052800 1084157744 11.687 97 15 30 0 1293747488 205859088 3730656
09:06:16 56654 8192000000 10103984336 10537709568 1114774112 11.978 99 30 61 0 1161793424 40518048 6501264
09:08:52 150271 8192000000 10071812816 10527596544 1082739376 11.674 97 35 68 1 1118714032 29595088 6379568
09:13:14 31448 8192000000 10095516256 10525147136 1105778592 11.892 98 49 96 1 1164294192 48627968 9887632
09:14:57 62782 8192000000 10098949824 10580058112 1129739232 12.119 100 18 36 0 1202443216 68806656 3897328
09:17:00 26971 8192000000 10152745792 10611585024 1141443152 12.229 100 19 37 0 1276055808 130262000 4350656
09:18:49 103154 8192000000 10139911712 10591916032 1127791696 12.101 99 20 40 0 1257607216 125323680 4491840
09:21:32 59649 8192000000 10142765392 10619375616 1132103456 12.141 99 20 39 2 1206660288 70379728 4177104
09:23:34 51842 8192000000 10080585472 10540199936 1134181856 12.161 99 15 30 0 1172148848 35196640 2770352
09:25:51 19921 8192000000 10139336096 10562277376 1135692688 12.175 99 62 123 1 1217575392 69381984 12500720
09:27:14 61808 8192000000 10100079504 10599116800 1150555536 12.315 100 15 30 0 1200777968 47186704 3035728
09:29:20 35587 8192000000 10069758016 10512871424 1112262224 11.954 97 19 38 0 1143369264 27019616 4087424
09:30:58 47581 8192000000 10107992704 10541658112 1102758432 11.864 96 31 62 1 1179328544 70353152 6216960
09:32:50 26136 8192000000 10090835712 10517155840 1104978880 11.885 97 23 46 0 1157011584 47562032 4470672
09:34:21 108978 8192000000 10042069600 10503413760 1121216320 12.038 99 19 37 1 1175776112 50756864 3802928
09:37:19 56975 8192000000 10074892320 10558001152 1134742720 12.166 100 23 46 1 1201202624 61605952 4853952
09:39:16 69175 8192000000 10064525632 10521456640 1118439360 12.012 98 16 32 0 1159716944 37516816 3760768
09:41:38 58782 8192000000 10106841696 10567602176 1103327440 11.869 98 18 35 2 1206072080 98520208 4224432
09:43:59 58509 8192000000 10094607904 10550738944 1100557056 11.843 98 14 28 0 1200487696 96488688 3441952
09:46:23 48388 8192000000 10067262304 10496806912 1093146800 11.773 97 22 44 1 1124682192 27406576 4128816
09:48:12 48072 8192000000 10073717152 10516615168 1088927728 11.732 97 34 68 2 1152012784 56218768 6866288
09:50:00 73440 8192000000 10053031680 10503946240 1069077232 11.543 95 20 40 0 1131799488 58987520 3734736
09:52:15 47900 8192000000 10108276528 10573877248 1113278016 11.963 99 38 76 0 1192657136 71915744 7463376
09:54:07 98960 8192000000 10050918352 10518343680 1059044416 11.447 95 12 23 0 1109489776 48284384 2160976
09:56:49 129356 8192000000 10044415680 10526523392 1096294768 11.802 98 15 30 0 1135313040 35954640 3063632
10:00:02 60475 8192000000 10055025136 10508652544 1102511264 11.861 98 12 23 0 1144643232 39552592 2579376
10:02:02 72462 8192000000 10124997360 10577113088 1129228096 12.114 100 22 44 0 1225744960 91508208 5008656
10:04:24 63098 8192000000 10127024704 10574262272 1126632640 12.090 99 17 34 0 1195752272 65317072 3802560
10:06:28 88474 8192000000 10099052752 10585665536 1090463552 11.747 96 19 38 0 1197157808 102406368 4287888
10:08:59 58330 8192000000 10111529120 10570489856 1130394560 12.125 99 28 56 1 1184459904 47912464 6152880
10:11:02 77726 8192000000 10082184432 10518245376 1117294512 12.001 98 46 91 1 1170869776 44289696 9285568
10:13:25 57924 8192000000 10098376672 10563899392 1132996256 12.150 99 24 47 1 1204795584 67013184 4786144
10:15:30 82861 8192000000 10088220672 10570870784 1135282624 12.171 100 24 48 0 1177828080 37963424 4582032
10:18:10 66698 8192000000 10092764416 10558976000 1136824176 12.186 99 9 18 0 1199950976 61371696 1755104
10:20:17 42914 8192000000 10077263152 10584080384 1145927936 12.271 100 7 14 1 1185094368 37939680 1226752
10:22:00 30768 8192000000 10116648832 10556592128 1115481840 11.984 96 26 51 1 1172500672 51426960 5591872
10:23:31 106374 8192000000 10150340960 10615865344 1077831776 11.627 94 18 36 0 1167870352 85874432 4164144
10:26:17 52627 8192000000 10193832064 10668052480 1132385808 12.144 100 27 54 1 1242937376 104603776 5947792
10:28:39 94827 8192000000 10116806416 10574938112 1119135872 12.019 98 10 20 0 1192646880 71529968 1981040
10:31:33 98436 8192000000 10060812480 10480377856 1088640416 11.730 96 27 54 0 1127948208 33956720 5351072
10:34:12 105707 8192000000 10101039536 10567020544 1099910272 11.837 97 41 82 0 1174117840 65848560 8359008

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 20-09-2021 10:39:46 to 20-09-2021 10:40:01.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 120 v_add_invoice_to_stock 335544517
except 11 v_cancel_supplier_invoice 335544517

Completed "Exceptions occured during test was in run". Done for 00:00:01, from 20-09-2021 10:40:02 to 20-09-2021 10:40:03.

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          238577
MON$OLDEST_ACTIVE               240344
MON$OLDEST_SNAPSHOT             240344
MON$NEXT_TRANSACTION            240344
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                       2067232
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             809
MON$NEXT_STATEMENT              53
MON$REPLICA_MODE                0


ISQL Version: LI-V4.0.1.2606 Firebird 4.0
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.1.2606 Firebird 4.0"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.1.2606 Firebird 4.0/tcp (mscs-test-161.budzdorov.ru)/P17:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.1.2606 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 ---
--- end of diff output ---

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

20.09.21 10:40:04. 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-20 10:40:07 No severe PSQL-related problems occured
20.09.21 10:40:08. End of report.