Generated by /opt/oltp-emul/oltp_isql_run_worker.sh, ISQL session No. 1 of total launched 100. 11.12.2020 10:27
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 e0e7b46250ba4f9795ceb55e0face73f
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 2020-09-25 13:42
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.00
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 16817492 kB
MemAvailable 31422544 kB
Buffers 0 kB
Cached 14725332 kB
SwapCached 3708 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.0
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 2020-12-11 10:26:54
DTS_END                         2020-12-11 10:26:54.9820
FB_GDSCODE                      -1
FB_MNEMONA                      
STACK                           SRV_RECALC_IDX_STAT(24:5) ==> SP_CHECK_TO_STOP_WORK(32:12) ==> SP_HALT_ON_ERROR(68:17)
IP                              ::1/58176
TRN_ID                          234346
ATT_ID                          638
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: 9277.73 24741 1113328
customer order: creation 163.57 420 19628
customer order: refuse 13.93 326 1672
order to supplier: creation 44.53 1289 5343
order to supplier: removal 3.33 132007 400
invoice (draft): creation 43.91 3207 5269
invoice (draft): removal 54.43 1043 6531
invoice accept: apply 39.85 17450 4782
invoice accept: cancel 49.16 870 5899
customer reserve: creation 3736.61 168 448393
customer reserve: removal 4835.27 71 580233
realization accept: apply 78.68 145 9441
realization accept: cancel 92.31 118 11077
payment from customer: creation 56.98 110 6838
payment from customer: removal 5.02 84 602
payment to supplier: creation 46.01 114 5521
payment to supplier: removal 3.30 82 396
service: total inventory turnovers 3.99 2746 479
service: total monetary turnovers 5.95 345 714
service: aggregate perf. data 0.88 13134 106
service: refresh index statistics 0.03 321094 4

Performance in TOTAL, chart


Completed "Performance in TOTAL". Done for 00:00:02, from 11-12-2020 10:27:46 to 11-12-2020 10:27:48.

Performance for every MINUTE

TEST_PHASE_NAME MINUTES_PASSED PERF_SCORE
TEST_TIME 1 3709
TEST_TIME 2 3411
TEST_TIME 3 3873
TEST_TIME 4 4210
TEST_TIME 5 4547
TEST_TIME 6 4883
TEST_TIME 7 5044
TEST_TIME 8 5238
TEST_TIME 9 5333
TEST_TIME 10 5404
TEST_TIME 11 5323
TEST_TIME 12 5480
TEST_TIME 13 5736
TEST_TIME 14 5936
TEST_TIME 15 6559
TEST_TIME 16 6919
TEST_TIME 17 7232
TEST_TIME 18 7385
TEST_TIME 19 7388
TEST_TIME 20 7990
TEST_TIME 21 7926
TEST_TIME 22 9928
TEST_TIME 23 10265
TEST_TIME 24 11955
TEST_TIME 25 12094
TEST_TIME 26 11917
TEST_TIME 27 11636
TEST_TIME 28 11326
TEST_TIME 29 11299
TEST_TIME 30 11040
TEST_TIME 31 10940
TEST_TIME 32 10757
TEST_TIME 33 10753
TEST_TIME 34 10721
TEST_TIME 35 10569
TEST_TIME 36 10417
TEST_TIME 37 10434
TEST_TIME 38 10240
TEST_TIME 39 10037
TEST_TIME 40 10248
TEST_TIME 41 10135
TEST_TIME 42 10154
TEST_TIME 43 10075
TEST_TIME 44 10002
TEST_TIME 45 10092
TEST_TIME 46 10094
TEST_TIME 47 10068
TEST_TIME 48 10163
TEST_TIME 49 10231
TEST_TIME 50 10197
TEST_TIME 51 10199
TEST_TIME 52 10170
TEST_TIME 53 9982
TEST_TIME 54 9962
TEST_TIME 55 9946
TEST_TIME 56 9836
TEST_TIME 57 9762
TEST_TIME 58 9717
TEST_TIME 59 9676
TEST_TIME 60 9744
TEST_TIME 61 9706
TEST_TIME 62 9658
TEST_TIME 63 9731
TEST_TIME 64 9779
TEST_TIME 65 9693
TEST_TIME 66 9740
TEST_TIME 67 9940
TEST_TIME 68 9879
TEST_TIME 69 9856
TEST_TIME 70 10005
TEST_TIME 71 9873
TEST_TIME 72 9786
TEST_TIME 73 9746
TEST_TIME 74 9709
TEST_TIME 75 9666
TEST_TIME 76 9586
TEST_TIME 77 9569
TEST_TIME 78 9623
TEST_TIME 79 9570
TEST_TIME 80 9580
TEST_TIME 81 9594
TEST_TIME 82 9602
TEST_TIME 83 9599
TEST_TIME 84 9627
TEST_TIME 85 9626
TEST_TIME 86 9631
TEST_TIME 87 9746
TEST_TIME 88 9719
TEST_TIME 89 9625
TEST_TIME 90 9579
TEST_TIME 91 9511
TEST_TIME 92 9474
TEST_TIME 93 9420
TEST_TIME 94 9436
TEST_TIME 95 9406
TEST_TIME 96 9353
TEST_TIME 97 9352
TEST_TIME 98 9366
TEST_TIME 99 9389
TEST_TIME 100 9437
TEST_TIME 101 9464
TEST_TIME 102 9494
TEST_TIME 103 9481
TEST_TIME 104 9506
TEST_TIME 105 9479
TEST_TIME 106 9420
TEST_TIME 107 9379
TEST_TIME 108 9354
TEST_TIME 109 9305
TEST_TIME 110 9269
TEST_TIME 111 9247
TEST_TIME 112 9221
TEST_TIME 113 9246
TEST_TIME 114 9246
TEST_TIME 115 9228
TEST_TIME 116 9211
TEST_TIME 117 9236
TEST_TIME 118 9208
TEST_TIME 119 9292
TEST_TIME 120 9281

Performance per minute, chart


Completed "Performance for every MINUTE". Done for 00:00:03, from 11-12-2020 10:27:50 to 11-12-2020 10:27:53.

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 1529356 1529356 0 0.00 0 33453 6 0 0
doc_list_biud 1529356 1529356 0 0.00 0 45611 34 0 0
sp_add_invoice_to_stock 4872 4782 90 1.84 40 172135 17450 0 90
sp_cancel_adding_invoice 5899 5899 0 0.00 5 81578 871 0 0
sp_cancel_client_order 1672 1672 0 0.00 4 20286 327 0 0
sp_cancel_customer_reserve 580233 580233 0 0.00 1 24463 72 0 0
sp_cancel_pay_from_customer 602 602 0 0.00 3 9793 85 0 0
sp_cancel_pay_to_supplier 396 396 0 0.00 3 11166 83 0 0
sp_cancel_supplier_invoice 6537 6531 6 0.09 1 17362 1043 0 6
sp_cancel_supplier_order 400 400 0 0.00 7 1243333 132007 0 0
sp_cancel_write_off 11077 11077 0 0.00 1 9995 118 0 0
sp_client_order 19628 19628 0 0.00 4 35124 420 0 0
sp_customer_reserve 448393 448393 0 0.00 0 36531 168 0 0
sp_fill_shopping_cart 30231 30231 0 0.00 1 22833 46 0 0
sp_fill_shopping_cart_clo_res 448386 448386 0 0.00 0 21850 19 0 0
sp_get_clo_for_invoice 4755 4755 0 0.00 0 23110 397 0 0
sp_kill_cost_storno 17974 17974 0 0.00 0 9619 57 0 0
sp_kill_qstorno_ret_qs2qd 1178474 1178474 0 0.00 0 24447 39 0 0
sp_kill_qty_storno 620007 620007 0 0.00 0 45611 83 0 0
sp_lock_selected_doc 19724 19724 0 0.00 0 10156 5 0 0
sp_make_cost_storno 26554 26554 0 0.00 0 14715 54 0 0
sp_multiply_rows_for_pdistr 26554 26554 0 0.00 0 8397 29 0 0
sp_multiply_rows_for_qdistr 19628 19628 0 0.00 0 17964 249 0 0
sp_pay_from_customer 6838 6838 0 0.00 4 12905 111 0 0
sp_pay_to_supplier 5521 5521 0 0.00 5 15443 114 0 0
sp_payment_common 12359 12359 0 0.00 1 12727 22 0 0
sp_qd_handle_on_cancel_clo 1672 1672 0 0.00 0 20278 308 0 0
sp_qd_handle_on_invoice_upd_sts 10653 10653 0 0.00 0 45611 371 0 0
sp_qd_handle_on_reserve_upd_sts 9441 9441 0 0.00 0 8231 40 0 0
sp_reserve_write_off 9441 9441 0 0.00 7 13752 145 0 0
sp_supplier_invoice 5269 5269 0 0.00 5 92387 3207 0 0
sp_supplier_order 5343 5343 0 0.00 5 87848 1289 0 0
srv_aggregate_perf_data 106 106 0 0.00 3020 108281 13134 0 0
srv_fill_mon_cache_memory 62 62 0 0.00 1784 159537 48759 0 0
srv_find_qd_qs_mism 1068010 1068010 0 0.00 0 33453 5 0 0
srv_make_invnt_saldo 479 479 0 0.00 60 95049 2747 0 0
srv_make_money_saldo 714 714 0 0.00 1 10822 345 0 0
srv_recalc_idx_stat 4 4 0 0.00 231988 472250 321094 0 0
t$perf-abend:sp_add_invoice_to_stock 90 90 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 4782 4782 0 0.00 0 12536 135 0 0
t$perf-norm:sp_cancel_adding_invoice 419 419 0 0.00 0 2490 62 0 0
t$perf-norm:sp_cancel_client_order 1672 1672 0 0.00 0 2703 9 0 0
t$perf-norm:sp_cancel_customer_reserve 689 689 0 0.00 0 1622 9 0 0
t$perf-norm:sp_cancel_pay_from_customer 602 602 0 0.00 0 379 2 0 0
t$perf-norm:sp_cancel_pay_to_supplier 396 396 0 0.00 0 171 2 0 0
t$perf-norm:sp_cancel_supplier_invoice 400 400 0 0.00 0 586 7 0 0
t$perf-norm:sp_cancel_supplier_order 400 400 0 0.00 0 19393 1435 0 0
t$perf-norm:sp_cancel_write_off 952 952 0 0.00 0 1323 3 0 0
t$perf-norm:sp_client_order 19628 19628 0 0.00 0 5280 12 0 0
t$perf-norm:sp_customer_reserve 915 915 0 0.00 0 1612 5 0 0
t$perf-norm:sp_pay_from_customer 6838 6838 0 0.00 0 4803 7 0 0
t$perf-norm:sp_pay_to_supplier 5521 5521 0 0.00 0 6120 6 0 0
t$perf-norm:sp_reserve_write_off 9441 9441 0 0.00 0 9880 9 0 0
t$perf-norm:sp_supplier_invoice 5269 5269 0 0.00 0 7178 12 0 0
t$perf-norm:sp_supplier_order 5343 5343 0 0.00 0 4257 13 0 0
t$perf-norm:srv_aggregate_perf_data 106 106 0 0.00 0 49 1 0 0
t$perf-norm:srv_fill_mon_cache_memory 62 62 0 0.00 0 24 1 0 0
t$perf-norm:srv_make_invnt_saldo 479 479 0 0.00 0 446 6 0 0
t$perf-norm:srv_make_money_saldo 714 714 0 0.00 0 792 6 0 0
t$perf-norm:srv_recalc_idx_stat 4 4 0 0.00 1 8 4 0 0
v_add_invoice_to_stock 9688 9508 180 1.85 0 17408 17 0 180
v_all_customers 36464 36464 0 0.00 0 17 1 0 0
v_all_suppliers 29634 29634 0 0.00 0 16 1 0 0
v_all_wares 39256 39256 0 0.00 0 35 1 0 0
v_cancel_adding_invoice 838 838 0 0.00 0 1107 7 0 0
v_cancel_client_order 3344 3344 0 0.00 0 664 3 0 0
v_cancel_customer_prepayment 1204 1204 0 0.00 0 3966 10 0 0
v_cancel_customer_reserve 1378 1378 0 0.00 0 304 3 0 0
v_cancel_payment_to_supplier 792 792 0 0.00 0 4139 9 0 0
v_cancel_supplier_invoice 812 800 12 1.47 0 7309 20 0 12
v_cancel_supplier_order 800 800 0 0.00 0 110 2 0 0
v_cancel_write_off 1904 1904 0 0.00 0 2590 13 0 0
v_max_id_clo_ord 5343 5343 0 0.00 0 595 1 0 0
v_max_id_clo_res 915 915 0 0.00 0 1944 14 0 0
v_max_id_ord_sup 5260 5260 0 0.00 0 9222 6 0 0
v_max_non_paid_invoice 1307 1307 0 0.00 0 7211 8 0 0
v_max_non_paid_realizn 3334 3334 0 0.00 0 7563 6 0 0
v_min_id_clo_ord 5343 5343 0 0.00 0 3001 4 0 0
v_min_id_clo_res 915 915 0 0.00 0 2034 7 0 0
v_min_id_ord_sup 5260 5260 0 0.00 0 5372 10 0 0
v_min_non_paid_invoice 5521 5521 0 0.00 0 5038 14 0 0
v_min_non_paid_realizn 6838 6838 0 0.00 0 6759 15 0 0
v_our_firm 9800 9800 0 0.00 0 13 1 0 0
v_reserve_write_off 18880 18880 0 0.00 0 4320 4 0 0
x_lock_depdocs_on_canc_invoice 12030 12030 0 0.00 0 21794 105 0 0
x_lock_depdocs_on_canc_sup_ord 400 400 0 0.00 0 7224 269 0 0
x_make_qstorno_1000_1200 5343 5343 0 0.00 2 87835 1226 0 0
x_make_qstorno_1200_2000 5269 5269 0 0.00 2 92339 3014 0 0
x_make_qstorno_2100_3300 448393 448393 0 0.00 0 36529 147 0 0

Completed "Performance in DETAILS". Done for 00:00:04, from 11-12-2020 10:27:54 to 11-12-2020 10:27:58.

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
07:56:58 4 8192000000 8796641776 8807174144 3168704 0.038 1 1 2 0 3266400 61840 35856
07:58:00 22559 8192000000 9714571376 10007453696 794002688 8.835 100 90 180 0 823660928 7655344 22002896
07:59:31 18563 8192000000 9752753296 10055049216 826989216 9.169 100 88 177 2 860827008 13037040 20800752
08:00:54 16621 8192000000 9803425184 10117169152 854342768 9.444 100 87 173 0 900074784 25676592 20055424
08:02:21 14943 8192000000 9869169088 10200879104 915720288 10.054 100 82 164 3 977768144 43825952 18221904
08:03:41 8561 8192000000 9918169856 10265804800 964018640 10.528 100 79 158 0 1033221392 52558240 16644512
08:04:54 4574 8192000000 9975770032 10328678400 1001662368 10.895 100 84 166 4 1088768832 70841552 16264912
08:05:59 1616 8192000000 10014744592 10385612800 1035345136 11.220 100 86 173 0 1130417056 77287872 17784048
08:07:02 1922 8192000000 10022324608 10391461888 1056940672 11.427 100 92 182 2 1135179520 59548784 18690064
08:08:05 20580 8192000000 10005753552 10385137664 1068957168 11.542 100 69 141 1 1117723392 34232320 14533904
08:09:33 5837 8192000000 10008973392 10398302208 1076351648 11.613 100 44 89 2 1114456832 28851920 9253264
08:10:42 5965 8192000000 9987728064 10387865600 1082681536 11.673 100 39 78 1 1109647568 17869504 9096528
08:11:50 4366 8192000000 10029940096 10423595008 1093577296 11.777 100 41 82 1 1132888464 31043504 8267664
08:12:55 31454 8192000000 10035090272 10448396288 1096113968 11.801 100 29 58 0 1133064544 31396672 5553904
08:14:29 6028 8192000000 10013659776 10414952448 1086364976 11.708 99 49 98 1 1123909376 26869632 10674768
08:15:36 4871 8192000000 10019837600 10413600768 1087903040 11.723 100 34 68 3 1117997248 22601728 7492480
08:16:41 19372 8192000000 9971919840 10374774784 1035406592 11.220 96 26 52 2 1060865360 19512816 5945952
08:18:03 17975 8192000000 9952343760 10356793344 983401056 10.717 92 19 38 3 1024372288 37058912 3912320
08:19:23 4994 8192000000 9951113216 10327519232 1011251664 10.987 98 41 80 1 1042979392 22837664 8890064
08:20:30 7305 8192000000 9928389392 10312646656 984522016 10.728 97 47 95 2 1018470144 23439616 10508512
08:21:40 70648 8192000000 9936208336 10345324544 1020134864 11.073 99 35 70 1 1041057536 12658848 8263824
08:23:51 7222 8192000000 9983339584 10375794688 1058553264 11.443 100 56 111 2 1090681632 19993808 12134560
08:24:59 7323 8192000000 10001328656 10402095104 1066053536 11.514 100 54 108 0 1089872320 11469808 12348976
08:26:08 11408 8192000000 9985605792 10383859712 1076645456 11.615 100 16 32 2 1116961360 36987872 3328032
08:27:22 72937 8192000000 10030119808 10436861952 1086488416 11.709 99 36 72 1 1130712592 36939680 7284496
08:29:58 5785 8192000000 10110726432 10511106048 1121921344 12.045 100 64 127 3 1214690896 79317056 13452496
08:31:07 4959 8192000000 10142791984 10548449280 1134289888 12.162 100 51 102 3 1247454096 102180816 10983392
08:32:16 7756 8192000000 10190661616 10603982848 1151077904 12.320 100 58 116 0 1303957584 139428976 13450704
08:33:28 6543 8192000000 10201719200 10610417664 1159619712 12.400 100 46 92 1 1313297296 142964912 10712672
08:34:38 14959 8192000000 10253321968 10672648192 1146830416 12.280 98 51 101 0 1347751424 189328096 11592912
08:35:57 12589 8192000000 10304316784 10713722880 1168334480 12.481 100 55 109 1 1404300144 223398544 12567120
08:37:14 9749 8192000000 10360412096 10769555456 1173946528 12.534 99 59 117 2 1448531760 260904928 13680304
08:38:31 3683 8192000000 10456612448 10864644096 1198827344 12.765 100 76 150 0 1555640080 339253760 17558976
08:39:41 4331 8192000000 10508863312 10927329280 1201955984 12.794 100 82 164 0 1621866704 399850160 20060560
08:40:50 1781 8192000000 10516873920 10925625344 1160362544 12.407 98 75 151 1 1611819904 433408528 18048832
08:41:58 12584 8192000000 10529357488 10949570560 1163460256 12.436 100 44 86 2 1633702912 459060800 11181856
08:43:15 2010 8192000000 10569982384 10980569088 1159048400 12.394 99 63 126 2 1657151552 482469008 15634144
08:44:20 10910 8192000000 10600037968 11037528064 1165613136 12.456 100 48 96 1 1700864224 522159264 13091824
08:45:33 6405 8192000000 10623368768 11054821376 1162235712 12.424 100 49 98 0 1727801776 551975376 13590688
08:46:46 21254 8192000000 10643210032 11125276672 1147707488 12.288 100 38 76 0 1726719280 568001472 11010320
08:48:10 16111 8192000000 10590847568 11131449344 1134243312 12.161 100 30 60 0 1718286304 575080880 8962112
08:49:38 43443 8192000000 10263857616 10818129920 1127363264 12.096 99 15 30 0 1451662192 320518800 3780128
08:52:23 92351 8192000000 10075166720 10513678336 1093554560 11.776 98 27 55 1 1151830384 52936320 5339504
08:55:10 113390 8192000000 10036778176 10472439808 1066366608 11.517 96 20 40 0 1122100096 51670192 4063296
08:58:09 60596 8192000000 10121329712 10547073024 1120586384 12.033 99 34 68 0 1205874208 77878048 7409776
09:00:12 30970 8192000000 10067588064 10507542528 1123047072 12.056 100 13 27 0 1181473152 55596672 2829408
09:01:43 40931 8192000000 10100908624 10552176640 1119894288 12.026 99 17 36 0 1182123936 58295072 3934576
09:03:25 37529 8192000000 10084376864 10542501888 1130427056 12.125 100 26 52 2 1201184352 64978560 5778736
09:06:00 69426 8192000000 10042165760 10490236928 1090058576 11.743 97 11 22 0 1181761776 89221552 2481648
09:08:10 56682 8192000000 10060260096 10493403136 1117457168 12.003 99 33 66 1 1152402464 28449568 6495728
09:10:07 76138 8192000000 10109510448 10602668032 1125280960 12.077 99 22 44 3 1220563504 90532800 4749744
09:12:28 96238 8192000000 10098613008 10560237568 1103432896 11.870 97 25 50 1 1172405744 63533808 5439040
09:15:16 48145 8192000000 10062461840 10492473344 1119884976 12.026 99 12 24 1 1155454720 33354416 2215328
09:17:04 67897 8192000000 10133382224 10594467840 1124199184 12.067 99 18 36 0 1205085696 77169728 3716784
09:19:38 84847 8192000000 10083261824 10550407168 1112221616 11.953 98 17 34 0 1157994432 42502672 3270144
09:22:03 67863 8192000000 10073262560 10561392640 1110090112 11.933 98 10 20 0 1136485104 24158784 2236208
09:24:11 51241 8192000000 10083700240 10516639744 1104014000 11.876 97 24 47 1 1154796816 45814800 4968016
09:26:06 31345 8192000000 10072798240 10541043712 1109291840 11.926 99 42 82 1 1154016400 36067520 8657040
09:27:45 43810 8192000000 10115602608 10540867584 1113663152 11.967 98 24 48 1 1156198928 37795392 4740384
09:29:35 43898 8192000000 10091474832 10557534208 1100358288 11.841 97 22 44 4 1161119168 56330480 4430400
09:31:19 46327 8192000000 10140971840 10575867904 1089609072 11.739 96 29 55 3 1191930944 96220160 6101712
09:33:06 50208 8192000000 10062572064 10567430144 1101480944 11.852 98 8 16 0 1174690432 71403488 1806000
09:34:57 24353 8192000000 10069945760 10506858496 1074275184 11.593 97 39 77 4 1137203584 55004320 7924080
09:36:23 79479 8192000000 10089815696 10592432128 1113962656 11.970 100 17 34 0 1184207824 66554368 3690800
09:39:05 58097 8192000000 10065313088 10533457920 1106189504 11.896 99 17 34 1 1164391024 54684640 3516880
09:41:09 64845 8192000000 10042558048 10519998464 1100138640 11.839 99 10 20 0 1141295312 39027088 2129584
09:43:19 74908 8192000000 10133471488 10588200960 1113969952 11.970 99 20 41 0 1189628464 70957920 4700592
09:45:50 74571 8192000000 10103436768 10563096576 1088584672 11.729 97 20 40 1 1159235296 66252720 4397904
09:48:11 23432 8192000000 10056787536 10486849536 1120500928 12.032 100 20 39 3 1160112752 35290000 4321824
09:49:34 42045 8192000000 10102696064 10537500672 1094944976 11.790 97 32 64 2 1155531648 54250128 6336544
09:51:17 49065 8192000000 10093492064 10568290304 1122184784 12.048 100 22 44 1 1189727520 63215648 4327088
09:53:36 57955 8192000000 10063975792 10515918848 1101614064 11.853 98 14 28 0 1147132448 42691296 2827088
09:55:40 63666 8192000000 10051151968 10519076864 1124102000 12.066 100 18 36 1 1150984992 22831664 4051328
09:57:44 87980 8192000000 10080410432 10554265600 1106006928 11.895 98 8 16 1 1156836560 49216752 1612880
10:00:12 94832 8192000000 10073974384 10518142976 1085861472 11.703 96 20 37 2 1161493600 71396416 4235712
10:02:55 22229 8192000000 10145395056 10584674304 1144605376 12.259 100 30 59 1 1234527344 83577312 6344656
10:04:20 72516 8192000000 10198865024 10652303360 1145717360 12.269 99 29 58 1 1286210256 133926416 6566480
10:06:49 159537 8192000000 10094178848 10627473408 1067816832 11.531 93 13 27 1 1128973600 58196384 2960384
10:10:29 53539 8192000000 10093013696 10589573120 1130222240 12.123 99 43 85 2 1206747088 67749984 8774864
10:12:26 109178 8192000000 10074198464 10539409408 1116011952 11.989 98 19 38 1 1162536016 42479072 4044992
10:15:19 38391 8192000000 10046005824 10522112000 1115143664 11.981 99 5 10 0 1138582944 22492256 947024
10:17:01 60959 8192000000 10084432400 10537701376 1122469840 12.050 99 19 38 1 1180706496 54487920 3748736
10:19:06 67684 8192000000 10073957568 10526208000 1101919808 11.856 97 18 36 1 1156776848 51069024 3788016
10:21:19 66070 8192000000 10096287600 10529976320 1086977072 11.714 96 27 54 0 1123977520 31238960 5761488
10:23:33 49735 8192000000 10086188064 10524925952 1104005376 11.876 98 18 36 2 1185227920 76717792 4504752
10:25:23 60246 8192000000 10098811472 10614820864 1125896896 12.083 99 19 38 1 1229117600 99307280 3913424

Memory consumption, total, chart

Metadata cache size, chart

Statements activity, chart


Completed "Memory consumption, metadata cache, attachments activity". Done for 00:00:15, from 11-12-2020 10:28:00 to 11-12-2020 10:28:15.

Exceptions occured during test was in run

FB_MNEMONA CNT UNIT FB_GDSCODE
except 90 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 11-12-2020 10:28:16 to 11-12-2020 10:28:18.

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          232890
MON$OLDEST_ACTIVE               234463
MON$OLDEST_SNAPSHOT             234463
MON$NEXT_TRANSACTION            234463
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                       2094848
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                        {8C105897-B735-40F5-7D8B-A1F76E952B78}
MON$FILE_ID                     02fd0000000000002e02000000000000
MON$NEXT_ATTACHMENT             798
MON$NEXT_STATEMENT              53


ISQL Version: LI-V4.0.0.2296 Firebird 4.0 Release Candidate 1
Server version:
Firebird/Linux/AMD/Intel/x64 (access method), version "LI-V4.0.0.2296 Firebird 4.0 Release Candidate 1"
Firebird/Linux/AMD/Intel/x64 (remote server), version "LI-V4.0.0.2296 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16:C"
Firebird/Linux/AMD/Intel/x64 (remote interface), version "LI-V4.0.0.2296 Firebird 4.0 Release Candidate 1/tcp (mscs-test-161.budzdorov.ru)/P16: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 ---
:83: 
:84: mscs-test-161.budzdorov.ru    Fri Dec 11 10:26:57 2020
:85:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:86: 
:87: 
:88: mscs-test-161.budzdorov.ru    Fri Dec 11 10:27:08 2020
:89:     INET/inet_error: send errno = 32, server host = localhost, address = ::1/3400
:90: 
--- end of diff output ---

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

11.12.20 10:28:19. 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
2020-12-11 10:28:21 No severe PSQL-related problems occured
11.12.20 10:28:22. End of report.